Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Examples.VAVReheat.Validation.TraceSubstance.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.001672/0.001672, allocations: 100.4 kB / 19.76 MB, free: 1.906 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.001644/0.001644, allocations: 215.6 kB / 23.06 MB, free: 4.992 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.369/1.369, allocations: 230.6 MB / 256.9 MB, free: 7.734 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo): time 2.643/2.643, allocations: 476 MB / 0.7708 GB, free: 4.641 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 11.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/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(Buildings.Examples.VAVReheat.Validation.TraceSubstance,tolerance=1e-06,outputFormat="mat",numberOfIntervals=192,variableFilter="time|hvac.VOut1.V_flow|hvac.senSupFlo.V_flow|flo.cor.air.vol.C.1.|flo.sou.air.vol.C.1.|flo.eas.air.vol.C.1.|flo.nor.air.vol.C.1.|flo.wes.air.vol.C.1.",fileNamePrefix="Buildings_11_Buildings.Examples.VAVReheat.Validation.TraceSubstance")
translateModel(Buildings.Examples.VAVReheat.Validation.TraceSubstance,tolerance=1e-06,outputFormat="mat",numberOfIntervals=192,variableFilter="time|hvac.VOut1.V_flow|hvac.senSupFlo.V_flow|flo.cor.air.vol.C.1.|flo.sou.air.vol.C.1.|flo.eas.air.vol.C.1.|flo.nor.air.vol.C.1.|flo.wes.air.vol.C.1.",fileNamePrefix="Buildings_11_Buildings.Examples.VAVReheat.Validation.TraceSubstance") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.715e-05/1.715e-05, allocations: 2.281 kB / 1.118 GB, free: 8.883 MB / 0.8419 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.VAVReheat.Validation.TraceSubstance): time 4.351/4.351, allocations: 1.898 GB / 3.016 GB, free: 95.86 MB / 1.889 GB
Notification: Performance of NFInst.instExpressions: time 0.315/4.666, allocations: 227.8 MB / 3.238 GB, free: 81.35 MB / 1.889 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.1085/4.774, allocations: 2.963 MB / 3.241 GB, free: 81.35 MB / 1.889 GB
Notification: Performance of NFTyping.typeComponents: time 0.08476/4.859, allocations: 30.57 MB / 3.271 GB, free: 74.69 MB / 1.889 GB
Notification: Performance of NFTyping.typeBindings: time 0.1524/5.011, allocations: 54.56 MB / 3.324 GB, free: 51.64 MB / 1.889 GB
Notification: Performance of NFTyping.typeClassSections: time 0.07905/5.09, allocations: 37.67 MB / 3.361 GB, free: 24.41 MB / 1.889 GB
Notification: Performance of NFFlatten.flatten: time 2.407/7.498, allocations: 330.3 MB / 3.683 GB, free: 114.3 MB / 2.108 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1267/7.624, allocations: 67.61 MB / 3.749 GB, free: 111.2 MB / 2.108 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1687/7.793, allocations: 110.6 MB / 3.858 GB, free: 106.8 MB / 2.108 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1429/7.936, allocations: 92.74 MB / 3.948 GB, free: 98.22 MB / 2.108 GB
Notification: Performance of NFPackage.collectConstants: time 0.03624/7.972, allocations: 9.726 MB / 3.958 GB, free: 98.22 MB / 2.108 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1078/8.08, allocations: 35.47 MB / 3.992 GB, free: 97.83 MB / 2.108 GB
Notification: Performance of combineBinaries: time 0.2705/8.351, allocations: 235.9 MB / 4.223 GB, free: 7.754 MB / 2.139 GB
Notification: Performance of replaceArrayConstructors: time 0.1593/8.51, allocations: 139 MB / 4.358 GB, free: 6.461 MB / 2.264 GB
Notification: Performance of NFVerifyModel.verify: time 0.04541/8.555, allocations: 5.812 MB / 4.364 GB, free: 0.6523 MB / 2.264 GB
Notification: Performance of FrontEnd: time 0.01441/8.57, allocations: 2.173 MB / 4.366 GB, free: 14.95 MB / 2.279 GB
Error: Internal error NBackendDAE.lowerVariable failed for protected final parameter Real[2, 1, ({{2}, {6}})[$conPar1, $lay1]] flo.cor.conPar.opa.lay.CInv = {{1.0 / flo.cor.conPar[$conPar1].opa.lay[$lay1].C[i] for i in 1:({{2}, {6}})[$conPar1, $lay1]} for $lay1 in 1:1, $conPar1 in 1:2}
"
[Timeout remaining time 291]
[Calling sys.exit(0), Time elapsed: 14.575701974972617]
Failed to read output from testmodel.py, exit status != 0:
8.59201485698577 8.604468386 0.031011925000000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4a14a5afb0>