Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.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.001245/0.001245, allocations: 112.2 kB / 21.09 MB, free: 0.8438 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.001451/0.001451, allocations: 214.8 kB / 24.37 MB, free: 4.211 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.377/1.377, allocations: 207.6 MB / 235.1 MB, free: 9.98 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 3.1.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 3.1.0-master/package.mo): time 0.2903/0.2903, allocations: 49.57 MB / 336.9 MB, free: 4.129 MB / 270.1 MB
"
[Timeout remaining time 179]
Using package ThermoPower with version 3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 3.1.0-master/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(ThermoPower.Test.WaterComponents.WaterPump,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_ThermoPower.Test.WaterComponents.WaterPump")
translateModel(ThermoPower.Test.WaterComponents.WaterPump,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_ThermoPower.Test.WaterComponents.WaterPump") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.853e-05/2.853e-05, allocations: 2.281 kB / 477.2 MB, free: 12.62 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ThermoPower.Test.WaterComponents.WaterPump): time 0.0424/0.04243, allocations: 31.72 MB / 0.497 GB, free: 12.79 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01103/0.05346, allocations: 7.593 MB / 0.5044 GB, free: 5.168 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003951/0.05386, allocations: 15.88 kB / 0.5044 GB, free: 5.152 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000536/0.05439, allocations: 135 kB / 0.5046 GB, free: 5.02 MB / 382.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Math/package.mo:1125:3-1182:12:writable] Warning: Pure function 'Modelica.Math.Matrices.solve' contains a call to impure function 'Modelica.Math.Matrices.LAPACK.dgesv_vec'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 3.1.0-master/Test.mo:1770:19-1772:45:writable] Warning: Pure function 'ThermoPower.Test.WaterComponents.WaterPump.Pump.flowCharacteristic' contains a call to impure function 'Modelica.Math.Matrices.solve'.
Notification: Performance of NFTyping.typeBindings: time 0.0008422/0.05523, allocations: 270.4 kB / 0.5048 GB, free: 4.754 MB / 382.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 3.1.0-master/Test.mo:1773:19-1775:46:writable] Warning: Pure function 'ThermoPower.Test.WaterComponents.WaterPump.Pump.powerCharacteristic' contains a call to impure function 'Modelica.Math.Matrices.solve'.
Notification: Performance of NFTyping.typeClassSections: time 0.01147/0.0667, allocations: 4.45 MB / 0.5092 GB, free: 296 kB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001005/0.0677, allocations: 0.7128 MB / 0.5099 GB, free: 15.57 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003576/0.06806, allocations: 213.3 kB / 0.5101 GB, free: 15.36 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008305/0.06889, allocations: 482.8 kB / 0.5105 GB, free: 14.89 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0002897/0.06918, allocations: 219 kB / 0.5107 GB, free: 14.68 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 4.873e-05/0.06923, allocations: 24 kB / 0.5108 GB, free: 14.65 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.008938/0.07817, allocations: 5.607 MB / 0.5162 GB, free: 9.039 MB / 398.1 MB
Notification: Performance of combineBinaries: time 0.0007657/0.07894, allocations: 0.6844 MB / 0.5169 GB, free: 8.348 MB / 398.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0003249/0.07926, allocations: 446.1 kB / 0.5173 GB, free: 7.906 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 7.556e-05/0.07934, allocations: 36 kB / 0.5174 GB, free: 7.871 MB / 398.1 MB
Notification: Performance of FrontEnd: time 9.316e-05/0.07943, allocations: 11.94 kB / 0.5174 GB, free: 7.859 MB / 398.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 60 (56)
 * Number of variables: 60 (60)
Notification: Performance of Bindings: time 0.00183/0.08126, allocations: 1.644 MB / 0.519 GB, free: 6.086 MB / 398.1 MB
Notification: Performance of FunctionAlias: time 0.000179/0.08144, allocations: 141.2 kB / 0.5191 GB, free: 5.934 MB / 398.1 MB
Notification: Performance of Early Inline: time 0.001323/0.08276, allocations: 1.073 MB / 0.5202 GB, free: 4.832 MB / 398.1 MB
Notification: Performance of Simplify 1: time 0.0003022/0.08306, allocations: 217.7 kB / 0.5204 GB, free: 4.574 MB / 398.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of Alias: time 0.001977/0.08504, allocations: 1.354 MB / 0.5217 GB, free: 3.07 MB / 398.1 MB
Notification: Performance of Simplify 2: time 0.0001965/0.08524, allocations: 156.4 kB / 0.5218 GB, free: 2.871 MB / 398.1 MB
Notification: Performance of Remove Stream: time 0.0001086/0.08535, allocations: 88.36 kB / 0.5219 GB, free: 2.758 MB / 398.1 MB
Notification: Performance of Detect States: time 0.0003041/0.08565, allocations: 216.5 kB / 0.5221 GB, free: 2.492 MB / 398.1 MB
Notification: Performance of Events: time 0.0002006/0.08585, allocations: 125.9 kB / 0.5222 GB, free: 2.371 MB / 398.1 MB
Notification: Performance of Partitioning: time 0.0003292/0.08618, allocations: 257.2 kB / 0.5225 GB, free: 2.098 MB / 398.1 MB
Notification: Performance of Causalize: time 0.0009318/0.08711, allocations: 0.698 MB / 0.5232 GB, free: 1.375 MB / 398.1 MB
Notification: Performance of After Index Reduction Inline: time 0.0006651/0.08778, allocations: 0.6598 MB / 0.5238 GB, free: 0.7148 MB / 398.1 MB
Notification: Performance of Simplify: time 0.0005147/0.08829, allocations: 380.2 kB / 0.5242 GB, free: 280 kB / 398.1 MB
Notification: Performance of Inline: time 0.00111/0.0894, allocations: 1.031 MB / 0.5252 GB, free: 15.24 MB / 414.1 MB
Notification: Performance of Partitioning: time 3.536e-05/0.08944, allocations: 22.75 kB / 0.5252 GB, free: 15.21 MB / 414.1 MB
Notification: Performance of Cleanup: time 0.0002369/0.08967, allocations: 221.1 kB / 0.5254 GB, free: 14.99 MB / 414.1 MB
Notification: Performance of Causalize: time 0.002211/0.09188, allocations: 1.707 MB / 0.5271 GB, free: 13.27 MB / 414.1 MB
Notification: Performance of Tearing: time 0.001464/0.09335, allocations: 1.158 MB / 0.5282 GB, free: 12.02 MB / 414.1 MB
Notification: Performance of Initialization: time 6.71e-07/0.09335, allocations: 0 / 0.5282 GB, free: 12.02 MB / 414.1 MB
Notification: Performance of Remove Dummies: time 5.31e-06/0.09335, allocations: 0 / 0.5282 GB, free: 12.02 MB / 414.1 MB
Notification: Performance of Tearing: time 0.0007594/0.09411, allocations: 0.6322 MB / 0.5288 GB, free: 11.34 MB / 414.1 MB
Notification: Performance of Categorize: time 9.688e-06/0.09412, allocations: 0 / 0.5288 GB, free: 11.34 MB / 414.1 MB
Notification: Performance of Solve: time 0.0005282/0.09465, allocations: 365.7 kB / 0.5292 GB, free: 10.98 MB / 414.1 MB
Notification: Performance of Jacobian: time 0.0002907/0.09494, allocations: 323.5 kB / 0.5295 GB, free: 10.62 MB / 414.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 1
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 1
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 1 (1) {Pump.h}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 9 (9) {Pump.inletFluidState.phase, $SEV_1, $SEV_0, $TEV_5, $TEV_4, $TEV_3, $TEV_2, $TEV_1, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 21 (scalar:21, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 33 (scalar:33, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 37 (scalar:37, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 0, nonlinear:1)
Notification: Performance of backend: time 7.036e-05/0.09501, allocations: 43.23 kB / 0.5295 GB, free: 10.59 MB / 414.1 MB
Notification: Performance of SimCode: time 0.01409/0.1091, allocations: 6.754 MB / 0.5361 GB, free: 3.789 MB / 414.1 MB
Notification: Performance of Templates: time 0.2531/0.3622, allocations: 26.92 MB / 0.5624 GB, free: 30.69 MB / 414.1 MB
"
[Timeout remaining time 660]
make -j1 -f ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.makefile [Timeout 660]
(rm -f ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.pipe ; mkfifo ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.pipe ; head -c 1048576 < ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.pipe >> ../files/ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.sim & ./ThermoPower_ThermoPower.Test.WaterComponents.WaterPump  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ThermoPower_ThermoPower.Test.WaterComponents.WaterPump.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 7.573753980919719]
Failed to read output from testmodel.py, exit status != 0:
0.3699902892112732 0.37871292700000003 0.29697608200000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f1e87195780>