Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow.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.001392/0.001392, allocations: 104.3 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.001367/0.001367, allocations: 216.9 kB / 23.06 MB, free: 4.98 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.403/1.403, allocations: 230.7 MB / 256.9 MB, free: 7.734 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo): time 0.9047/0.9047, allocations: 145 MB / 458.3 MB, free: 15.24 MB / 366.1 MB
"
[Timeout remaining time 179]
Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/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(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow")
translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.775e-05/2.775e-05, allocations: 2.281 kB / 0.6344 GB, free: 6.137 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlow): time 0.5116/0.5116, allocations: 168.5 MB / 0.7989 GB, free: 3.664 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.008318/0.52, allocations: 5.405 MB / 0.8042 GB, free: 3.496 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001517/0.5215, allocations: 58.83 kB / 0.8043 GB, free: 3.496 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.001266/0.5227, allocations: 0.5482 MB / 0.8048 GB, free: 3.484 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.00434/0.5271, allocations: 1.914 MB / 0.8067 GB, free: 3.391 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002948/0.53, allocations: 1.201 MB / 0.8079 GB, free: 3.352 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.004128/0.5342, allocations: 3.522 MB / 0.8113 GB, free: 2.949 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001292/0.5354, allocations: 0.9495 MB / 0.8122 GB, free: 2.57 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002182/0.5376, allocations: 1.371 MB / 0.8136 GB, free: 2.148 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001989/0.5396, allocations: 1.008 MB / 0.8145 GB, free: 1.844 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001799/0.5398, allocations: 118.1 kB / 0.8147 GB, free: 1.844 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002504/0.5423, allocations: 1.183 MB / 0.8158 GB, free: 1.363 MB / 0.5606 GB
Notification: Performance of combineBinaries: time 0.002119/0.5444, allocations: 2.896 MB / 0.8186 GB, free: 15.6 MB / 0.5762 GB
Notification: Performance of replaceArrayConstructors: time 0.001432/0.5459, allocations: 1.8 MB / 0.8204 GB, free: 14.46 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002778/0.5461, allocations: 120.3 kB / 0.8205 GB, free: 14.46 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 0.0002631/0.5464, allocations: 57.27 kB / 0.8206 GB, free: 14.43 MB / 0.5762 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 248 (225)
 * Number of variables: 248 (206)
Notification: Performance of [SIM] Bindings: time 0.00689/0.5533, allocations: 7.116 MB / 0.8275 GB, free: 9.91 MB / 0.5762 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0009213/0.5542, allocations: 0.8147 MB / 0.8283 GB, free: 9.324 MB / 0.5762 GB
Notification: Performance of [SIM] Early Inline: time 0.003625/0.5578, allocations: 3.448 MB / 0.8317 GB, free: 6.848 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 1: time 0.0009552/0.5588, allocations: 0.5815 MB / 0.8322 GB, free: 6.465 MB / 0.5762 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.007563/0.5664, allocations: 5.006 MB / 0.8371 GB, free: 3.23 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 2: time 0.0007945/0.5671, allocations: 498.4 kB / 0.8376 GB, free: 2.898 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003927/0.5675, allocations: 324.2 kB / 0.8379 GB, free: 2.645 MB / 0.5762 GB
Notification: Performance of [SIM] Detect States: time 0.0007631/0.5683, allocations: 0.6561 MB / 0.8386 GB, free: 2.09 MB / 0.5762 GB
Notification: Performance of [SIM] Events: time 0.0003023/0.5686, allocations: 210.8 kB / 0.8388 GB, free: 1.961 MB / 0.5762 GB
Notification: Performance of [SIM] Partitioning: time 0.001084/0.5697, allocations: 0.9803 MB / 0.8397 GB, free: 1.391 MB / 0.5762 GB
Notification: Performance of [SIM] Causalize: time 0.006877/0.5766, allocations: 5.065 MB / 0.8447 GB, free: 14.16 MB / 0.5919 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.002318/0.5789, allocations: 2.231 MB / 0.8468 GB, free: 11.98 MB / 0.5919 GB
Notification: Performance of [INI] Simplify: time 0.003092/0.582, allocations: 1.573 MB / 0.8484 GB, free: 10.36 MB / 0.5919 GB
Notification: Performance of [INI] Inline: time 0.004506/0.5865, allocations: 4.246 MB / 0.8525 GB, free: 6.215 MB / 0.5919 GB
Notification: Performance of [INI] Partitioning: time 0.0001672/0.5866, allocations: 72 kB / 0.8526 GB, free: 6.129 MB / 0.5919 GB
Notification: Performance of [INI] Cleanup: time 0.0006116/0.5873, allocations: 0.5486 MB / 0.8531 GB, free: 5.605 MB / 0.5919 GB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) volDyn.dynBal.p_start = volDyn.p_start ($RES_BND_329);
Notification: Performance of [INI] Causalize: time 0.009259/0.5965, allocations: 6.143 MB / 0.8591 GB, free: 15.42 MB / 0.6075 GB
Notification: Performance of [INI] Tearing: time 0.0002498/0.5968, allocations: 215.8 kB / 0.8593 GB, free: 15.21 MB / 0.6075 GB
Notification: Performance of [SIM] Initialization: time 1.223e-06/0.5968, allocations: 0 / 0.8593 GB, free: 15.21 MB / 0.6075 GB
Notification: Performance of [SIM] DAE-Mode: time 0.001157/0.5979, allocations: 0.6723 MB / 0.86 GB, free: 14.53 MB / 0.6075 GB
Notification: Performance of [SIM] Remove Dummies: time 9.678e-06/0.5979, allocations: 5.25 kB / 0.86 GB, free: 14.52 MB / 0.6075 GB
Notification: Performance of [SIM] Tearing: time 0.0002176/0.5982, allocations: 252.5 kB / 0.8602 GB, free: 14.28 MB / 0.6075 GB
Notification: Performance of [SIM] Categorize: time 3.115e-05/0.5982, allocations: 11.94 kB / 0.8602 GB, free: 14.27 MB / 0.6075 GB
Notification: Performance of [SIM] Solve: time 0.004901/0.6031, allocations: 3.16 MB / 0.8633 GB, free: 11.18 MB / 0.6075 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref volDyn.ports[1].p is neither a seed nor a partial candidate!
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 4.053398354968522]
Failed to read output from testmodel.py, exit status != 0:
0.6134242950356565 0.626233388 0.07687688200000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f1f4fd3afb0>