Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.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.001316/0.001316, 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.001659/0.001659, allocations: 218.5 kB / 23.07 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.453/1.453, allocations: 230.6 MB / 256.9 MB, free: 7.773 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.07855/0.07855, allocations: 16.93 MB / 330.2 MB, free: 2.34 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package PowerGrids with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/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(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF")
translateModel(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.2e-05/2.2e-05, allocations: 8.328 kB / 461.3 MB, free: 1.766 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF): time 0.3533/0.3534, allocations: 86.72 MB / 0.5351 GB, free: 22.98 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.04037/0.3937, allocations: 22.15 MB / 0.5568 GB, free: 15.36 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01492/0.4087, allocations: 253.7 kB / 0.557 GB, free: 15.24 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0134/0.4221, allocations: 3.053 MB / 0.56 GB, free: 13.17 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.03379/0.4559, allocations: 9.793 MB / 0.5695 GB, free: 5 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01538/0.4712, allocations: 4.945 MB / 0.5744 GB, free: 1.449 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.04026/0.5115, allocations: 25.97 MB / 0.5997 GB, free: 12.49 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01218/0.5237, allocations: 4.182 MB / 0.6038 GB, free: 8.23 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02006/0.5437, allocations: 8.258 MB / 0.6119 GB, free: 15.94 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01793/0.5617, allocations: 8.09 MB / 0.6198 GB, free: 7.816 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.004505/0.5662, allocations: 0.8125 MB / 0.6206 GB, free: 7.004 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01161/0.5778, allocations: 2.086 MB / 0.6226 GB, free: 4.918 MB / 430.1 MB
Notification: Performance of combineBinaries: time 0.04341/0.6212, allocations: 31.83 MB / 0.6537 GB, free: 4.812 MB / 462.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0248/0.646, allocations: 17.99 MB / 0.6713 GB, free: 2.605 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00878/0.6548, allocations: 1.055 MB / 0.6723 GB, free: 1.555 MB / 478.1 MB
Notification: Performance of FrontEnd: time 0.003938/0.6587, allocations: 354.8 kB / 0.6726 GB, free: 1.207 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2013 (1568)
 * Number of variables: 2013 (2013)
Notification: Performance of [SIM] Bindings: time 0.4188/1.078, allocations: 74.87 MB / 0.7457 GB, free: 83.96 MB / 0.4981 GB
Notification: Performance of [SIM] FunctionAlias: time 0.01371/1.091, allocations: 7.459 MB / 0.753 GB, free: 80.32 MB / 0.4981 GB
Notification: Performance of [SIM] Early Inline: time 0.06046/1.152, allocations: 42.56 MB / 0.7946 GB, free: 52.33 MB / 0.4981 GB
Notification: Performance of [SIM] Simplify 1: time 0.01673/1.168, allocations: 4.5 MB / 0.799 GB, free: 49.98 MB / 0.4981 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.05009/1.219, allocations: 35.38 MB / 0.8335 GB, free: 12.07 MB / 0.4981 GB
Notification: Performance of [SIM] Simplify 2: time 0.009001/1.228, allocations: 3.74 MB / 0.8372 GB, free: 8.207 MB / 0.4981 GB
Notification: Performance of [SIM] Remove Stream: time 0.004937/1.232, allocations: 2.709 MB / 0.8398 GB, free: 5.422 MB / 0.4981 GB
Notification: Performance of [SIM] Detect States: time 0.008583/1.241, allocations: 6.667 MB / 0.8463 GB, free: 14.77 MB / 0.5137 GB
Notification: Performance of [SIM] Events: time 0.003905/1.245, allocations: 1.574 MB / 0.8479 GB, free: 13.23 MB / 0.5137 GB
Notification: Performance of [SIM] Partitioning: time 0.02113/1.266, allocations: 11.23 MB / 0.8588 GB, free: 0.9922 MB / 0.5137 GB
Notification: Performance of [SIM] Causalize: time 0.04496/1.311, allocations: 27.61 MB / 0.8858 GB, free: 4.453 MB / 0.545 GB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.3793/1.69, allocations: 21.13 MB / 0.9064 GB, free: 118.7 MB / 0.5606 GB
Notification: Performance of [INI] Simplify: time 0.05621/1.747, allocations: 17.68 MB / 0.9237 GB, free: 115.4 MB / 0.5606 GB
Notification: Performance of [INI] Inline: time 0.08571/1.832, allocations: 49.25 MB / 0.9718 GB, free: 102.5 MB / 0.5606 GB
Notification: Performance of [INI] Partitioning: time 0.005796/1.838, allocations: 0.8309 MB / 0.9726 GB, free: 102.1 MB / 0.5606 GB
Notification: Performance of [INI] Cleanup: time 0.01261/1.851, allocations: 6.076 MB / 0.9785 GB, free: 97.19 MB / 0.5606 GB
Notification: Performance of [INI] Causalize: time 0.1198/1.97, allocations: 50.96 MB / 1.028 GB, free: 57.71 MB / 0.5606 GB
Notification: Performance of [INI] Tearing: time 0.003022/1.974, allocations: 2.983 MB / 1.031 GB, free: 54.85 MB / 0.5606 GB
Notification: Performance of [SIM] Initialization: time 3.006e-06/1.974, allocations: 0 / 1.031 GB, free: 54.85 MB / 0.5606 GB
Notification: Performance of [SIM] DAE-Mode: time 0.01274/1.986, allocations: 9.509 MB / 1.041 GB, free: 44.69 MB / 0.5606 GB
Notification: Performance of [SIM] Remove Dummies: time 0.0001544/1.986, allocations: 46.5 kB / 1.041 GB, free: 44.66 MB / 0.5606 GB
Notification: Performance of [SIM] Tearing: time 0.003448/1.99, allocations: 3.734 MB / 1.044 GB, free: 41.12 MB / 0.5606 GB
Notification: Performance of [SIM] Categorize: time 0.0002888/1.99, allocations: 98.14 kB / 1.044 GB, free: 41.06 MB / 0.5606 GB
Notification: Performance of [SIM] Solve: time 0.06478/2.055, allocations: 42.55 MB / 1.086 GB, free: 15.14 MB / 0.5762 GB
Notification: Performance of [SIM] Jacobian: time 0.4607/2.516, allocations: 60 MB / 1.144 GB, free: 100.8 MB / 0.6075 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 132
 * Number of ODE event partitions: ............... 3
 * Number of algebraic event partitions: ......... 5
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 0
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 18 (18) {machine1.theta, machine2.theta, machine3.theta, machine1.omegaRefPu, machine1.lambdafPu, machine1.lambdaDPu, machine1.lambdaQ1Pu, machine1.lambdaQ2Pu, machine2.omegaRefPu, machine2.lambdafPu, machine2.lambdaDPu, machine2.lambdaQ1Pu, machine2.lambdaQ2Pu, machine3.omegaRefPu, machine3.lambdafPu, machine3.lambdaDPu, machine3.lambdaQ1Pu, machine3.lambdaQ2Pu}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 78 (78) {$SEV_77, $SEV_76, $SEV_75, $SEV_74, $SEV_73, $SEV_72, $SEV_71, $SEV_70, $SEV_69, $SEV_68, $SEV_67, $SEV_66, $SEV_65, $SEV_64, $SEV_63, $SEV_62, $SEV_61, $SEV_60, $SEV_59, $SEV_58, $SEV_57, $SEV_56, $SEV_55, $SEV_54, $SEV_53, $SEV_52, $SEV_51, $SEV_50, $SEV_49, $SEV_48, $SEV_47, $SEV_46, $SEV_45, $SEV_44, $SEV_43, $SEV_42, $SEV_41, $SEV_40, $SEV_39, $SEV_38, $SEV_37, $SEV_36, $SEV_35, $SEV_34, $SEV_33, $SEV_32, $SEV_31, $SEV_30, $SEV_29, $SEV_28, $SEV_27, $SEV_26, $SEV_25, $SEV_24, $SEV_23, $SEV_22, $SEV_21, $SEV_20, $SEV_19, $SEV_18, $SEV_17, $SEV_16, $SEV_15, $SEV_14, $SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_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: ........... 1064 (scalar:1064, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:48)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 7 (linear: 7, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 2331 (scalar:2331, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:88)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 34 (linear: 34, nonlinear:0)
Notification: Performance of backend: time 0.00106/2.517, allocations: 0.5133 MB / 1.145 GB, free: 100.6 MB / 0.6075 GB
Notification: Performance of SimCode: time 0.08504/2.602, allocations: 54.41 MB / 1.198 GB, free: 86.05 MB / 0.6075 GB
Notification: Performance of Templates: time 0.2336/2.835, allocations: 152.6 MB / 1.347 GB, free: 10.84 MB / 0.6544 GB
"
[Timeout remaining time 657]
make -j1 -f PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe ; mkfifo PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe >> ../files/PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.sim & ./PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 15.21104992000619]
Failed to read output from testmodel.py, exit status != 0:
2.844424606009852 3.051336015 2.389413131
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f0a66556dd0>