Console Output

Started by upstream project "autoplot-run-tests" build number 7898
originally caused by:
 Started by upstream project "autoplot-all" build number 7870
 originally caused by:
  Started by timer
 Started by upstream project "autoplot-all" build number 7870
Running as SYSTEM
Building in workspace /var/local/jenkins/workspace/autoplot-test012
[autoplot-test012] $ /bin/sh -xe /usr/local/apache-tomcat-8.0.27/temp/jenkins5579356182020484796.sh
+ JAR=../autoplot-jar-all/autoplot/Autoplot/dist/AutoplotAll.jar
+ cp ../autoplot-jar-all/autoplot/Autoplot/temp-src/test/endtoend/Test012.java .
+ /usr/local/jdk1.8//bin/java -Djava.awt.headless=true -cp ../autoplot-jar-all/autoplot/Autoplot/dist/AutoplotAll.jar test.endtoend.Test012
== 000 vap:file:///home/jbf/ct/hudson/data.backup/cdf/c4_cp_fgm_spin_20030102_v01.cdf?B_vec_xyz_gse__C4_CP_FGM_SPIN ==
Apr 29, 2024 12:15:35 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Apr 29, 2024 12:15:36 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.147 seconds (test012_000): vap:file:///home/jbf/ct/hudson/data.backup/cdf/c4_cp_fgm_spin_20030102_v01.cdf?B_vec_xyz_gse__C4_CP_FGM_SPIN
== 001 vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_efw_20080402_v01.cdf?tha_efw ==
Read in     0.440 seconds (test012_001): vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_efw_20080402_v01.cdf?tha_efw
== 002 vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?dens_e ==
Read in     0.008 seconds (test012_002): vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?dens_e
== 003 vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?Theta_l ==
Apr 29, 2024 12:15:40 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.002 seconds (test012_003): vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?Theta_l
== 004 vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?Ecounts[1] ==
Read in     0.014 seconds (test012_004): vap:file:///home/jbf/ct/hudson/data.backup/cdf/l1_h0_mpa_20020202_v02.cdf?Ecounts[1]
== 005 vap:file:///home/jbf/ct/hudson/data.backup/cdf/c2_waveform_wbd_200704170840_u01.cdf?WBD_Elec[1000000:1100000] ==
Read in     0.040 seconds (test012_005): vap:file:///home/jbf/ct/hudson/data.backup/cdf/c2_waveform_wbd_200704170840_u01.cdf?WBD_Elec[1000000:1100000]
== 006 vap:file:///home/jbf/ct/hudson/data.backup/cdf/c2_waveform_wbd_200704170840_u01.cdf?DATA_QUALITY[::1090] ==
Read in     0.296 seconds (test012_006): vap:file:///home/jbf/ct/hudson/data.backup/cdf/c2_waveform_wbd_200704170840_u01.cdf?DATA_QUALITY[::1090]
== 007 vap:file:///home/jbf/ct/hudson/data.backup/cdf/i8_15sec_mag_19731030_v02.cdf?F1_Average_B_15s ==
Read in     0.003 seconds (test012_007): vap:file:///home/jbf/ct/hudson/data.backup/cdf/i8_15sec_mag_19731030_v02.cdf?F1_Average_B_15s
== 008 vap:file:///home/jbf/ct/hudson/data.backup/cdf/i8_15sec_mag_19731030_v02.cdf?B_Vector_GSM ==
Apr 29, 2024 12:15:43 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.004 seconds (test012_008): vap:file:///home/jbf/ct/hudson/data.backup/cdf/i8_15sec_mag_19731030_v02.cdf?B_Vector_GSM
== 009 vap:file:///home/jbf/ct/hudson/data.backup/cdf/wi_h0_mfi_19941123_v04.cdf?P1GSM ==
Apr 29, 2024 12:15:44 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.007 seconds (test012_009): vap:file:///home/jbf/ct/hudson/data.backup/cdf/wi_h0_mfi_19941123_v04.cdf?P1GSM
== 010 vap:file:///home/jbf/ct/hudson/data.backup/cdf/wi_h0_mfi_19941123_v04.cdf?ORTH_I ==
Apr 29, 2024 12:15:44 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.001 seconds (test012_010): vap:file:///home/jbf/ct/hudson/data.backup/cdf/wi_h0_mfi_19941123_v04.cdf?ORTH_I
== 011 vap:file:///home/jbf/ct/hudson/data.backup/cdf/po_h0_hyd_20010117_v01.cdf?ION_DIFFERENTIAL_ENERGY_FLUX ==
Apr 29, 2024 12:15:45 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.017 seconds (test012_011): vap:file:///home/jbf/ct/hudson/data.backup/cdf/po_h0_hyd_20010117_v01.cdf?ION_DIFFERENTIAL_ENERGY_FLUX
== 012 vap:file:///home/jbf/ct/hudson/data.backup/cdf/po_h0_tim_19960409_v03.cdf?Flux_H ==
Read in     0.165 seconds (test012_012): vap:file:///home/jbf/ct/hudson/data.backup/cdf/po_h0_tim_19960409_v03.cdf?Flux_H
== 013 vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_fgm_20100101_v01.cdf?tha_fgh[0:10000] ==
Apr 29, 2024 12:15:47 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.017 seconds (test012_013): vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_fgm_20100101_v01.cdf?tha_fgh[0:10000]
== 014 vap:file:///home/jbf/ct/hudson/data.backup/cdf/twins/twins1_l1_imager_2009011102_v01.cdf?smooth_image&interpMeta=no ==
Apr 29, 2024 12:15:49 AM org.das2.qds.DataSetUtil getPreviousInterval
WARNING: step limit in nextprev https://sourceforge.net/p/autoplot/bugs/1209/
Read in     0.119 seconds (test012_014): vap:file:///home/jbf/ct/hudson/data.backup/cdf/twins/twins1_l1_imager_2009011102_v01.cdf?smooth_image&interpMeta=no
== 015 vap:file:///home/jbf/ct/hudson/data.backup/cdf/fa_k0_ees_19980111_v01.cdf?el_0 ==
Read in     0.031 seconds (test012_015): vap:file:///home/jbf/ct/hudson/data.backup/cdf/fa_k0_ees_19980111_v01.cdf?el_0
== 016 vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_ffp_32_20090101_v01.cdf?tha_ffp_32 ==
Read in     0.080 seconds (test012_016): vap:file:///home/jbf/ct/hudson/data.backup/cdf/tha_l1_ffp_32_20090101_v01.cdf?tha_ffp_32
== 017 file:///home/jbf/ct/hudson/data.backup/cdf/virbo/poes_n17_20041228.cdf?P1_90[0:300] ==
Apr 29, 2024 12:15:52 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.005 seconds (test012_017): file:///home/jbf/ct/hudson/data.backup/cdf/virbo/poes_n17_20041228.cdf?P1_90[0:300]
== 018 file:///home/jbf/ct/hudson/data.backup/cdf/cn_k0_asi_19991230_v01.cdf?Image ==
Apr 29, 2024 12:15:52 AM org.autoplot.cdf.CdfDataSource readAttributes
SEVERE: CDF does not hava a variable named unit_img
gov.nasa.gsfc.spdf.cdfj.CDFException$ReaderError: CDF does not hava a variable named unit_img
	at gov.nasa.gsfc.spdf.cdfj.MetaData.getType(MetaData.java:158)
	at org.autoplot.cdf.CdfUtil.loadVariable(CdfUtil.java:578)
	at org.autoplot.cdf.CdfUtil.loadVariable(CdfUtil.java:525)
	at org.autoplot.cdf.CdfDataSource.readAttributes(CdfDataSource.java:923)
	at org.autoplot.cdf.CdfDataSource.getMetadata(CdfDataSource.java:1662)
	at org.autoplot.cdf.CdfDataSource.getDataSet(CdfDataSource.java:374)
	at org.autoplot.jythonsupport.Util.getDataSet(Util.java:277)
	at org.autoplot.jythonsupport.Util.getDataSet(Util.java:554)
	at test.endtoend.Test012.doTest(Test012.java:29)
	at test.endtoend.Test012.main(Test012.java:120)

Apr 29, 2024 12:15:52 AM org.autoplot.cdf.CdfDataSource readAttributes
SEVERE: CDF does not hava a variable named unit_img
gov.nasa.gsfc.spdf.cdfj.CDFException$ReaderError: CDF does not hava a variable named unit_img
	at gov.nasa.gsfc.spdf.cdfj.MetaData.getType(MetaData.java:158)
	at org.autoplot.cdf.CdfUtil.loadVariable(CdfUtil.java:578)
	at org.autoplot.cdf.CdfUtil.loadVariable(CdfUtil.java:525)
	at org.autoplot.cdf.CdfDataSource.readAttributes(CdfDataSource.java:923)
	at org.autoplot.cdf.CdfDataSource.getDataSet(CdfDataSource.java:378)
	at org.autoplot.jythonsupport.Util.getDataSet(Util.java:277)
	at org.autoplot.jythonsupport.Util.getDataSet(Util.java:554)
	at test.endtoend.Test012.doTest(Test012.java:29)
	at test.endtoend.Test012.main(Test012.java:120)

Apr 29, 2024 12:15:52 AM org.autoplot.cdf.CdfDataSource loadVariableAndDependents
INFO: units variable does not exist: unit_img
Apr 29, 2024 12:15:53 AM org.das2.qds.DataSetUtil getPreviousInterval
WARNING: step limit in nextprev https://sourceforge.net/p/autoplot/bugs/1209/
Read in     0.008 seconds (test012_018): file:///home/jbf/ct/hudson/data.backup/cdf/cn_k0_asi_19991230_v01.cdf?Image
== 019 file:///home/jbf/ct/hudson/data.backup/cdf/pw/rbsp/rbsp-a_magnetometer_emfisis-L1_20120904_v1.2.2.cdf?Mag_UVW[0:100000] ==
Apr 29, 2024 12:15:54 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.110 seconds (test012_019): file:///home/jbf/ct/hudson/data.backup/cdf/pw/rbsp/rbsp-a_magnetometer_emfisis-L1_20120904_v1.2.2.cdf?Mag_UVW[0:100000]
== 020 file:///home/jbf/ct/hudson/data.backup/cdf/pw/rbsp/rbsp-a_magnetometer_emfisis-L1_20120904_v1.2.2.cdf?Mag_UVW ==
Read in     1.400 seconds (test012_020): file:///home/jbf/ct/hudson/data.backup/cdf/pw/rbsp/rbsp-a_magnetometer_emfisis-L1_20120904_v1.2.2.cdf?Mag_UVW
== 021 file:///home/jbf/ct/hudson/data.backup/cdf/ge_k0_lep_20111016_v01.cdf?V0 ==
Apr 29, 2024 12:16:12 AM org.autoplot.cdf.CdfDataSource loadVariableAndDependents
INFO: units are not all the same, unable to use: label_units
Apr 29, 2024 12:16:12 AM org.autoplot.cdf.CdfDataSource loadVariableAndDependents
INFO: units are not all the same, unable to use: label_units
Apr 29, 2024 12:16:12 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.005 seconds (test012_021): file:///home/jbf/ct/hudson/data.backup/cdf/ge_k0_lep_20111016_v01.cdf?V0
== 022 file:///home/jbf/ct/hudson/data.backup/cdf/rbspb_pre_ect-mageisHIGH-sp-L1_20130709_v1.0.0.cdf?Histogram_prot ==
Read in     0.705 seconds (test012_022): file:///home/jbf/ct/hudson/data.backup/cdf/rbspb_pre_ect-mageisHIGH-sp-L1_20130709_v1.0.0.cdf?Histogram_prot
== 023 file:///home/jbf/ct/hudson/data.backup/cdf/lanl/rbspb_ect-hope-sci-L2SA_20121219_v3.0.0.cdf?FPSA[0:100:5] ==
Read in     0.012 seconds (test012_023): file:///home/jbf/ct/hudson/data.backup/cdf/lanl/rbspb_ect-hope-sci-L2SA_20121219_v3.0.0.cdf?FPSA[0:100:5]
== 024 file:///home/jbf/ct/hudson/data.backup/cdf/rbsp/rbsp-a_HFR-spectra-burst_emfisis-L2_20140121_v1.3.6.cdf?HFR_Spectra[0:100] ==
Apr 29, 2024 12:16:17 AM org.das2.graph.DasCanvas waitUntilIdle
INFO: strange bug where update event didn't clear dirty flags, reposting.
Read in     0.005 seconds (test012_024): file:///home/jbf/ct/hudson/data.backup/cdf/rbsp/rbsp-a_HFR-spectra-burst_emfisis-L2_20140121_v1.3.6.cdf?HFR_Spectra[0:100]
== 025 http://cdaweb.gsfc.nasa.gov/istp_public/data/themis/thc/l2/esa/2015/thc_l2_esa_20151214_v01.cdf?thc_peif_velocity_dslQ&slice1=0 ==
Apr 29, 2024 12:16:17 AM org.das2.util.filesystem.HttpUtil checkRedirect
INFO: URL http://cdaweb.gsfc.nasa.gov/istp_public/data/themis/thc/l2/esa/2015/thc_l2_esa_20151214_v01.cdf permanently moved to https://cdaweb.gsfc.nasa.gov/istp_public/data/themis/thc/l2/esa/2015/thc_l2_esa_20151214_v01.cdf
Read in     0.675 seconds (test012_025): http://cdaweb.gsfc.nasa.gov/istp_public/data/themis/thc/l2/esa/2015/thc_l2_esa_20151214_v01.cdf?thc_peif_velocity_dslQ&slice1=0
[autoplot-test012] $ /bin/sh -xe /usr/local/apache-tomcat-8.0.27/temp/jenkins9079998887775218960.sh
+ rm -f diffs.*
+ /home/jbf/ct/hudson/bin/compareImages.sh
== test012_000.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_000.png /home/jbf/ct/hudson/artifacts/test012_000.png diffs.test012_000.png

diff=5.48495 (8.3695e-05)
== test012_001.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_001.png /home/jbf/ct/hudson/artifacts/test012_001.png diffs.test012_001.png

diff=0 (0)
== test012_002.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_002.png /home/jbf/ct/hudson/artifacts/test012_002.png diffs.test012_002.png

diff=0 (0)
== test012_003.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_003.png /home/jbf/ct/hudson/artifacts/test012_003.png diffs.test012_003.png

diff=0 (0)
== test012_004.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_004.png /home/jbf/ct/hudson/artifacts/test012_004.png diffs.test012_004.png

diff=0 (0)
== test012_005.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_005.png /home/jbf/ct/hudson/artifacts/test012_005.png diffs.test012_005.png

diff=0 (0)
== test012_006.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_006.png /home/jbf/ct/hudson/artifacts/test012_006.png diffs.test012_006.png

diff=0 (0)
== test012_007.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_007.png /home/jbf/ct/hudson/artifacts/test012_007.png diffs.test012_007.png

diff=0 (0)
== test012_008.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_008.png /home/jbf/ct/hudson/artifacts/test012_008.png diffs.test012_008.png

diff=5.48495 (8.3695e-05)
== test012_009.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_009.png /home/jbf/ct/hudson/artifacts/test012_009.png diffs.test012_009.png

diff=3.14492 (4.79884e-05)
== test012_010.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_010.png /home/jbf/ct/hudson/artifacts/test012_010.png diffs.test012_010.png

diff=3.14492 (4.79884e-05)
== test012_011.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_011.png /home/jbf/ct/hudson/artifacts/test012_011.png diffs.test012_011.png

diff=0 (0)
== test012_012.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_012.png /home/jbf/ct/hudson/artifacts/test012_012.png diffs.test012_012.png

diff=172.505 (0.00263226)
 try alternate version in /home/jbf/ct/hudson/artifacts_alt/test012_012.png ***
compare -metric MAE test012_012.png /home/jbf/ct/hudson/artifacts_alt/test012_012.png diffs.alt.test012_012.png 2>&1 | cut -d' ' -f1 
m=0
THRESHOLD=20.0
--> Alternate Version Matches
    rm diffs.alt.test012_012.png
    rm diffs.test012_012.png
== test012_013.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_013.png /home/jbf/ct/hudson/artifacts/test012_013.png diffs.test012_013.png

diff=5.21006 (7.95004e-05)
== test012_014.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_014.png /home/jbf/ct/hudson/artifacts/test012_014.png diffs.test012_014.png

diff=0 (0)
== test012_015.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_015.png /home/jbf/ct/hudson/artifacts/test012_015.png diffs.test012_015.png

diff=0 (0)
== test012_016.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_016.png /home/jbf/ct/hudson/artifacts/test012_016.png diffs.test012_016.png

diff=0 (0)
== test012_017.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_017.png /home/jbf/ct/hudson/artifacts/test012_017.png diffs.test012_017.png

diff=0 (0)
== test012_018.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_018.png /home/jbf/ct/hudson/artifacts/test012_018.png diffs.test012_018.png

diff=0 (0)
== test012_019.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_019.png /home/jbf/ct/hudson/artifacts/test012_019.png diffs.test012_019.png

diff=0 (0)
== test012_020.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_020.png /home/jbf/ct/hudson/artifacts/test012_020.png diffs.test012_020.png

diff=0 (0)
== test012_021.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_021.png /home/jbf/ct/hudson/artifacts/test012_021.png diffs.test012_021.png

diff=5.48495 (8.3695e-05)
== test012_022.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_022.png /home/jbf/ct/hudson/artifacts/test012_022.png diffs.test012_022.png

diff=0 (0)
== test012_023.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_023.png /home/jbf/ct/hudson/artifacts/test012_023.png diffs.test012_023.png

diff=0 (0)
== test012_024.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_024.png /home/jbf/ct/hudson/artifacts/test012_024.png diffs.test012_024.png

diff=1.16507 (1.77778e-05)
== test012_025.png  ==
compare -metric MAE -dissimilarity-threshold 1 test012_025.png /home/jbf/ct/hudson/artifacts/test012_025.png diffs.test012_025.png

diff=0 (0)
--> matches
Archiving artifacts
Finished: SUCCESS