Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.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.001425/0.001425, 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.001585/0.001585, allocations: 218.3 kB / 23.07 MB, free: 4.844 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.502/1.502, allocations: 230.6 MB / 256.9 MB, free: 7.738 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.07906/0.07906, allocations: 16.93 MB / 330.2 MB, free: 2.336 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.TestTransformerWithTapChangerInterval,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="$cse1|$cse2|busLoad.ERef|busLoad.PStart|busLoad.QStart|busLoad.R|busLoad.SNom|busLoad.UNom|busLoad.UPhaseStart|busLoad.URef|busLoad.UStart|busLoad.X|busLoad.Z.im|busLoad.Z.re|busLoad.i.im|busLoad.i.re|busLoad.localInit|busLoad.port.IBase|busLoad.port.IStart|busLoad.port.PStart|busLoad.port.QStart|busLoad.port.SBase|busLoad.port.SNom|busLoad.port.UBase|busLoad.port.UNom|busLoad.port.UPhaseStart|busLoad.port.UStart|busLoad.port.VBase|busLoad.port.VStart|busLoad.port.i.im|busLoad.port.i.re|busLoad.port.iStart.im|busLoad.port.iStart.re|busLoad.port.portVariablesPhases|busLoad.port.v.im|busLoad.port.v.re|busLoad.port.vStart.im|busLoad.port.vStart.re|busLoad.portVariablesPhases|busLoad.theta|busLoad.v.im|busLoad.v.re|infiniteBus.PStart|infiniteBus.QStart|infiniteBus.R|infiniteBus.SNom|infiniteBus.UFixed|infiniteBus.UNom|infiniteBus.UPhaseStart|infiniteBus.UStart|infiniteBus.X|infiniteBus.Z.im|infiniteBus.Z.re|infiniteBus.localInit|infiniteBus.port.IBase|infiniteBus.port.IStart|infiniteBus.port.PStart|infiniteBus.port.QStart|infiniteBus.port.SBase|infiniteBus.port.SNom|infiniteBus.port.UBase|infiniteBus.port.UNom|infiniteBus.port.UPhaseStart|infiniteBus.port.UStart|infiniteBus.port.VBase|infiniteBus.port.VStart|infiniteBus.port.i.im|infiniteBus.port.i.re|infiniteBus.port.iStart.im|infiniteBus.port.iStart.re|infiniteBus.port.portVariablesPhases|infiniteBus.port.v.im|infiniteBus.port.v.re|infiniteBus.port.vStart.im|infiniteBus.port.vStart.re|infiniteBus.portVariablesPhases|infiniteBus.thetaAux|infiniteBus.thetaFixed|infiniteBus.useThetaIn|infiniteBus.useUIn|systemPowerGrids.fNom|systemPowerGrids.initOpt|systemPowerGrids.omegaNom|systemPowerGrids.omegaRef|systemPowerGrids.omegaRefInternal|systemPowerGrids.referenceFrequency|time|trafo.B|trafo.G|trafo.K.1.|trafo.K.2.|trafo.K.3.|trafo.K.4.|trafo.K.5.|trafo.Ntap|trafo.PStartA|trafo.PStartB|trafo.QStartA|trafo.QStartB|trafo.R|trafo.SNom|trafo.UMax|trafo.UMin|trafo.UNomA|trafo.UNomB|trafo.UPhaseStartA|trafo.UPhaseStartB|trafo.UStartA|trafo.UStartB|trafo.X|trafo.YB.im|trafo.YB.re|trafo.actionSel|trafo.computePowerBalance|trafo.directAction|trafo.iA.im|trafo.iA.re|trafo.lockedStart|trafo.portA.IBase|trafo.portA.IStart|trafo.portA.PStart|trafo.portA.QStart|trafo.portA.SBase|trafo.portA.SNom|trafo.portA.UBase|trafo.portA.UNom|trafo.portA.UPhaseStart|trafo.portA.UStart|trafo.portA.VBase|trafo.portA.VStart|trafo.portA.iStart.im|trafo.portA.iStart.re|trafo.portA.portVariablesPhases|trafo.portA.vStart.im|trafo.portA.vStart.re|trafo.portB.IBase|trafo.portB.IStart|trafo.portB.PStart|trafo.portB.QStart|trafo.portB.SBase|trafo.portB.SNom|trafo.portB.UBase|trafo.portB.UNom|trafo.portB.UPhaseStart|trafo.portB.UStart|trafo.portB.VBase|trafo.portB.VStart|trafo.portB.iStart.im|trafo.portB.iStart.re|trafo.portB.portVariablesPhases|trafo.portB.vStart.im|trafo.portB.vStart.re|trafo.portVariablesPhases|trafo.reverseAction|trafo.runningStart|trafo.stateStart|trafo.t1st|trafo.tNext|trafo.tToStandardState|trafo.tapMax|trafo.tapMin|trafo.tapStart|trafo.vA.im|trafo.vA.re",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval")
translateModel(PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="$cse1|$cse2|busLoad.ERef|busLoad.PStart|busLoad.QStart|busLoad.R|busLoad.SNom|busLoad.UNom|busLoad.UPhaseStart|busLoad.URef|busLoad.UStart|busLoad.X|busLoad.Z.im|busLoad.Z.re|busLoad.i.im|busLoad.i.re|busLoad.localInit|busLoad.port.IBase|busLoad.port.IStart|busLoad.port.PStart|busLoad.port.QStart|busLoad.port.SBase|busLoad.port.SNom|busLoad.port.UBase|busLoad.port.UNom|busLoad.port.UPhaseStart|busLoad.port.UStart|busLoad.port.VBase|busLoad.port.VStart|busLoad.port.i.im|busLoad.port.i.re|busLoad.port.iStart.im|busLoad.port.iStart.re|busLoad.port.portVariablesPhases|busLoad.port.v.im|busLoad.port.v.re|busLoad.port.vStart.im|busLoad.port.vStart.re|busLoad.portVariablesPhases|busLoad.theta|busLoad.v.im|busLoad.v.re|infiniteBus.PStart|infiniteBus.QStart|infiniteBus.R|infiniteBus.SNom|infiniteBus.UFixed|infiniteBus.UNom|infiniteBus.UPhaseStart|infiniteBus.UStart|infiniteBus.X|infiniteBus.Z.im|infiniteBus.Z.re|infiniteBus.localInit|infiniteBus.port.IBase|infiniteBus.port.IStart|infiniteBus.port.PStart|infiniteBus.port.QStart|infiniteBus.port.SBase|infiniteBus.port.SNom|infiniteBus.port.UBase|infiniteBus.port.UNom|infiniteBus.port.UPhaseStart|infiniteBus.port.UStart|infiniteBus.port.VBase|infiniteBus.port.VStart|infiniteBus.port.i.im|infiniteBus.port.i.re|infiniteBus.port.iStart.im|infiniteBus.port.iStart.re|infiniteBus.port.portVariablesPhases|infiniteBus.port.v.im|infiniteBus.port.v.re|infiniteBus.port.vStart.im|infiniteBus.port.vStart.re|infiniteBus.portVariablesPhases|infiniteBus.thetaAux|infiniteBus.thetaFixed|infiniteBus.useThetaIn|infiniteBus.useUIn|systemPowerGrids.fNom|systemPowerGrids.initOpt|systemPowerGrids.omegaNom|systemPowerGrids.omegaRef|systemPowerGrids.omegaRefInternal|systemPowerGrids.referenceFrequency|time|trafo.B|trafo.G|trafo.K.1.|trafo.K.2.|trafo.K.3.|trafo.K.4.|trafo.K.5.|trafo.Ntap|trafo.PStartA|trafo.PStartB|trafo.QStartA|trafo.QStartB|trafo.R|trafo.SNom|trafo.UMax|trafo.UMin|trafo.UNomA|trafo.UNomB|trafo.UPhaseStartA|trafo.UPhaseStartB|trafo.UStartA|trafo.UStartB|trafo.X|trafo.YB.im|trafo.YB.re|trafo.actionSel|trafo.computePowerBalance|trafo.directAction|trafo.iA.im|trafo.iA.re|trafo.lockedStart|trafo.portA.IBase|trafo.portA.IStart|trafo.portA.PStart|trafo.portA.QStart|trafo.portA.SBase|trafo.portA.SNom|trafo.portA.UBase|trafo.portA.UNom|trafo.portA.UPhaseStart|trafo.portA.UStart|trafo.portA.VBase|trafo.portA.VStart|trafo.portA.iStart.im|trafo.portA.iStart.re|trafo.portA.portVariablesPhases|trafo.portA.vStart.im|trafo.portA.vStart.re|trafo.portB.IBase|trafo.portB.IStart|trafo.portB.PStart|trafo.portB.QStart|trafo.portB.SBase|trafo.portB.SNom|trafo.portB.UBase|trafo.portB.UNom|trafo.portB.UPhaseStart|trafo.portB.UStart|trafo.portB.VBase|trafo.portB.VStart|trafo.portB.iStart.im|trafo.portB.iStart.re|trafo.portB.portVariablesPhases|trafo.portB.vStart.im|trafo.portB.vStart.re|trafo.portVariablesPhases|trafo.reverseAction|trafo.runningStart|trafo.stateStart|trafo.t1st|trafo.tNext|trafo.tToStandardState|trafo.tapMax|trafo.tapMin|trafo.tapStart|trafo.vA.im|trafo.vA.re",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.328e-05/2.328e-05, allocations: 2.281 kB / 461.3 MB, free: 1.75 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval): time 0.04038/0.0404, allocations: 38.02 MB / 499.3 MB, free: 11.57 MB / 366.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/TwoPortACVI.mo:4:3-5:21:writable] Warning: Parameter PowerGrids.Electrical.Branches.TransformerWithTapChangerInterval.UNomA has no value, and is fixed during initialization (fixed=true), using available start value (start=400e3) as default value.
Notification: Performance of NFInst.instExpressions: time 0.03665/0.07705, allocations: 24.36 MB / 0.5114 GB, free: 3.129 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005153/0.0822, allocations: 95.31 kB / 0.5115 GB, free: 3.035 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005145/0.08735, allocations: 1.269 MB / 0.5127 GB, free: 1.758 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.284/0.3714, allocations: 4.116 MB / 0.5167 GB, free: 19.79 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006393/0.3778, allocations: 1.636 MB / 0.5183 GB, free: 19.79 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01937/0.3971, allocations: 9.453 MB / 0.5275 GB, free: 19.73 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.005726/0.4029, allocations: 1.503 MB / 0.529 GB, free: 19.6 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008871/0.4117, allocations: 2.975 MB / 0.5319 GB, free: 19.35 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.007648/0.4194, allocations: 3.012 MB / 0.5349 GB, free: 18.82 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002126/0.4215, allocations: 296 kB / 0.5351 GB, free: 18.82 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.007141/0.4287, allocations: 0.9495 MB / 0.5361 GB, free: 18.81 MB / 382.1 MB
Notification: Performance of combineBinaries: time 0.01831/0.447, allocations: 11.75 MB / 0.5475 GB, free: 12.21 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.007134/0.4541, allocations: 6.617 MB / 0.554 GB, free: 7.832 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003171/0.4573, allocations: 400.5 kB / 0.5544 GB, free: 7.832 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.001052/0.4583, allocations: 115.2 kB / 0.5545 GB, free: 7.812 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 760 (560)
 * Number of variables: 760 (760)
Notification: Performance of [SIM] Bindings: time 0.04253/0.5009, allocations: 29.08 MB / 0.5829 GB, free: 1.672 MB / 398.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.006936/0.5078, allocations: 2.536 MB / 0.5854 GB, free: 15.38 MB / 414.1 MB
Notification: Performance of [SIM] Early Inline: time 0.0384/0.5462, allocations: 18.73 MB / 0.6037 GB, free: 13.13 MB / 430.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.007356/0.5536, allocations: 1.709 MB / 0.6053 GB, free: 11.32 MB / 430.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.03148/0.585, allocations: 13.54 MB / 0.6186 GB, free: 12.92 MB / 446.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.005031/0.5901, allocations: 1.573 MB / 0.6201 GB, free: 11.23 MB / 446.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.002543/0.5926, allocations: 1.211 MB / 0.6213 GB, free: 9.965 MB / 446.1 MB
Notification: Performance of [SIM] Detect States: time 0.006208/0.5988, allocations: 2.971 MB / 0.6242 GB, free: 7 MB / 446.1 MB
Notification: Performance of [SIM] Events: time 0.003664/0.6025, allocations: 1.426 MB / 0.6256 GB, free: 5.652 MB / 446.1 MB
Notification: Performance of [SIM] Partitioning: time 0.01195/0.6144, allocations: 5.152 MB / 0.6306 GB, free: 96 kB / 446.1 MB
Notification: Performance of [SIM] Causalize: time 0.02493/0.6394, allocations: 11.99 MB / 0.6423 GB, free: 3.668 MB / 462.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.01372/0.6531, allocations: 9.558 MB / 0.6517 GB, free: 10.18 MB / 478.1 MB
Notification: Performance of [INI] Simplify: time 0.01701/0.6701, allocations: 6.49 MB / 0.658 GB, free: 3.578 MB / 478.1 MB
Notification: Performance of [INI] Inline: time 0.03616/0.7062, allocations: 20.6 MB / 0.6781 GB, free: 15.22 MB / 0.4981 GB
Notification: Performance of [INI] Partitioning: time 0.002026/0.7083, allocations: 323.9 kB / 0.6784 GB, free: 14.85 MB / 0.4981 GB
Notification: Performance of [INI] Cleanup: time 0.005841/0.7141, allocations: 2.626 MB / 0.681 GB, free: 12.27 MB / 0.4981 GB
Notification: Performance of [INI] Causalize: time 0.3911/1.105, allocations: 32.66 MB / 0.7129 GB, free: 108.4 MB / 0.5137 GB
Notification: Performance of [INI] Tearing: time 0.001316/1.107, allocations: 1.023 MB / 0.7139 GB, free: 108.4 MB / 0.5137 GB
Notification: Performance of [SIM] Initialization: time 1.874e-06/1.107, allocations: 1.312 kB / 0.7139 GB, free: 108.4 MB / 0.5137 GB
Notification: Performance of [SIM] DAE-Mode: time 0.005801/1.112, allocations: 3.837 MB / 0.7176 GB, free: 107.3 MB / 0.5137 GB
Notification: Performance of [SIM] Remove Dummies: time 5.574e-05/1.112, allocations: 17.92 kB / 0.7176 GB, free: 107.3 MB / 0.5137 GB
Notification: Performance of [SIM] Tearing: time 0.001069/1.113, allocations: 1.164 MB / 0.7188 GB, free: 106.9 MB / 0.5137 GB
Notification: Performance of [SIM] Categorize: time 0.0001591/1.114, allocations: 38.72 kB / 0.7188 GB, free: 106.9 MB / 0.5137 GB
Notification: Performance of [SIM] Solve: time 0.02461/1.138, allocations: 16.41 MB / 0.7348 GB, free: 105.7 MB / 0.5137 GB
Notification: Performance of [SIM] Jacobian: time 0.01515/1.153, allocations: 17.15 MB / 0.7516 GB, free: 93.68 MB / 0.5137 GB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 48
 * Number of ODE event partitions: ............... 0
 * Number of algebraic event partitions: ......... 2
 * 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: ............................. 0 (0) {}
 * Number of discrete states: .................... 6 (6) {trafo.tTapMoved, trafo.tap, trafo.state, trafo.tValueInRangeWhileRunning, trafo.tValueAboveMaxWhileRunning, trafo.tValueUnderMinWhileRunning}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 62 (62) {trafo.locked, trafo.running, trafo.lookingToIncreaseTap, trafo.lookingToDecreaseTap, trafo.valueUnderMin, trafo.valueAboveMax, trafo.valueInRange, $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, $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: ........... 499 (scalar:499, array:0, record:0)
 * Number of multi strong components: ............ 6 (algorithm:0, when:6, if:0, tuple:32)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 2 (linear: 2, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 887 (scalar:887, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:32)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 2 (linear: 2, nonlinear:0)
Notification: Performance of backend: time 0.0006178/1.154, allocations: 226.3 kB / 0.7518 GB, free: 93.48 MB / 0.5137 GB
Notification: Performance of SimCode: time 0.02713/1.181, allocations: 21.18 MB / 0.7725 GB, free: 87.27 MB / 0.5137 GB
Notification: Performance of Templates: time 0.08319/1.264, allocations: 60.43 MB / 0.8315 GB, free: 41.34 MB / 0.5137 GB
"
[Timeout remaining time 659]
make -j1 -f PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.pipe ; mkfifo PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.pipe >> ../files/PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.sim & ./PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.TestTransformerWithTapChangerInterval.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 8.466220765956677]
Failed to read output from testmodel.py, exit status != 0:
1.2737273649545386 1.281462108 0.820666215
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4d68416dd0>