Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr HelmholtzMedia_HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2.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.001429/0.001429, 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.001878/0.001878, allocations: 216 kB / 23.07 MB, free: 4.84 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.486/1.486, allocations: 230.6 MB / 256.9 MB, free: 7.742 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/HelmholtzMedia main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/HelmholtzMedia main/package.mo): time 0.048/0.048, allocations: 9.129 MB / 322.4 MB, free: 10.18 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package HelmholtzMedia with version 2.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/HelmholtzMedia main/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(HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="HelmholtzMedia_HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2")
translateModel(HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="HelmholtzMedia_HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.576e-05/1.576e-05, allocations: 2.281 kB / 447.9 MB, free: 8 kB / 302.1 MB
Notification: Performance of NFInst.instantiate(HelmholtzMedia.Examples.MediaTestModels.EthanolTestModel2): time 0.4353/0.4353, allocations: 174.9 MB / 0.6081 GB, free: 1.109 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.5164/0.9517, allocations: 125.6 MB / 0.7308 GB, free: 212 kB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008111/0.9525, allocations: 12.66 kB / 0.7308 GB, free: 212 kB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.003039/0.9555, allocations: 1.254 MB / 0.7321 GB, free: 208 kB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.003904/0.9594, allocations: 1.634 MB / 0.7337 GB, free: 192 kB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.1075/1.067, allocations: 40.56 MB / 0.7733 GB, free: 12.72 MB / 0.5137 GB
Notification: Performance of NFFlatten.flatten: time 0.003236/1.07, allocations: 2.304 MB / 0.7755 GB, free: 12.09 MB / 0.5137 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004013/1.071, allocations: 117.9 kB / 0.7756 GB, free: 12.07 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001874/1.072, allocations: 1.003 MB / 0.7766 GB, free: 11.8 MB / 0.5137 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006487/1.073, allocations: 498.8 kB / 0.7771 GB, free: 11.67 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 8.49e-05/1.073, allocations: 65.88 kB / 0.7772 GB, free: 11.66 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.07015/1.143, allocations: 31.26 MB / 0.8077 GB, free: 10.07 MB / 0.5294 GB
Notification: Performance of combineBinaries: time 0.002013/1.145, allocations: 1.464 MB / 0.8091 GB, free: 8.82 MB / 0.5294 GB
Notification: Performance of replaceArrayConstructors: time 0.0007574/1.146, allocations: 0.9299 MB / 0.81 GB, free: 7.906 MB / 0.5294 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002273/1.146, allocations: 96 kB / 0.8101 GB, free: 7.812 MB / 0.5294 GB
Notification: Performance of FrontEnd: time 0.0001118/1.146, allocations: 11.94 kB / 0.8101 GB, free: 7.801 MB / 0.5294 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 176 (116)
 * Number of variables: 176 (176)
Notification: Performance of [SIM] Bindings: time 0.0055/1.152, allocations: 5.142 MB / 0.8151 GB, free: 2.648 MB / 0.5294 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0007766/1.153, allocations: 0.6252 MB / 0.8158 GB, free: 2.031 MB / 0.5294 GB
Notification: Performance of [SIM] Early Inline: time 0.003408/1.156, allocations: 2.974 MB / 0.8187 GB, free: 15.12 MB / 0.545 GB
Notification: Performance of [SIM] Simplify 1: time 0.0006951/1.157, allocations: 387.5 kB / 0.819 GB, free: 14.68 MB / 0.545 GB
Notification: Performance of [SIM] Alias: time 0.004352/1.161, allocations: 2.787 MB / 0.8218 GB, free: 11.78 MB / 0.545 GB
Notification: Performance of [SIM] Simplify 2: time 0.0006652/1.162, allocations: 397.8 kB / 0.8221 GB, free: 11.32 MB / 0.545 GB
Notification: Performance of [SIM] Remove Stream: time 0.0003236/1.162, allocations: 274.1 kB / 0.8224 GB, free: 11.03 MB / 0.545 GB
Notification: Performance of [SIM] Detect States: time 0.000765/1.163, allocations: 0.6412 MB / 0.823 GB, free: 10.36 MB / 0.545 GB
Notification: Performance of [SIM] Events: time 0.0004348/1.163, allocations: 280.9 kB / 0.8233 GB, free: 10.11 MB / 0.545 GB
Error: Internal error NBPartitioning.partitioningClocked (ODE) failed because the following variables could not be assigned to a partition:
  {[ALGB] (1) Real volume1.medium.sat.vap.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real volume1.medium.sat.vap.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real volume1.medium.sat.vap.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real volume1.medium.sat.vap.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real volume1.medium.sat.vap.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer volume1.medium.sat.vap.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real volume1.medium.sat.liq.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real volume1.medium.sat.liq.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real volume1.medium.sat.liq.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real volume1.medium.sat.liq.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real volume1.medium.sat.liq.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer volume1.medium.sat.liq.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real ambient.medium.sat.vap.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real ambient.medium.sat.vap.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real ambient.medium.sat.vap.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real ambient.medium.sat.vap.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real ambient.medium.sat.vap.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer ambient.medium.sat.vap.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real ambient.medium.sat.liq.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real ambient.medium.sat.liq.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real ambient.medium.sat.liq.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real ambient.medium.sat.liq.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real ambient.medium.sat.liq.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer ambient.medium.sat.liq.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.vap.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.vap.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.vap.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.vap.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.vap.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer fixedMassFlowRate.medium.sat.vap.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.liq.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.liq.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.liq.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.liq.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real fixedMassFlowRate.medium.sat.liq.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer fixedMassFlowRate.medium.sat.liq.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real volume.medium.sat.vap.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real volume.medium.sat.vap.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real volume.medium.sat.vap.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real volume.medium.sat.vap.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real volume.medium.sat.vap.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer volume.medium.sat.vap.phase (start = 0, min = 0, max = 2)
[ALGB] (1) Real volume.medium.sat.liq.s (start = 3817.5, min = -465.0, max = 8100.0, nominal = 1000.0)
[ALGB] (1) Real volume.medium.sat.liq.u (min = -1e8, max = 1e8, nominal = 1e6)
[ALGB] (1) Real volume.medium.sat.liq.d (start = 1.0, min = 2.2250738585072014e-308, max = 893.73, nominal = 1.0)
[ALGB] (1) Real volume.medium.sat.liq.p (start = 101325.0, min = 0.0, max = 2.8e8, nominal = 1e5)
[ALGB] (1) Real volume.medium.sat.liq.T (start = 298.15, min = 250.0, max = 650.0, nominal = 300.0)
[DISC] (1) Integer volume.medium.sat.liq.phase (start = 0, min = 0, max = 2)}
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 3.5992838899837807]
Failed to read output from testmodel.py, exit status != 0:
1.170967317011673 1.185756126 0.03603282
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f50d8cf2fb0>