Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance.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.001349/0.001349, allocations: 100.3 kB / 20.51 MB, free: 1.305 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.001405/0.001405, allocations: 213.8 kB / 23.81 MB, free: 4.449 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.396/1.396, allocations: 230.6 MB / 257.6 MB, free: 7.742 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 0.908/0.908, allocations: 149.9 MB / 463.9 MB, free: 10.27 MB / 366.1 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 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(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance")
translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.938e-05/1.938e-05, allocations: 2.281 kB / 0.6424 GB, free: 9.547 MB / 478.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance): time 0.5765/0.5765, allocations: 168.3 MB / 0.8068 GB, free: 6.957 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.009435/0.586, allocations: 5.387 MB / 0.812 GB, free: 6.797 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001799/0.5878, allocations: 57.22 kB / 0.8121 GB, free: 6.797 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.001856/0.5896, allocations: 0.5423 MB / 0.8126 GB, free: 6.785 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.005327/0.595, allocations: 1.682 MB / 0.8143 GB, free: 6.703 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003269/0.5982, allocations: 1.222 MB / 0.8155 GB, free: 6.66 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.003622/0.6018, allocations: 3.057 MB / 0.8184 GB, free: 6.602 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001297/0.6031, allocations: 0.833 MB / 0.8193 GB, free: 6.527 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003052/0.6062, allocations: 1.461 MB / 0.8207 GB, free: 6.516 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002075/0.6083, allocations: 0.8614 MB / 0.8215 GB, free: 6.418 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 0.000173/0.6084, allocations: 98.31 kB / 0.8216 GB, free: 6.418 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002878/0.6113, allocations: 1.238 MB / 0.8228 GB, free: 6.398 MB / 0.5762 GB
Notification: Performance of combineBinaries: time 0.002037/0.6134, allocations: 2.528 MB / 0.8253 GB, free: 5.453 MB / 0.5762 GB
Notification: Performance of replaceArrayConstructors: time 0.001052/0.6144, allocations: 1.593 MB / 0.8268 GB, free: 4.656 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003344/0.6147, allocations: 109.4 kB / 0.8269 GB, free: 4.656 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 0.0002823/0.615, allocations: 43.45 kB / 0.827 GB, free: 4.648 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: 212 (196)
 * Number of variables: 212 (180)
Notification: Performance of [SIM] Bindings: time 0.005204/0.6202, allocations: 6.246 MB / 0.8331 GB, free: 2.156 MB / 0.5762 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0006761/0.6209, allocations: 0.6645 MB / 0.8337 GB, free: 1.785 MB / 0.5762 GB
Notification: Performance of [SIM] Early Inline: time 0.002619/0.6235, allocations: 2.948 MB / 0.8366 GB, free: 40 kB / 0.5762 GB
Notification: Performance of [SIM] Simplify 1: time 0.0009314/0.6245, allocations: 0.5088 MB / 0.8371 GB, free: 15.71 MB / 0.5919 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.006153/0.6306, allocations: 4.275 MB / 0.8413 GB, free: 12.92 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 2: time 0.0006315/0.6312, allocations: 418.1 kB / 0.8417 GB, free: 12.64 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003247/0.6316, allocations: 273.9 kB / 0.8419 GB, free: 12.43 MB / 0.5919 GB
Notification: Performance of [SIM] Detect States: time 0.0006609/0.6322, allocations: 0.5609 MB / 0.8425 GB, free: 11.95 MB / 0.5919 GB
Notification: Performance of [SIM] Events: time 0.0002856/0.6325, allocations: 190.9 kB / 0.8427 GB, free: 11.84 MB / 0.5919 GB
Notification: Performance of [SIM] Partitioning: time 0.0009543/0.6335, allocations: 0.8693 MB / 0.8435 GB, free: 11.27 MB / 0.5919 GB
Notification: Performance of [SIM] Causalize: time 0.005123/0.6386, allocations: 4.08 MB / 0.8475 GB, free: 9.633 MB / 0.5919 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001734/0.6403, allocations: 1.891 MB / 0.8494 GB, free: 8.363 MB / 0.5919 GB
Notification: Performance of [INI] Simplify: time 0.002611/0.6429, allocations: 1.457 MB / 0.8508 GB, free: 7.414 MB / 0.5919 GB
Notification: Performance of [INI] Inline: time 0.003424/0.6464, allocations: 3.584 MB / 0.8543 GB, free: 4.938 MB / 0.5919 GB
Notification: Performance of [INI] Partitioning: time 0.0001402/0.6465, allocations: 65.31 kB / 0.8543 GB, free: 4.891 MB / 0.5919 GB
Notification: Performance of [INI] Cleanup: time 0.0004444/0.6469, allocations: 476.2 kB / 0.8548 GB, free: 4.539 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_283);
Notification: Performance of [INI] Causalize: time 0.007197/0.6541, allocations: 5.408 MB / 0.8601 GB, free: 0.9492 MB / 0.5919 GB
Notification: Performance of [INI] Tearing: time 7.166e-05/0.6542, allocations: 96 kB / 0.8602 GB, free: 0.8555 MB / 0.5919 GB
Notification: Performance of [SIM] Initialization: time 1.442e-06/0.6542, allocations: 0 / 0.8602 GB, free: 0.8555 MB / 0.5919 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0009888/0.6552, allocations: 0.5784 MB / 0.8607 GB, free: 248 kB / 0.5919 GB
Notification: Performance of [SIM] Remove Dummies: time 1.191e-05/0.6552, allocations: 9.312 kB / 0.8607 GB, free: 240 kB / 0.5919 GB
Notification: Performance of [SIM] Tearing: time 9.022e-05/0.6553, allocations: 138.6 kB / 0.8609 GB, free: 104 kB / 0.5919 GB
Notification: Performance of [SIM] Categorize: time 2.571e-05/0.6553, allocations: 6.438 kB / 0.8609 GB, free: 100 kB / 0.5919 GB
Notification: Performance of [SIM] Solve: time 0.003878/0.6592, allocations: 2.574 MB / 0.8634 GB, free: 13.57 MB / 0.6075 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref volSte.ports[1].p is neither a seed nor a partial candidate!
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 4.070418177987449]
Failed to read output from testmodel.py, exit status != 0:
0.6679490850074217 0.681505726 0.063534773
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4a86afefb0>