Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Greenhouses_Greenhouses.Examples.Greenhouse_2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001381/0.001381, allocations: 88.34 kB / 20.22 MB, free: 1.047 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001488/0.001488, allocations: 159 kB / 23.52 MB, free: 3.859 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.435/1.435, allocations: 177.1 MB / 203.9 MB, free: 8.117 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Greenhouses master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Greenhouses master/package.mo): time 0.07593/0.07593, allocations: 11.57 MB / 271.9 MB, free: 8.797 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package Greenhouses with version 1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Greenhouses master/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Greenhouses.Examples.Greenhouse_2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Greenhouses_Greenhouses.Examples.Greenhouse_2")
translateModel(Greenhouses.Examples.Greenhouse_2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Greenhouses_Greenhouses.Examples.Greenhouse_2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 3.655e-05/3.655e-05, allocations: 2.312 kB / 344.6 MB, free: 15.98 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Greenhouses.Examples.Greenhouse_2): time 0.1956/0.1956, allocations: 30.28 MB / 374.9 MB, free: 64.69 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.5305/0.7261, allocations: 149.2 MB / 0.5118 GB, free: 1.484 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008063/0.7342, allocations: 80.28 kB / 0.5119 GB, free: 1.484 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00517/0.7394, allocations: 1.032 MB / 0.5129 GB, free: 1.469 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01255/0.7519, allocations: 2.219 MB / 0.5151 GB, free: 1.441 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0145/0.7664, allocations: 3.852 MB / 0.5188 GB, free: 1.375 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02345/0.7899, allocations: 12.59 MB / 0.5311 GB, free: 15.61 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01617/0.806, allocations: 4.811 MB / 0.5358 GB, free: 15.16 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02218/0.8282, allocations: 6.065 MB / 0.5417 GB, free: 12.45 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.009317/0.8375, allocations: 3.674 MB / 0.5453 GB, free: 11.24 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.005082/0.8426, allocations: 0.9713 MB / 0.5463 GB, free: 11.24 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00983/0.8524, allocations: 2.488 MB / 0.5487 GB, free: 10.9 MB / 414.1 MB
Notification: Performance of combineBinaries: time 0.02336/0.8758, allocations: 13.92 MB / 0.5623 GB, free: 1.551 MB / 414.1 MB
Notification: Performance of replaceArrayConstructors: time 0.009615/0.8854, allocations: 7.475 MB / 0.5696 GB, free: 12.91 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004891/0.8903, allocations: 492.8 kB / 0.5701 GB, free: 12.91 MB / 430.1 MB
Notification: Performance of FrontEnd: time 0.003289/0.8936, allocations: 273.5 kB / 0.5703 GB, free: 12.86 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 7214 (1540)
 * Number of variables: 7214 (1463)
Notification: Performance of [SIM] Bindings: time 0.07087/0.9645, allocations: 35.43 MB / 0.6049 GB, free: 11.01 MB / 462.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.01669/0.9812, allocations: 6.702 MB / 0.6115 GB, free: 4.469 MB / 462.1 MB
Notification: Performance of [SIM] Early Inline: time 0.06112/1.042, allocations: 34.69 MB / 0.6454 GB, free: 1.941 MB / 494.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.01425/1.057, allocations: 3.645 MB / 0.6489 GB, free: 14.18 MB / 0.4981 GB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.07158/1.128, allocations: 35.07 MB / 0.6832 GB, free: 9.199 MB / 0.5294 GB
Notification: Performance of [SIM] Simplify 2: time 0.007463/1.136, allocations: 2.877 MB / 0.686 GB, free: 6.234 MB / 0.5294 GB
Notification: Performance of [SIM] Remove Stream: time 0.00382/1.139, allocations: 1.861 MB / 0.6878 GB, free: 4.312 MB / 0.5294 GB
Notification: Performance of [SIM] Detect States: time 0.2983/1.438, allocations: 7.202 MB / 0.6948 GB, free: 107.7 MB / 0.5294 GB
Notification: Performance of [SIM] Events: time 0.006064/1.444, allocations: 2.984 MB / 0.6977 GB, free: 107 MB / 0.5294 GB
Notification: Performance of [SIM] Partitioning: time 0.02551/1.469, allocations: 10.33 MB / 0.7078 GB, free: 105.8 MB / 0.5294 GB
Notification: Performance of [SIM] Causalize: time 29.5/30.97, allocations: 13.25 GB / 13.96 GB, free: 143 MB / 2.044 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.05172/31.02, allocations: 27.13 MB / 13.99 GB, free: 136.5 MB / 2.044 GB
Notification: Performance of [INI] Simplify: time 0.02177/31.04, allocations: 7.522 MB / 13.99 GB, free: 129.6 MB / 2.044 GB
Notification: Performance of [INI] Inline: time 0.05742/31.1, allocations: 38.88 MB / 14.03 GB, free: 91.09 MB / 2.044 GB
Notification: Performance of [INI] Partitioning: time 0.002777/31.1, allocations: 409.5 kB / 14.03 GB, free: 90.63 MB / 2.044 GB
Notification: Performance of [INI] Cleanup: time 0.0133/31.11, allocations: 5.979 MB / 14.04 GB, free: 84.71 MB / 2.044 GB
Notification: Performance of [INI] Causalize: time 70.73/101.8, allocations: 32.23 GB / 46.27 GB, free: 398.9 MB / 2.747 GB
Notification: Performance of [INI] Tearing: time 0.02114/101.9, allocations: 5.414 MB / 46.27 GB, free: 395 MB / 2.747 GB
Notification: Performance of [SIM] Initialization: time 3.757e-06/101.9, allocations: 0 / 46.27 GB, free: 395 MB / 2.747 GB
Notification: Performance of [SIM] Remove Dummies: time 0.0002086/101.9, allocations: 17.23 kB / 46.27 GB, free: 395 MB / 2.747 GB
Notification: Performance of [SIM] Tearing: time 0.0003642/101.9, allocations: 0.5341 MB / 46.27 GB, free: 394.6 MB / 2.747 GB
Notification: Performance of [SIM] Categorize: time 0.0001261/101.9, allocations: 37.48 kB / 46.27 GB, free: 394.6 MB / 2.747 GB
Notification: Performance of [SIM] Solve: time 0.0278/101.9, allocations: 13.56 MB / 46.29 GB, free: 386.9 MB / 2.747 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/Media/Air/ReferenceAir.mo:746:11-746:40:writable] Warning: Variable dp of the generated function $fDER0.Modelica.Media.Air.ReferenceAir.Air_Utilities.Inverses.dofpT will be initialized to 0.0 because it could not be proven that it is always assigned before it is used.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/Media/Air/ReferenceAir.mo:747:11-747:41:writable] Warning: Variable deld of the generated function $fDER0.Modelica.Media.Air.ReferenceAir.Air_Utilities.Inverses.dofpT will be initialized to 0.0 because it could not be proven that it is always assigned before it is used.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/Media/Air/ReferenceAir.mo:746:11-746:40:writable] Warning: Variable $fDER_dp of the generated function $fDER0.Modelica.Media.Air.ReferenceAir.Air_Utilities.Inverses.dofpT will be initialized to 0.0 because it could not be proven that it is always assigned before it is used.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/Media/Air/ReferenceAir.mo:747:11-747:41:writable] Warning: Variable $fDER_deld of the generated function $fDER0.Modelica.Media.Air.ReferenceAir.Air_Utilities.Inverses.dofpT will be initialized to 0.0 because it could not be proven that it is always assigned before it is used.
Notification: Performance of [SIM] Jacobian: time 0.03443/101.9, allocations: 19.47 MB / 46.3 GB, free: 373 MB / 2.747 GB
Notification: Performance of [SIM] Minimize Homotopy System: time 4.599e-06/101.9, allocations: 4 kB / 46.3 GB, free: 373 MB / 2.747 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 9
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 4
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 138 (32) {pipe_low.flow1DimInc.Cells.h, TYM.g_Tcan24, E_th_tot_kWhm2, E_el_tot_kWhm2, MV_CanAir.CO2_ppm, CO2_top.CO2_ppm, Q_cd_Soil.Layer_c.T, Q_cd_Soil.Layer_s.T, thScreen.T, PID_Mdot.I, TYM.W_Fruit_1_Pot, TYM.C_Buf, TYM.C_Fruit, TYM.N_Fruit, TYM.C_Leaf, TYM.C_Stem, DM_Har, TYM.T_canSumC, PID_CO2.I, SC.PID_crack.I, SC.PID_crack_T.I, U_vents.PID.I, U_vents.PIDT.I, U_vents.PIDT_noH.I, error, cover.T, MV_CanAir.VP_air, air.T, MV_CanAir.T_can, floor.T, air_Top.air.VP, air_Top.T}
 * Number of discrete states: .................... 13 (13) {SP_new.nextTimeEvent, SP_new.nextTimeEventScaled, SC.SC_CCD_value.entryTime, SC.SC_OCD_value.entryTime, SC.SC_OWD_value.entryTime, SC.timer2.entryTime, SC.timer1.entryTime, SC.timer.entryTime, SC.T4.t_start, SC_usable.nextTimeEvent, SC_usable.nextTimeEventScaled, TMY_and_control.nextTimeEvent, TMY_and_control.nextTimeEventScaled}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 286 (267) {SC.cl, SC.closed.active, SC.closed.localActive, SC.closed.inPort.occupied, SC.closed.inPort.set, SC.closed.outPort.available, SC.closed.outPort.reset, SC.closed.newActive, SC.stateGraphRoot.activeSteps, SC.T2.localCondition, SC.T2.enableFire, SC.T2.fire, SC.T2.inPort.available, SC.T2.outPort.occupied, SC.T3.localCondition, SC.T3.enableFire, SC.T3.fire, SC.T3.inPort.available, SC.T3.outPort.occupied, SC.T4.localCondition, SC.T4.enableFire, SC.T4.fire, SC.T4.inPort.available, SC.T4.outPort.occupied, SC.opening_ColdDay.localActive, SC.opening_ColdDay.inPort.occupied, SC.opening_ColdDay.inPort.set, SC.opening_ColdDay.outPort.available, SC.opening_ColdDay.outPort.reset, SC.opening_ColdDay.newActive, SC.T6.localCondition, SC.T6.enableFire, SC.T6.fire, SC.T6.inPort.available, SC.T6.outPort.occupied, SC.open.active, SC.open.localActive, SC.open.inPort.occupied, SC.open.inPort.set, SC.open.outPort.available, SC.open.outPort.reset, SC.open.newActive, SC.opening_WarmDay.localActive, SC.opening_WarmDay.inPort.occupied, SC.opening_WarmDay.inPort.set, SC.opening_WarmDay.outPort.available, SC.opening_WarmDay.outPort.reset, SC.opening_WarmDay.newActive, SC.T7.localCondition, SC.T7.enableFire, SC.T7.fire, SC.T7.inPort.available, SC.T7.outPort.occupied, SC.closing_ColdDay.localActive, SC.closing_ColdDay.inPort.occupied, SC.closing_ColdDay.inPort.set, SC.closing_ColdDay.outPort.available, SC.closing_ColdDay.outPort.reset, SC.closing_ColdDay.newActive, SC.T1.localCondition, SC.T1.enableFire, SC.T1.fire, SC.T1.inPort.available, SC.T1.outPort.occupied, $FUN_94, $FUN_95, $FUN_96, $FUN_97, $FUN_98, $FUN_99, $FUN_100, $FUN_101, $FUN_102, $FUN_103, $SEV_180, $SEV_179, $SEV_178, $SEV_177, $SEV_176, $SEV_175, $SEV_174, $SEV_173, $SEV_172, $SEV_171, $SEV_170, $SEV_169, $SEV_168, $SEV_167, $SEV_166, $SEV_165, $SEV_164, $SEV_163, $SEV_162, $SEV_161, $SEV_160, $SEV_159, $SEV_158, $SEV_157, $SEV_156, $SEV_155, $SEV_154, $SEV_153, $SEV_152, $SEV_151, $SEV_150, $SEV_149, $SEV_148, $SEV_147, $SEV_146, $SEV_145, $SEV_144, $SEV_143, $SEV_142, $SEV_141, $SEV_140, $SEV_139, $SEV_138, $SEV_137, $SEV_136, $SEV_135, $SEV_134, $SEV_133, $SEV_132, $SEV_131, $SEV_130, $SEV_129, $SEV_128, $SEV_127, $SEV_126, $SEV_125, $SEV_124, $SEV_123, $SEV_122, $SEV_121, $SEV_120, $SEV_119, $SEV_118, $SEV_117, $SEV_116, $SEV_115, $SEV_114, $SEV_113, $SEV_112, $SEV_111, $SEV_110, $SEV_109, $SEV_108, $SEV_107, $SEV_106, $SEV_105, $SEV_104, $SEV_103, $SEV_102, $SEV_101, $SEV_100, $SEV_99, $SEV_98, $SEV_97, $SEV_96, $SEV_95, $SEV_94, $SEV_93, $SEV_92, $SEV_91, $SEV_90, $SEV_89, $SEV_88, $SEV_87, $SEV_86, $SEV_85, $SEV_84, $SEV_83, $SEV_82, $SEV_81, $SEV_80, $SEV_79, $SEV_78, $SEV_77, $SEV_76, $SEV_75, $SEV_74, $SEV_73, $SEV_72, $SEV_71, $SEV_70, $SEV_69, $SEV_68, $SEV_67, $SEV_66, $SEV_65, $SEV_64, $SEV_63, $SEV_62, $SEV_61, $SEV_60, $SEV_59, $SEV_58, $SEV_57, $SEV_56, $SEV_55, $SEV_54, $SEV_53, $SEV_52, $SEV_51, $SEV_50, $SEV_49, $SEV_48, $SEV_47, $SEV_46, $SEV_45, $SEV_44, $SEV_43, $SEV_42, $SEV_41, $SEV_40, $SEV_39, $SEV_38, $SEV_37, $SEV_36, $SEV_35, $SEV_34, $SEV_33, $SEV_32, $SEV_31, $SEV_30, $SEV_29, $SEV_28, $SEV_27, $SEV_26, $SEV_25, $SEV_24, $SEV_23, $SEV_22, $SEV_21, $SEV_20, $SEV_19, $SEV_18, $SEV_17, $SEV_16, $SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_11, $TEV_10, $TEV_9, $TEV_8, $TEV_7, $TEV_6, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $TEV_1, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 923 (scalar:911, array:12, record:0)
 * Number of multi strong components: ............ 15 (algorithm:2, when:13, if:0, tuple:5)
 * Number of for-loop strong components: ......... 79 (resizable: 72, generic: 3, entwined:4)
 * Number of algebraic-loop strong components: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 907 (scalar:891, array:16, record:0)
 * Number of multi strong components: ............ 8 (algorithm:8, when:0, if:0, tuple:4)
 * Number of for-loop strong components: ......... 85 (resizable: 75, generic: 6, entwined:4)
 * Number of algebraic-loop strong components: ... 4 (linear: 2, nonlinear:2)
Notification: Performance of backend: time 0.001267/101.9, allocations: 376.9 kB / 46.3 GB, free: 372.6 MB / 2.747 GB
Notification: Performance of SimCode: time 0.2161/102.1, allocations: 166.8 MB / 46.47 GB, free: 221.2 MB / 2.747 GB
Notification: Performance of Templates: time 0.2815/102.4, allocations: 141.9 MB / 46.61 GB, free: 79.53 MB / 2.747 GB
"
[Timeout remaining time 558]
make -j1 -f Greenhouses_Greenhouses.Examples.Greenhouse_2.makefile [Timeout 660]
(rm -f Greenhouses_Greenhouses.Examples.Greenhouse_2.pipe ; mkfifo Greenhouses_Greenhouses.Examples.Greenhouse_2.pipe ; head -c 1048576 < Greenhouses_Greenhouses.Examples.Greenhouse_2.pipe >> ../files/Greenhouses_Greenhouses.Examples.Greenhouse_2.sim & ./Greenhouses_Greenhouses.Examples.Greenhouse_2  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Greenhouses_Greenhouses.Examples.Greenhouse_2.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 117.21460082754493]
Failed to read output from testmodel.py, exit status != 0:
102.44059531763196 102.452136824 101.55551427
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f157b1c11b0>