Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.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.001572/0.001572, 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.001599/0.001599, allocations: 219.4 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.4/1.4, allocations: 230.7 MB / 256.9 MB, free: 7.723 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.8863/0.8863, allocations: 145 MB / 458.3 MB, free: 15.21 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.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance")
translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.697e-05/2.697e-05, allocations: 6.109 kB / 0.6344 GB, free: 6.148 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstance): time 0.5158/0.5158, allocations: 168.4 MB / 0.7988 GB, free: 3.645 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.008054/0.5239, allocations: 5.381 MB / 0.8041 GB, free: 3.484 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001748/0.5256, allocations: 57.81 kB / 0.8041 GB, free: 3.48 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.00146/0.5271, allocations: 0.5433 MB / 0.8047 GB, free: 3.473 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.004151/0.5312, allocations: 1.675 MB / 0.8063 GB, free: 3.391 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002972/0.5342, allocations: 1.233 MB / 0.8075 GB, free: 3.344 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.003609/0.5378, allocations: 3.062 MB / 0.8105 GB, free: 3.039 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001251/0.5391, allocations: 0.8348 MB / 0.8113 GB, free: 2.949 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002292/0.5414, allocations: 1.287 MB / 0.8126 GB, free: 2.57 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001704/0.5431, allocations: 0.8716 MB / 0.8134 GB, free: 2.348 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.000151/0.5432, allocations: 97.97 kB / 0.8135 GB, free: 2.348 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002563/0.5458, allocations: 1.249 MB / 0.8147 GB, free: 1.977 MB / 0.5606 GB
Notification: Performance of combineBinaries: time 0.001692/0.5475, allocations: 2.538 MB / 0.8172 GB, free: 436 kB / 0.5606 GB
Notification: Performance of replaceArrayConstructors: time 0.001091/0.5486, allocations: 1.598 MB / 0.8188 GB, free: 15.4 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002366/0.5488, allocations: 111.2 kB / 0.8189 GB, free: 15.4 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 0.0002621/0.5491, allocations: 48.45 kB / 0.8189 GB, free: 15.38 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.006301/0.5554, allocations: 6.275 MB / 0.825 GB, free: 11.37 MB / 0.5762 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0007527/0.5561, allocations: 0.6601 MB / 0.8257 GB, free: 10.88 MB / 0.5762 GB
Notification: Performance of [SIM] Early Inline: time 0.003071/0.5592, allocations: 2.948 MB / 0.8286 GB, free: 8.75 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 1: time 0.0008355/0.56, allocations: 0.4996 MB / 0.829 GB, free: 8.422 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.005997/0.566, allocations: 4.273 MB / 0.8332 GB, free: 5.574 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 2: time 0.0006491/0.5667, allocations: 420.3 kB / 0.8336 GB, free: 5.297 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003382/0.567, allocations: 265.7 kB / 0.8339 GB, free: 5.094 MB / 0.5762 GB
Notification: Performance of [SIM] Detect States: time 0.0006877/0.5677, allocations: 0.578 MB / 0.8344 GB, free: 4.59 MB / 0.5762 GB
Notification: Performance of [SIM] Events: time 0.0002662/0.568, allocations: 177.6 kB / 0.8346 GB, free: 4.484 MB / 0.5762 GB
Notification: Performance of [SIM] Partitioning: time 0.001011/0.569, allocations: 0.8785 MB / 0.8355 GB, free: 3.91 MB / 0.5762 GB
Notification: Performance of [SIM] Causalize: time 0.005514/0.5745, allocations: 4.082 MB / 0.8395 GB, free: 2.262 MB / 0.5762 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.001829/0.5763, allocations: 1.878 MB / 0.8413 GB, free: 1 MB / 0.5762 GB
Notification: Performance of [INI] Simplify: time 0.002741/0.5791, allocations: 1.469 MB / 0.8427 GB, free: 15.83 MB / 0.5919 GB
Notification: Performance of [INI] Inline: time 0.003904/0.583, allocations: 3.587 MB / 0.8462 GB, free: 12.32 MB / 0.5919 GB
Notification: Performance of [INI] Partitioning: time 0.0001495/0.5831, allocations: 60.06 kB / 0.8463 GB, free: 12.25 MB / 0.5919 GB
Notification: Performance of [INI] Cleanup: time 0.000505/0.5836, allocations: 473.5 kB / 0.8467 GB, free: 11.8 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.007996/0.5916, allocations: 5.411 MB / 0.852 GB, free: 6.352 MB / 0.5919 GB
Notification: Performance of [INI] Tearing: time 7.689e-05/0.5917, allocations: 100 kB / 0.8521 GB, free: 6.254 MB / 0.5919 GB
Notification: Performance of [SIM] Initialization: time 1.112e-06/0.5917, allocations: 0 / 0.8521 GB, free: 6.254 MB / 0.5919 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0009893/0.5927, allocations: 0.5895 MB / 0.8527 GB, free: 5.629 MB / 0.5919 GB
Notification: Performance of [SIM] Remove Dummies: time 9.347e-06/0.5927, allocations: 1.312 kB / 0.8527 GB, free: 5.629 MB / 0.5919 GB
Notification: Performance of [SIM] Tearing: time 0.0001021/0.5928, allocations: 143.9 kB / 0.8528 GB, free: 5.488 MB / 0.5919 GB
Notification: Performance of [SIM] Categorize: time 2.564e-05/0.5928, allocations: 6.188 kB / 0.8528 GB, free: 5.484 MB / 0.5919 GB
Notification: Performance of [SIM] Solve: time 0.003924/0.5967, allocations: 2.573 MB / 0.8553 GB, free: 2.945 MB / 0.5919 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.037898523034528]
Failed to read output from testmodel.py, exit status != 0:
0.6063151460257359 0.619677984 0.06754206800000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f0a5b0e2fb0>