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.00134/0.00134, allocations: 92.22 kB / 19.65 MB, free: 276 kB / 13.93 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.001655/0.001655, allocations: 173 kB / 23.02 MB, free: 1.547 MB / 18.57 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 0.9455/0.9455, allocations: 177.1 MB / 203.4 MB, free: 5.625 MB / 186.7 MB
"
[Timeout remaining time 179]
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.04791/0.04791, allocations: 12.97 MB / 272.9 MB, free: 472 kB / 250.7 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 - loaded program: time 1.582e-06/1.582e-06, allocations: 0 / 347.1 MB, free: 1.5 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.905e-05/2.063e-05, allocations: 6.25 kB / 347.1 MB, free: 1.492 MB / 266.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF): time 0.1904/0.1905, allocations: 79.09 MB / 426.2 MB, free: 26.52 MB / 330.7 MB
Notification: Performance of NFInst.instExpressions: time 0.03378/0.2242, allocations: 23.49 MB / 449.7 MB, free: 21.27 MB / 330.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01223/0.2365, allocations: 263.2 kB / 450 MB, free: 21.15 MB / 330.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.011/0.2475, allocations: 3.223 MB / 453.2 MB, free: 18.64 MB / 330.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.023/0.2705, allocations: 10.59 MB / 463.8 MB, free: 11.11 MB / 330.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.008433/0.2789, allocations: 4.846 MB / 468.6 MB, free: 8.613 MB / 330.7 MB
Notification: Performance of NFFlatten.flatten: time 0.02482/0.3037, allocations: 24.06 MB / 492.7 MB, free: 2.332 MB / 346.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.009948/0.3137, allocations: 5.301 MB / 498 MB, free: 12.95 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02193/0.3356, allocations: 11.54 MB / 0.4976 GB, free: 1.383 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01045/0.3461, allocations: 6.115 MB / 0.5035 GB, free: 11.25 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.004493/0.3505, allocations: 0.9297 MB / 0.5045 GB, free: 10.32 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.008026/0.3586, allocations: 1.893 MB / 0.5063 GB, free: 8.426 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.002817/0.3614, allocations: 2.352 MB / 0.5086 GB, free: 6.062 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.01139/0.3728, allocations: 4.744 MB / 0.5132 GB, free: 1.305 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01591/0.3887, allocations: 9.714 MB / 0.5227 GB, free: 7.551 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.619e-06/0.3887, allocations: 4 kB / 0.5227 GB, free: 7.547 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.614e-06/0.3887, allocations: 0 / 0.5227 GB, free: 7.547 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 0.0003854/0.3891, allocations: 0 / 0.5227 GB, free: 7.547 MB / 394.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2053
 * Number of variables: 2053
Notification: Performance of Generate backend data structure: time 0.03252/0.4216, allocations: 17.86 MB / 0.5402 GB, free: 5.566 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.566e-05/0.4216, allocations: 8.031 kB / 0.5402 GB, free: 5.559 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008201/0.4298, allocations: 3.269 MB / 0.5434 GB, free: 2.281 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01036/0.4402, allocations: 6.701 MB / 0.5499 GB, free: 11.46 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003866/0.4406, allocations: 0.6411 MB / 0.5505 GB, free: 10.81 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001059/0.4417, allocations: 0.887 MB / 0.5514 GB, free: 9.926 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2777/0.7193, allocations: 11.09 MB / 0.5622 GB, free: 75.77 MB / 426.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001599/0.7195, allocations: 60.81 kB / 0.5623 GB, free: 75.77 MB / 426.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006997/0.7202, allocations: 375.1 kB / 0.5626 GB, free: 75.77 MB / 426.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001814/0.7203, allocations: 215.5 kB / 0.5629 GB, free: 75.77 MB / 426.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007948/0.7283, allocations: 4.782 MB / 0.5675 GB, free: 75.66 MB / 426.8 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.04212/0.7704, allocations: 70.77 MB / 0.6366 GB, free: 24.03 MB / 426.8 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006523/0.7769, allocations: 4.925 MB / 0.6414 GB, free: 23.74 MB / 426.8 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002295/0.7792, allocations: 1.525 MB / 0.6429 GB, free: 23.67 MB / 426.8 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002841/0.7795, allocations: 158.6 kB / 0.6431 GB, free: 23.66 MB / 426.8 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001342/0.7797, allocations: 133.5 kB / 0.6432 GB, free: 23.58 MB / 426.8 MB
Notification: Performance of pre-optimization done (n=729): time 1.097e-05/0.7797, allocations: 7.156 kB / 0.6432 GB, free: 23.58 MB / 426.8 MB
Notification: Performance of matching and sorting (n=729): time 0.03211/0.8118, allocations: 14.51 MB / 0.6574 GB, free: 16.68 MB / 426.8 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.122e-05/0.8118, allocations: 181 kB / 0.6576 GB, free: 16.48 MB / 426.8 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007183/0.819, allocations: 8.022 MB / 0.6654 GB, free: 8.719 MB / 426.8 MB
Notification: Performance of collectPreVariables (initialization): time 0.0009123/0.8199, allocations: 201.7 kB / 0.6656 GB, free: 8.516 MB / 426.8 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002494/0.8224, allocations: 5.05 MB / 0.6705 GB, free: 4.043 MB / 426.8 MB
Notification: Performance of collectInitialBindings (initialization): time 0.00276/0.8252, allocations: 3.087 MB / 0.6735 GB, free: 1.379 MB / 426.8 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.005283/0.8305, allocations: 4.512 MB / 0.6779 GB, free: 12.84 MB / 442.8 MB
Notification: Performance of setup shared object (initialization): time 0.0001109/0.8306, allocations: 476.8 kB / 0.6784 GB, free: 12.38 MB / 442.8 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006313/0.8369, allocations: 3.95 MB / 0.6822 GB, free: 8.418 MB / 442.8 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008133/0.845, allocations: 6.817 MB / 0.6889 GB, free: 1.082 MB / 442.8 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01171/0.8567, allocations: 9.819 MB / 0.6985 GB, free: 6.789 MB / 458.8 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.948e-05/0.8568, allocations: 8 kB / 0.6985 GB, free: 6.781 MB / 458.8 MB
Notification: Performance of matching and sorting (n=3113) (initialization): time 0.02497/0.8818, allocations: 17.61 MB / 0.7157 GB, free: 5.363 MB / 474.8 MB
Notification: Performance of prepare postOptimizeDAE: time 5.545e-05/0.8818, allocations: 16 kB / 0.7157 GB, free: 5.348 MB / 474.8 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.209e-05/0.8819, allocations: 40.06 kB / 0.7157 GB, free: 5.309 MB / 474.8 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01756/0.8994, allocations: 5.595 MB / 0.7212 GB, free: 15.64 MB / 490.8 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01188/0.9113, allocations: 4.771 MB / 0.7259 GB, free: 11.15 MB / 490.8 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1726/1.084, allocations: 44.46 MB / 0.7693 GB, free: 98.48 MB / 490.8 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01067/1.095, allocations: 1.914 MB / 0.7712 GB, free: 98.48 MB / 490.8 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001669/1.096, allocations: 0.7529 MB / 0.7719 GB, free: 98.48 MB / 490.8 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 131
 * Number of states: 0 ()
 * Number of discrete variables: 23 (LoadPQ41.isSlackBus,LoadImp42.componentPF.isSlackBus,loadPQ32.componentPF.isSlackBus,LoadPQ31.isSlackBus,loadPQ11.isSlackBus,loadPQ12.componentPF.isSlackBus,loadPQ22.componentPF.isSlackBus,busLoad2.componentPF.isSlackBus,busLoad1.isSlackBus,busGen22.componentPF.isSlackBus,GEN22.componentPF.isSlackBus,GEN22.isSlackBus,GEN22.isReferenceBus,busGen21.isSlackBus,GEN21.isSlackBus,GEN21.isReferenceBus,slackBus1.isSlackBus,busGen11.isSlackBus,LoadPQ21.isSlackBus,busGen12.componentPF.isSlackBus,GEN12.componentPF.isSlackBus,GEN12.isSlackBus,GEN12.isReferenceBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2946):
 * Single equations (assignments): 2926
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 20
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 7 systems
   {(2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 13 systems
   {(47,0), (6,0), (6,0), (50,0), (48,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.002951/1.099, allocations: 1.098 MB / 0.773 GB, free: 98.31 MB / 490.8 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002011/1.101, allocations: 1.297 MB / 0.7742 GB, free: 98.31 MB / 490.8 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008387/1.11, allocations: 5.884 MB / 0.78 GB, free: 97.83 MB / 490.8 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.244e-05/1.11, allocations: 39.3 kB / 0.78 GB, free: 97.83 MB / 490.8 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.546e-06/1.11, allocations: 0 / 0.78 GB, free: 97.83 MB / 490.8 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.004058/1.114, allocations: 2.069 MB / 0.782 GB, free: 97.82 MB / 490.8 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001515/1.115, allocations: 122.8 kB / 0.7822 GB, free: 97.82 MB / 490.8 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003777/1.119, allocations: 264.3 kB / 0.7824 GB, free: 97.82 MB / 490.8 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006958/1.12, allocations: 0.5793 MB / 0.783 GB, free: 97.81 MB / 490.8 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009125/1.129, allocations: 5.745 MB / 0.7886 GB, free: 97.16 MB / 490.8 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.007834/1.137, allocations: 6.343 MB / 0.7948 GB, free: 95.74 MB / 490.8 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001592/1.138, allocations: 1.378 MB / 0.7961 GB, free: 95.63 MB / 490.8 MB
Notification: Performance of sorting global known variables: time 0.004526/1.143, allocations: 4.158 MB / 0.8002 GB, free: 95.3 MB / 490.8 MB
Notification: Performance of Backend: time 4.31e-07/1.143, allocations: 3.5 kB / 0.8002 GB, free: 95.3 MB / 490.8 MB
Notification: Performance of simCode: created initialization part: time 0.01926/1.162, allocations: 15.68 MB / 0.8155 GB, free: 89.1 MB / 490.8 MB
Notification: Performance of SimCode: time 0.01903/1.181, allocations: 18.15 MB / 0.8332 GB, free: 73.08 MB / 490.8 MB
Notification: Performance of Templates: time 0.3051/1.486, allocations: 173.5 MB / 1.003 GB, free: 58.84 MB / 490.8 MB
"
[Timeout remaining time 659]
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=240  -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.SynchronousMachine4WindingsPF.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 8.465997754130512]