Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.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.001498/0.001498, allocations: 104.3 kB / 19.77 MB, free: 2.051 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.001654/0.001654, allocations: 215.8 kB / 23.07 MB, free: 4.844 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.336/1.336, allocations: 230.7 MB / 256.9 MB, free: 7.727 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002323/0.002323, allocations: 285 kB / 313.6 MB, free: 2.551 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.157/1.157, allocations: 180.6 MB / 0.5378 GB, free: 14.48 MB / 382.1 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 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(BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance")
translateModel(BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.623e-05/1.623e-05, allocations: 6.219 kB / 0.7408 GB, free: 27.9 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(BuildingSystems.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance): time 0.2125/0.2125, allocations: 168.2 MB / 0.9051 GB, free: 14.56 MB / 0.6544 GB
Notification: Performance of NFInst.instExpressions: time 0.00981/0.2223, allocations: 5.41 MB / 0.9104 GB, free: 9.141 MB / 0.6544 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001489/0.2238, allocations: 55.56 kB / 0.9104 GB, free: 9.086 MB / 0.6544 GB
Notification: Performance of NFTyping.typeComponents: time 0.001758/0.2256, allocations: 0.5358 MB / 0.911 GB, free: 8.547 MB / 0.6544 GB
Notification: Performance of NFTyping.typeBindings: time 0.004544/0.2301, allocations: 1.676 MB / 0.9126 GB, free: 6.863 MB / 0.6544 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003551/0.2337, allocations: 1.225 MB / 0.9138 GB, free: 5.645 MB / 0.6544 GB
Notification: Performance of NFFlatten.flatten: time 0.004142/0.2378, allocations: 3.057 MB / 0.9168 GB, free: 2.582 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001335/0.2392, allocations: 0.8389 MB / 0.9176 GB, free: 1.723 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002335/0.2415, allocations: 1.289 MB / 0.9189 GB, free: 440 kB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001734/0.2432, allocations: 0.8652 MB / 0.9197 GB, free: 15.56 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001676/0.2434, allocations: 100 kB / 0.9198 GB, free: 15.46 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002595/0.246, allocations: 1.254 MB / 0.921 GB, free: 14.21 MB / 0.67 GB
Notification: Performance of combineBinaries: time 0.002503/0.2485, allocations: 2.526 MB / 0.9235 GB, free: 11.66 MB / 0.67 GB
Notification: Performance of replaceArrayConstructors: time 0.001455/0.25, allocations: 1.608 MB / 0.9251 GB, free: 10.04 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002856/0.2502, allocations: 108 kB / 0.9252 GB, free: 9.934 MB / 0.67 GB
Notification: Performance of FrontEnd: time 0.0003073/0.2505, allocations: 39.75 kB / 0.9252 GB, free: 9.895 MB / 0.67 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.006613/0.2572, allocations: 6.273 MB / 0.9313 GB, free: 3.422 MB / 0.67 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0007721/0.2579, allocations: 0.65 MB / 0.932 GB, free: 2.738 MB / 0.67 GB
Notification: Performance of [SIM] Early Inline: time 0.003202/0.2611, allocations: 2.959 MB / 0.9348 GB, free: 15.74 MB / 0.6856 GB
Notification: Performance of [SIM] Simplify 1: time 0.0008518/0.262, allocations: 0.5031 MB / 0.9353 GB, free: 15.17 MB / 0.6856 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.0063/0.2683, allocations: 4.284 MB / 0.9395 GB, free: 10.47 MB / 0.6856 GB
Notification: Performance of [SIM] Simplify 2: time 0.0006605/0.2689, allocations: 405.6 kB / 0.9399 GB, free: 10.01 MB / 0.6856 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003409/0.2693, allocations: 272.1 kB / 0.9402 GB, free: 9.715 MB / 0.6856 GB
Notification: Performance of [SIM] Detect States: time 0.0006741/0.27, allocations: 0.5688 MB / 0.9407 GB, free: 9.09 MB / 0.6856 GB
Notification: Performance of [SIM] Events: time 0.0002804/0.2702, allocations: 187.6 kB / 0.9409 GB, free: 8.906 MB / 0.6856 GB
Notification: Performance of [SIM] Partitioning: time 0.001026/0.2713, allocations: 0.8709 MB / 0.9417 GB, free: 7.973 MB / 0.6856 GB
Notification: Performance of [SIM] Causalize: time 0.005824/0.2771, allocations: 4.066 MB / 0.9457 GB, free: 3.773 MB / 0.6856 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001933/0.279, allocations: 1.885 MB / 0.9476 GB, free: 1.887 MB / 0.6856 GB
Notification: Performance of [INI] Simplify: time 0.002764/0.2818, allocations: 1.477 MB / 0.949 GB, free: 304 kB / 0.6856 GB
Notification: Performance of [INI] Inline: time 0.003786/0.2856, allocations: 3.582 MB / 0.9525 GB, free: 12.71 MB / 0.7012 GB
Notification: Performance of [INI] Partitioning: time 0.0001465/0.2857, allocations: 68.05 kB / 0.9526 GB, free: 12.62 MB / 0.7012 GB
Notification: Performance of [INI] Cleanup: time 0.0004797/0.2862, allocations: 475.5 kB / 0.953 GB, free: 12.16 MB / 0.7012 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.007905/0.2941, allocations: 5.412 MB / 0.9583 GB, free: 6.648 MB / 0.7012 GB
Notification: Performance of [INI] Tearing: time 9.227e-05/0.2942, allocations: 101.4 kB / 0.9584 GB, free: 6.551 MB / 0.7012 GB
Notification: Performance of [SIM] Initialization: time 1.723e-06/0.2942, allocations: 0 / 0.9584 GB, free: 6.551 MB / 0.7012 GB
Notification: Performance of [SIM] DAE-Mode: time 0.001111/0.2953, allocations: 0.5676 MB / 0.959 GB, free: 5.922 MB / 0.7012 GB
Notification: Performance of [SIM] Remove Dummies: time 1.45e-05/0.2953, allocations: 9.25 kB / 0.959 GB, free: 5.914 MB / 0.7012 GB
Notification: Performance of [SIM] Tearing: time 9.517e-05/0.2954, allocations: 136 kB / 0.9591 GB, free: 5.781 MB / 0.7012 GB
Notification: Performance of [SIM] Categorize: time 3.339e-05/0.2955, allocations: 7.938 kB / 0.9591 GB, free: 5.773 MB / 0.7012 GB
Notification: Performance of [SIM] Solve: time 0.003926/0.2994, allocations: 2.57 MB / 0.9616 GB, free: 3.191 MB / 0.7012 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 660]
[Calling sys.exit(0), Time elapsed: 4.001643937022891]
Failed to read output from testmodel.py, exit status != 0:
0.30771350499708205 0.32183885500000003 0.06843576400000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe65db3afb0>