Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi.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.001322/0.001322, allocations: 96.41 kB / 20.51 MB, free: 1.152 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.001484/0.001484, allocations: 211.3 kB / 23.81 MB, free: 4.344 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.405/1.405, allocations: 230.7 MB / 257.6 MB, free: 7.77 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.9513/0.9513, 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.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi")
translateModel(IBPSA.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.405e-05/2.405e-05, allocations: 4.812 kB / 0.6424 GB, free: 9.574 MB / 478.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Utilities.Psychrometrics.Examples.TWetBul_TDryBulPhi): time 0.003012/0.003036, allocations: 1.87 MB / 0.6442 GB, free: 7.711 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.5423/0.5453, allocations: 136.7 MB / 0.7777 GB, free: 7.477 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001865/0.5455, allocations: 0.8125 kB / 0.7777 GB, free: 7.477 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.0001789/0.5457, allocations: 30.47 kB / 0.7777 GB, free: 7.477 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.0003221/0.546, allocations: 79.7 kB / 0.7778 GB, free: 7.473 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.000807/0.5468, allocations: 264 kB / 0.7781 GB, free: 7.461 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.000383/0.5472, allocations: 371.6 kB / 0.7784 GB, free: 7.461 MB / 0.5762 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001843/0.5474, allocations: 45.66 kB / 0.7785 GB, free: 7.457 MB / 0.5762 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001497/0.5489, allocations: 312.5 kB / 0.7788 GB, free: 7.441 MB / 0.5762 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001368/0.549, allocations: 85.52 kB / 0.7789 GB, free: 7.441 MB / 0.5762 GB
Notification: Performance of NFPackage.collectConstants: time 3.965e-05/0.5491, allocations: 14.03 kB / 0.7789 GB, free: 7.441 MB / 0.5762 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003715/0.5495, allocations: 182.2 kB / 0.779 GB, free: 7.438 MB / 0.5762 GB
Notification: Performance of combineBinaries: time 0.0003213/0.5498, allocations: 368.2 kB / 0.7794 GB, free: 7.344 MB / 0.5762 GB
Notification: Performance of replaceArrayConstructors: time 0.0001315/0.5499, allocations: 214.6 kB / 0.7796 GB, free: 7.25 MB / 0.5762 GB
Notification: Performance of NFVerifyModel.verify: time 4.224e-05/0.5499, allocations: 17.22 kB / 0.7796 GB, free: 7.25 MB / 0.5762 GB
Notification: Performance of FrontEnd: time 6.32e-05/0.55, allocations: 9.328 kB / 0.7796 GB, free: 7.25 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: 39 (40)
 * Number of variables: 39 (38)
Notification: Performance of [SIM] Bindings: time 0.0009525/0.551, allocations: 0.9627 MB / 0.7806 GB, free: 6.797 MB / 0.5762 GB
Notification: Performance of [SIM] FunctionAlias: time 0.0001524/0.5511, allocations: 134.6 kB / 0.7807 GB, free: 6.766 MB / 0.5762 GB
Notification: Performance of [SIM] Early Inline: time 0.0009122/0.552, allocations: 0.9079 MB / 0.7816 GB, free: 6.68 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 1: time 0.0003118/0.5523, allocations: 236.3 kB / 0.7818 GB, free: 6.543 MB / 0.5762 GB
Notification: Performance of [SIM] Alias: time 0.001412/0.5538, allocations: 1.031 MB / 0.7828 GB, free: 6.188 MB / 0.5762 GB
Notification: Performance of [SIM] Simplify 2: time 0.0002437/0.554, allocations: 207.9 kB / 0.783 GB, free: 6.059 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Stream: time 8.066e-05/0.5541, allocations: 80.81 kB / 0.7831 GB, free: 6.004 MB / 0.5762 GB
Notification: Performance of [SIM] Detect States: time 0.0001813/0.5543, allocations: 194.5 kB / 0.7833 GB, free: 5.895 MB / 0.5762 GB
Notification: Performance of [SIM] Events: time 0.0001536/0.5544, allocations: 102.2 kB / 0.7834 GB, free: 5.879 MB / 0.5762 GB
Notification: Performance of [SIM] Partitioning: time 0.0003304/0.5547, allocations: 282.5 kB / 0.7836 GB, free: 5.855 MB / 0.5762 GB
Notification: Performance of [SIM] Causalize: time 0.000562/0.5553, allocations: 360.2 kB / 0.784 GB, free: 5.809 MB / 0.5762 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.0004197/0.5557, allocations: 0.4911 MB / 0.7845 GB, free: 5.809 MB / 0.5762 GB
Notification: Performance of [INI] Simplify: time 0.0005785/0.5563, allocations: 421.3 kB / 0.7849 GB, free: 5.598 MB / 0.5762 GB
Notification: Performance of [INI] Inline: time 0.0007726/0.5571, allocations: 0.8614 MB / 0.7857 GB, free: 5.582 MB / 0.5762 GB
Notification: Performance of [INI] Partitioning: time 2.987e-05/0.5571, allocations: 19.33 kB / 0.7857 GB, free: 5.562 MB / 0.5762 GB
Notification: Performance of [INI] Cleanup: time 0.0001234/0.5572, allocations: 135.1 kB / 0.7859 GB, free: 5.562 MB / 0.5762 GB
Notification: Performance of [INI] Causalize: time 0.00078/0.558, allocations: 0.4947 MB / 0.7863 GB, free: 5.496 MB / 0.5762 GB
Notification: Performance of [INI] Tearing: time 0.000202/0.5582, allocations: 126.2 kB / 0.7865 GB, free: 5.453 MB / 0.5762 GB
Notification: Performance of [SIM] Initialization: time 4.31e-07/0.5582, allocations: 0 / 0.7865 GB, free: 5.453 MB / 0.5762 GB
Notification: Performance of [SIM] DAE-Mode: time 0.0003674/0.5586, allocations: 281 kB / 0.7867 GB, free: 5.332 MB / 0.5762 GB
Notification: Performance of [SIM] Remove Dummies: time 4.899e-06/0.5586, allocations: 0.5625 kB / 0.7867 GB, free: 5.332 MB / 0.5762 GB
Notification: Performance of [SIM] Tearing: time 0.0001551/0.5587, allocations: 131.3 kB / 0.7868 GB, free: 5.285 MB / 0.5762 GB
Notification: Performance of [SIM] Categorize: time 8.326e-06/0.5587, allocations: 1.969 kB / 0.7869 GB, free: 5.285 MB / 0.5762 GB
Notification: Performance of [SIM] Solve: time 0.001294/0.56, allocations: 0.9501 MB / 0.7878 GB, free: 5.117 MB / 0.5762 GB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref x_pTphi.X[1] is neither a seed nor a partial candidate!
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 4.031223023019265]
Failed to read output from testmodel.py, exit status != 0:
0.5681029389961623 0.578515655 0.026314095000000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f2c49b8afb0>