Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.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.001149/0.001149, allocations: 83.66 kB / 21.29 MB, free: 500 kB / 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.001321/0.001321, allocations: 164 kB / 24.55 MB, free: 3.789 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.35/1.35, allocations: 177.3 MB / 204.9 MB, free: 9.242 MB / 190.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/package.mo): time 0.1442/0.1442, allocations: 19.71 MB / 281 MB, free: 13.03 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package ThermoPower with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermoPower 4.0.0-dev/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(ThermoPower.Test.WaterComponents.TestValveChoked,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked")
translateModel(ThermoPower.Test.WaterComponents.TestValveChoked,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 1.873e-05/1.873e-05, allocations: 6.219 kB / 417.6 MB, free: 0.7383 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(ThermoPower.Test.WaterComponents.TestValveChoked): time 0.2352/0.2352, allocations: 40.79 MB / 458.4 MB, free: 15.36 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01047/0.2457, allocations: 8.357 MB / 466.7 MB, free: 15.11 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004111/0.2461, allocations: 20.22 kB / 466.7 MB, free: 15.11 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.000653/0.2468, allocations: 204.2 kB / 466.9 MB, free: 15.11 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0006302/0.2474, allocations: 311.4 kB / 467.2 MB, free: 15.05 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01415/0.2615, allocations: 5.084 MB / 472.3 MB, free: 14.21 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00167/0.2632, allocations: 1.153 MB / 473.5 MB, free: 13.97 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004802/0.2637, allocations: 308.3 kB / 473.8 MB, free: 13.96 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001039/0.2647, allocations: 0.6771 MB / 474.5 MB, free: 13.86 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0003531/0.2651, allocations: 298.3 kB / 474.7 MB, free: 13.78 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 5.989e-05/0.2651, allocations: 34.19 kB / 474.8 MB, free: 13.78 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.009486/0.2746, allocations: 5.399 MB / 480.2 MB, free: 11.46 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.0009279/0.2755, allocations: 1.035 MB / 481.2 MB, free: 10.76 MB / 366.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0003014/0.2759, allocations: 0.6653 MB / 481.9 MB, free: 10.32 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 8.908e-05/0.2759, allocations: 59.81 kB / 481.9 MB, free: 10.32 MB / 366.1 MB
Notification: Performance of FrontEnd: time 0.0001051/0.276, allocations: 19.78 kB / 482 MB, free: 10.31 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 81 (73)
 * Number of variables: 81 (81)
Notification: Performance of [SIM] Bindings: time 0.001552/0.2776, allocations: 2.397 MB / 484.3 MB, free: 8.641 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0002848/0.2779, allocations: 307.6 kB / 484.6 MB, free: 8.402 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.001392/0.2793, allocations: 1.816 MB / 486.5 MB, free: 7.012 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.000308/0.2796, allocations: 267 kB / 486.7 MB, free: 6.805 MB / 366.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.001828/0.2814, allocations: 1.78 MB / 488.5 MB, free: 5.535 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.000175/0.2816, allocations: 190.1 kB / 488.7 MB, free: 5.367 MB / 366.1 MB
Notification: Performance of [SIM] Remove Stream: time 6.907e-05/0.2817, allocations: 95.92 kB / 488.8 MB, free: 5.27 MB / 366.1 MB
Notification: Performance of [SIM] Detect States: time 0.0002699/0.2819, allocations: 374.1 kB / 489.2 MB, free: 4.922 MB / 366.1 MB
Notification: Performance of [SIM] Events: time 0.0001368/0.2821, allocations: 91.25 kB / 489.2 MB, free: 4.863 MB / 366.1 MB
Notification: Performance of [SIM] Partitioning: time 0.0003489/0.2824, allocations: 386.1 kB / 489.6 MB, free: 4.598 MB / 366.1 MB
Notification: Performance of [SIM] Causalize: time 0.0007071/0.2831, allocations: 0.6801 MB / 490.3 MB, free: 4.316 MB / 366.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.000602/0.2837, allocations: 0.8781 MB / 491.2 MB, free: 3.676 MB / 366.1 MB
Notification: Performance of [INI] Simplify: time 0.0006286/0.2843, allocations: 0.6701 MB / 491.8 MB, free: 3.148 MB / 366.1 MB
Notification: Performance of [INI] Inline: time 0.0008813/0.2852, allocations: 1.324 MB / 493.2 MB, free: 2.113 MB / 366.1 MB
Notification: Performance of [INI] Partitioning: time 4.143e-05/0.2853, allocations: 35.98 kB / 493.2 MB, free: 2.074 MB / 366.1 MB
Notification: Performance of [INI] Cleanup: time 0.000229/0.2855, allocations: 427.5 kB / 493.6 MB, free: 1.648 MB / 366.1 MB
Notification: Performance of [INI] Causalize: time 0.002327/0.2878, allocations: 2.288 MB / 495.9 MB, free: 15.34 MB / 382.1 MB
Notification: Performance of [INI] Tearing: time 6.3e-05/0.2879, allocations: 88 kB / 496 MB, free: 15.25 MB / 382.1 MB
Notification: Performance of [SIM] Initialization: time 5.41e-07/0.2879, allocations: 0 / 496 MB, free: 15.25 MB / 382.1 MB
Notification: Performance of [SIM] Remove Dummies: time 8.717e-06/0.2879, allocations: 3.953 kB / 496 MB, free: 15.25 MB / 382.1 MB
Notification: Performance of [SIM] Tearing: time 2.345e-05/0.2879, allocations: 20 kB / 496 MB, free: 15.23 MB / 382.1 MB
Notification: Performance of [SIM] Categorize: time 1.236e-05/0.2879, allocations: 0 / 496 MB, free: 15.23 MB / 382.1 MB
Notification: Performance of [SIM] Solve: time 0.0009746/0.2889, allocations: 0.7057 MB / 496.7 MB, free: 14.54 MB / 382.1 MB
Notification: Performance of [SIM] Jacobian: time 1.182e-05/0.2889, allocations: 8 kB / 496.7 MB, free: 14.53 MB / 382.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 4
 * 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: ............................. 0 (0) {}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 9 (9) {ValveLiqChocked.fluidState.phase, ValveVap.fluidState.phase, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $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: ........... 39 (scalar:39, 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: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 83 (scalar:83, 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: ... 0 (linear: 0, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 83 (scalar:83, 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: ... 0 (linear: 0, nonlinear:0)
Notification: Performance of backend: time 0.0001231/0.289, allocations: 40.25 kB / 496.8 MB, free: 14.49 MB / 382.1 MB
Notification: Performance of SimCode: time 0.01506/0.3041, allocations: 6.711 MB / 0.4917 GB, free: 7.738 MB / 382.1 MB
Notification: Performance of Templates: time 0.03798/0.3421, allocations: 27.08 MB / 0.5181 GB, free: 12.65 MB / 414.1 MB
"
[Timeout remaining time 660]
make -j1 -f ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.makefile [Timeout 660]
(rm -f ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.pipe ; mkfifo ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.pipe ; head -c 1048576 < ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.pipe >> ../files/ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.sim & ./ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ThermoPower_4.0.0-dev_ThermoPower.Test.WaterComponents.TestValveChoked.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 5.441907711327076]
Failed to read output from testmodel.py, exit status != 0:
0.3505471833050251 0.36397678999999994 0.084928375
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fc0637591b0>