Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeMassFlow.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.001626/0.001626, 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.001908/0.001908, allocations: 209.5 kB / 23.06 MB, free: 4.984 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.42/1.42, allocations: 230.7 MB / 256.9 MB, free: 7.785 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.8538/0.8538, allocations: 145 MB / 458.3 MB, free: 15.29 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.MixingVolumeMassFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeMassFlow")
translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeMassFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeMassFlow") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.728e-05/2.728e-05, allocations: 5.516 kB / 0.6344 GB, free: 6.219 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeMassFlow): time 0.5085/0.5085, allocations: 150.7 MB / 0.7816 GB, free: 4.023 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.004736/0.5132, allocations: 3.203 MB / 0.7847 GB, free: 3.91 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004683/0.5137, allocations: 32.81 kB / 0.7847 GB, free: 3.91 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.0008172/0.5145, allocations: 303.6 kB / 0.785 GB, free: 3.902 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.002214/0.5167, allocations: 1.055 MB / 0.7861 GB, free: 3.848 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001458/0.5182, allocations: 0.6857 MB / 0.7867 GB, free: 3.824 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.001729/0.5199, allocations: 1.831 MB / 0.7885 GB, free: 3.789 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005426/0.5205, allocations: 465.8 kB / 0.789 GB, free: 3.758 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009552/0.5214, allocations: 0.7413 MB / 0.7897 GB, free: 3.754 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009315/0.5224, allocations: 0.5031 MB / 0.7902 GB, free: 3.754 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 9.105e-05/0.5225, allocations: 58.45 kB / 0.7902 GB, free: 3.75 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001135/0.5236, allocations: 0.6811 MB / 0.7909 GB, free: 3.742 MB / 0.5606 GB
Notification: Performance of combineBinaries: time 0.0009497/0.5245, allocations: 1.317 MB / 0.7922 GB, free: 3.469 MB / 0.5606 GB
Notification: Performance of replaceArrayConstructors: time 0.0004368/0.525, allocations: 0.8188 MB / 0.793 GB, free: 3.145 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001063/0.5251, allocations: 60.62 kB / 0.793 GB, free: 3.141 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 0.000132/0.5252, allocations: 21.83 kB / 0.7931 GB, free: 3.137 MB / 0.5606 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 104 (95)
 * Number of variables: 104 (88)
Notification: Performance of [SIM] Bindings: time 0.002409/0.5276, allocations: 3.306 MB / 0.7963 GB, free: 1.926 MB / 0.5606 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0002975/0.5279, allocations: 334.7 kB / 0.7966 GB, free: 1.863 MB / 0.5606 GB
Notification: Performance of [SIM] Early Inline: time 0.001137/0.5291, allocations: 1.542 MB / 0.7981 GB, free: 1.789 MB / 0.5606 GB
Notification: Performance of [SIM] Simplify 1: time 0.0003462/0.5294, allocations: 304.8 kB / 0.7984 GB, free: 1.652 MB / 0.5606 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.002364/0.5318, allocations: 2.202 MB / 0.8006 GB, free: 1.016 MB / 0.5606 GB
Notification: Performance of [SIM] Simplify 2: time 0.0002734/0.532, allocations: 256.9 kB / 0.8008 GB, free: 0.8945 MB / 0.5606 GB
Notification: Performance of [SIM] Remove Stream: time 0.0001461/0.5322, allocations: 174 kB / 0.801 GB, free: 0.8164 MB / 0.5606 GB
Notification: Performance of [SIM] Detect States: time 0.0002958/0.5325, allocations: 340.6 kB / 0.8013 GB, free: 0.6914 MB / 0.5606 GB
Notification: Performance of [SIM] Events: time 0.0001256/0.5326, allocations: 101.7 kB / 0.8014 GB, free: 0.668 MB / 0.5606 GB
Notification: Performance of [SIM] Partitioning: time 0.000417/0.533, allocations: 437.2 kB / 0.8018 GB, free: 0.6211 MB / 0.5606 GB
Notification: Performance of [SIM] Causalize: time 0.002535/0.5356, allocations: 2.426 MB / 0.8042 GB, free: 160 kB / 0.5606 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0008146/0.5364, allocations: 1.052 MB / 0.8052 GB, free: 152 kB / 0.5606 GB
Notification: Performance of [INI] Simplify: time 0.001203/0.5376, allocations: 0.8147 MB / 0.806 GB, free: 15.88 MB / 0.5762 GB
Notification: Performance of [INI] Inline: time 0.001813/0.5394, allocations: 1.993 MB / 0.8079 GB, free: 14.96 MB / 0.5762 GB
Notification: Performance of [INI] Partitioning: time 7.146e-05/0.5395, allocations: 48.58 kB / 0.808 GB, free: 14.93 MB / 0.5762 GB
Notification: Performance of [INI] Cleanup: time 0.0002425/0.5397, allocations: 266.2 kB / 0.8082 GB, free: 14.79 MB / 0.5762 GB
Warning: NBResolveSingularities.balanceInitialization reports an overdetermined initialization!
Checking for consistency is not yet supported, following equations had to be removed:
	[SCAL] (1) vol.dynBal.p_start = vol.p_start ($RES_BND_149);
Notification: Performance of [INI] Causalize: time 0.003316/0.543, allocations: 2.869 MB / 0.811 GB, free: 14.14 MB / 0.5762 GB
Notification: Performance of [INI] Tearing: time 5.182e-05/0.5431, allocations: 53.41 kB / 0.8111 GB, free: 14.1 MB / 0.5762 GB
Notification: Performance of [SIM] Initialization: time 8.01e-07/0.5431, allocations: 0 / 0.8111 GB, free: 14.1 MB / 0.5762 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0004185/0.5435, allocations: 327.2 kB / 0.8114 GB, free: 13.98 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Dummies: time 5.55e-06/0.5435, allocations: 4.656 kB / 0.8114 GB, free: 13.97 MB / 0.5762 GB
Notification: Performance of [SIM] Tearing: time 4.183e-05/0.5435, allocations: 74.97 kB / 0.8115 GB, free: 13.92 MB / 0.5762 GB
Notification: Performance of [SIM] Categorize: time 8.776e-06/0.5435, allocations: 2.625 kB / 0.8115 GB, free: 13.92 MB / 0.5762 GB
Notification: Performance of [SIM] Solve: time 0.001784/0.5453, allocations: 1.466 MB / 0.8129 GB, free: 13.44 MB / 0.5762 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref $DER.vol.ports[1].p is neither a seed nor a partial candidate!
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.9422161590191536]
Failed to read output from testmodel.py, exit status != 0:
0.5536573260324076 0.5648608190000001 0.037006496
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fea3d556fb0>