Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_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.001375/0.001375, allocations: 104.3 kB / 19.72 MB, free: 2.145 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.00156/0.00156, allocations: 212.8 kB / 23.01 MB, free: 4.965 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.587/1.587, allocations: 230.6 MB / 256.8 MB, free: 7.789 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.256/3.256, allocations: 0.49 GB / 0.7958 GB, free: 35.48 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.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_12_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_12_Buildings.Examples.VAVReheat.Validation.TraceSubstance") [Timeout 300]
"Notification: Performance of FrontEnd - Absyn->SCode: time 3.209e-05/3.209e-05, allocations: 4.672 kB / 1.155 GB, free: 25.18 MB / 0.9044 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.VAVReheat.Validation.TraceSubstance): time 5.303/5.303, allocations: 1.898 GB / 3.053 GB, free: 105.8 MB / 1.936 GB
Notification: Performance of NFInst.instExpressions: time 0.3515/5.655, allocations: 227 MB / 3.275 GB, free: 94.38 MB / 1.936 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.09936/5.754, allocations: 2.981 MB / 3.278 GB, free: 94.38 MB / 1.936 GB
Notification: Performance of NFTyping.typeComponents: time 0.0939/5.848, allocations: 30.58 MB / 3.308 GB, free: 87.65 MB / 1.936 GB
Notification: Performance of NFTyping.typeBindings: time 0.1607/6.009, allocations: 54.55 MB / 3.361 GB, free: 68.88 MB / 1.936 GB
Notification: Performance of NFTyping.typeClassSections: time 0.08537/6.094, allocations: 37.24 MB / 3.398 GB, free: 45.59 MB / 1.936 GB
Notification: Performance of NFFlatten.flatten: time 0.4006/6.495, allocations: 322.3 MB / 3.712 GB, free: 10.59 MB / 2.154 GB
Notification: Performance of NFFlatten.resolveConnections: time 2.907/9.401, allocations: 66.8 MB / 3.778 GB, free: 108.5 MB / 2.186 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.2073/9.609, allocations: 111.1 MB / 3.886 GB, free: 105.1 MB / 2.186 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1627/9.771, allocations: 90.81 MB / 3.975 GB, free: 96.61 MB / 2.186 GB
Notification: Performance of NFPackage.collectConstants: time 0.03995/9.811, allocations: 9.508 MB / 3.984 GB, free: 96.61 MB / 2.186 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.1436/9.955, allocations: 35.75 MB / 4.019 GB, free: 96.6 MB / 2.186 GB
Notification: Performance of combineBinaries: time 0.3084/10.26, allocations: 233.6 MB / 4.247 GB, free: 28.22 MB / 2.186 GB
Notification: Performance of replaceArrayConstructors: time 0.1705/10.43, allocations: 138.1 MB / 4.382 GB, free: 14.25 MB / 2.279 GB
Notification: Performance of NFVerifyModel.verify: time 0.05642/10.49, allocations: 5.758 MB / 4.388 GB, free: 8.496 MB / 2.279 GB
Notification: Performance of FrontEnd: time 0.01636/10.51, allocations: 2.065 MB / 4.39 GB, free: 6.871 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 289]
[Calling sys.exit(0), Time elapsed: 17.49578697187826]
Failed to read output from testmodel.py, exit status != 0:
10.529780830256641 10.541930377 0.032123442
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fcd33362f20>