Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.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.0008515/0.0008515, allocations: 82.05 kB / 19.87 MB, free: 4.621 MB / 18.57 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.0008531/0.0008531, allocations: 169.7 kB / 23.28 MB, free: 1.223 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.9373/0.9373, allocations: 177.1 MB / 203.7 MB, free: 5.52 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.04733/0.04733, allocations: 13 MB / 273.2 MB, free: 312 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.TestControlledSynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF")
translateModel(PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.323e-06/1.323e-06, allocations: 0 / 347.6 MB, free: 1.223 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 9.268e-06/1.059e-05, allocations: 2.312 kB / 347.6 MB, free: 1.219 MB / 266.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF): time 0.01256/0.01258, allocations: 20.79 MB / 368.4 MB, free: 12.34 MB / 298.7 MB
Notification: Performance of NFInst.instExpressions: time 0.008818/0.02139, allocations: 7.342 MB / 375.7 MB, free: 4.969 MB / 298.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001357/0.02275, allocations: 91.25 kB / 375.8 MB, free: 4.879 MB / 298.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001526/0.02428, allocations: 0.9033 MB / 376.7 MB, free: 3.969 MB / 298.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00392/0.0282, allocations: 3.072 MB / 379.8 MB, free: 0.8945 MB / 298.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003047/0.03124, allocations: 1.878 MB / 381.7 MB, free: 15.02 MB / 314.7 MB
Notification: Performance of NFFlatten.flatten: time 0.005593/0.03684, allocations: 6.483 MB / 388.1 MB, free: 8.516 MB / 314.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002023/0.03886, allocations: 1.714 MB / 389.9 MB, free: 6.758 MB / 314.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003816/0.04268, allocations: 3.172 MB / 393 MB, free: 3.578 MB / 314.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001788/0.04446, allocations: 1.651 MB / 394.7 MB, free: 1.922 MB / 314.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.000385/0.04485, allocations: 312 kB / 395 MB, free: 1.617 MB / 314.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001216/0.04607, allocations: 0.6479 MB / 395.6 MB, free: 0.9688 MB / 314.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0005736/0.04664, allocations: 0.7388 MB / 396.4 MB, free: 232 kB / 314.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001154/0.04779, allocations: 1.324 MB / 397.7 MB, free: 14.9 MB / 330.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.003215/0.05101, allocations: 2.942 MB / 400.6 MB, free: 11.95 MB / 330.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.359e-06/0.05101, allocations: 8 kB / 400.6 MB, free: 11.94 MB / 330.7 MB
Notification: Performance of FrontEnd: time 9.32e-07/0.05101, allocations: 0 / 400.6 MB, free: 11.94 MB / 330.7 MB
Notification: Performance of Transformations before backend: time 1.889e-05/0.05103, allocations: 0 / 400.6 MB, free: 11.94 MB / 330.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 564
 * Number of variables: 564
Notification: Performance of Generate backend data structure: time 0.008726/0.05976, allocations: 5.046 MB / 405.7 MB, free: 6.828 MB / 330.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.523e-05/0.0598, allocations: 8.031 kB / 405.7 MB, free: 6.82 MB / 330.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00232/0.06212, allocations: 0.8731 MB / 406.6 MB, free: 5.945 MB / 330.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0027/0.06482, allocations: 2.02 MB / 408.6 MB, free: 3.867 MB / 330.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001273/0.06495, allocations: 188.8 kB / 408.8 MB, free: 3.68 MB / 330.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003021/0.06525, allocations: 268.4 kB / 409 MB, free: 3.418 MB / 330.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1214/0.1866, allocations: 3.141 MB / 412.2 MB, free: 43.82 MB / 330.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.373e-05/0.1867, allocations: 12.16 kB / 412.2 MB, free: 43.82 MB / 330.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000172/0.1868, allocations: 106.5 kB / 412.3 MB, free: 43.82 MB / 330.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.229e-05/0.1869, allocations: 59.12 kB / 412.4 MB, free: 43.82 MB / 330.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001269/0.1882, allocations: 1.019 MB / 413.4 MB, free: 43.79 MB / 330.7 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.01022/0.1984, allocations: 17.97 MB / 431.3 MB, free: 30.89 MB / 330.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001378/0.1998, allocations: 0.9843 MB / 432.3 MB, free: 30.78 MB / 330.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003365/0.2001, allocations: 299.6 kB / 432.6 MB, free: 30.74 MB / 330.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.798e-05/0.2002, allocations: 46.77 kB / 432.7 MB, free: 30.73 MB / 330.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.321e-05/0.2002, allocations: 64.58 kB / 432.7 MB, free: 30.69 MB / 330.7 MB
Notification: Performance of pre-optimization done (n=167): time 4.188e-06/0.2002, allocations: 0 / 432.7 MB, free: 30.69 MB / 330.7 MB
Notification: Performance of matching and sorting (n=167): time 0.003363/0.2036, allocations: 2.028 MB / 434.8 MB, free: 30.41 MB / 330.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.419e-05/0.2036, allocations: 201.9 kB / 435 MB, free: 30.18 MB / 330.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001517/0.2051, allocations: 2.381 MB / 437.3 MB, free: 29.78 MB / 330.7 MB
Notification: Performance of collectPreVariables (initialization): time 9.617e-05/0.2052, allocations: 75.52 kB / 437.4 MB, free: 29.75 MB / 330.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0006111/0.2058, allocations: 1.583 MB / 439 MB, free: 29.17 MB / 330.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003313/0.2062, allocations: 0.72 MB / 439.7 MB, free: 28.85 MB / 330.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009256/0.2071, allocations: 1.159 MB / 440.9 MB, free: 28.75 MB / 330.7 MB
Notification: Performance of setup shared object (initialization): time 3.284e-05/0.2071, allocations: 478.5 kB / 441.3 MB, free: 28.28 MB / 330.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001099/0.2082, allocations: 0.9423 MB / 442.3 MB, free: 28.21 MB / 330.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001407/0.2096, allocations: 1.956 MB / 444.2 MB, free: 27.32 MB / 330.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001941/0.2116, allocations: 2.37 MB / 446.6 MB, free: 26.69 MB / 330.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.221e-05/0.2116, allocations: 4 kB / 446.6 MB, free: 26.68 MB / 330.7 MB
Notification: Performance of matching and sorting (n=767) (initialization): time 0.004184/0.2158, allocations: 4.214 MB / 450.8 MB, free: 25.04 MB / 330.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.052e-05/0.2158, allocations: 11.28 kB / 450.8 MB, free: 25.03 MB / 330.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.738e-05/0.2158, allocations: 13.92 kB / 450.8 MB, free: 25.02 MB / 330.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004553/0.2203, allocations: 1.437 MB / 452.3 MB, free: 23.56 MB / 330.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002642/0.223, allocations: 1.532 MB / 453.8 MB, free: 22.1 MB / 330.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007821/0.2308, allocations: 28.85 MB / 482.7 MB, free: 7.727 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002224/0.233, allocations: 0.5076 MB / 483.2 MB, free: 7.336 MB / 346.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001917/0.2332, allocations: 144 kB / 483.3 MB, free: 7.195 MB / 346.7 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001677/0.2349, allocations: 1.139 MB / 484.5 MB, free: 6.047 MB / 346.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001756/0.2367, allocations: 1.946 MB / 486.4 MB, free: 3.969 MB / 346.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002331/0.239, allocations: 2.357 MB / 488.8 MB, free: 1.59 MB / 346.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.348e-06/0.239, allocations: 4 kB / 488.8 MB, free: 1.586 MB / 346.7 MB
Notification: Performance of matching and sorting (n=767) (initialization_lambda0): time 0.005133/0.2441, allocations: 4.2 MB / 493 MB, free: 13.41 MB / 362.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.722e-05/0.2441, allocations: 8 kB / 493 MB, free: 13.41 MB / 362.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.692e-05/0.2442, allocations: 12 kB / 493 MB, free: 13.39 MB / 362.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004852/0.249, allocations: 1.439 MB / 494.4 MB, free: 11.92 MB / 362.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002268/0.2513, allocations: 1.235 MB / 495.7 MB, free: 10.76 MB / 362.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00712/0.2584, allocations: 17.9 MB / 0.5015 GB, free: 7.879 MB / 378.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002251/0.2607, allocations: 423.1 kB / 0.5019 GB, free: 7.555 MB / 378.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001852/0.2608, allocations: 144 kB / 0.5021 GB, free: 7.414 MB / 378.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 44
 * Number of states: 0 ()
 * Number of discrete variables: 10 (TGOV.firstOrderLim.I.local_reset,AVR.firstOrderLim.I.local_reset,GEN12.isReferenceBus,GEN12.isSlackBus,GEN12.componentPF.isSlackBus,busGen12.componentPF.isSlackBus,bus1.isSlackBus,slackBus1.isSlackBus,loadPQ12.componentPF.isSlackBus,loadPQ11.isSlackBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (753):
 * Single equations (assignments): 737
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 6
 * Torn equation systems: 10
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 6 systems
   {1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 5 systems
   {(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): 5 systems
   {(6,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007831/0.2616, allocations: 0.5026 MB / 0.5025 GB, free: 6.98 MB / 378.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004436/0.2621, allocations: 374 kB / 0.5029 GB, free: 6.613 MB / 378.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001636/0.2637, allocations: 1.247 MB / 0.5041 GB, free: 5.375 MB / 378.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.38e-05/0.2637, allocations: 15.91 kB / 0.5041 GB, free: 5.359 MB / 378.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.176e-06/0.2637, allocations: 0 / 0.5041 GB, free: 5.359 MB / 378.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007082/0.2644, allocations: 0.5602 MB / 0.5047 GB, free: 4.801 MB / 378.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002054/0.2646, allocations: 32 kB / 0.5047 GB, free: 4.77 MB / 378.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007899/0.2654, allocations: 87.94 kB / 0.5048 GB, free: 4.684 MB / 378.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001668/0.2656, allocations: 136.7 kB / 0.5049 GB, free: 4.551 MB / 378.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001994/0.2676, allocations: 1.458 MB / 0.5064 GB, free: 3.145 MB / 378.7 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.001541/0.2691, allocations: 1.853 MB / 0.5082 GB, free: 1.309 MB / 378.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003257/0.2695, allocations: 333.8 kB / 0.5085 GB, free: 0.9922 MB / 378.7 MB
Notification: Performance of sorting global known variables: time 0.0009661/0.2704, allocations: 1.21 MB / 0.5097 GB, free: 15.91 MB / 394.7 MB
Notification: Performance of Backend: time 4.71e-07/0.2704, allocations: 0 / 0.5097 GB, free: 15.91 MB / 394.7 MB
Notification: Performance of simCode: created initialization part: time 0.008747/0.2792, allocations: 8.593 MB / 0.5181 GB, free: 7.312 MB / 394.7 MB
Notification: Performance of SimCode: time 0.006132/0.2853, allocations: 5.837 MB / 0.5238 GB, free: 1.488 MB / 394.7 MB
Notification: Performance of Templates: time 0.19/0.4753, allocations: 77.75 MB / 0.5997 GB, free: 97.94 MB / 426.7 MB
"
[Timeout remaining time 660]
make -j1 -f PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.pipe ; mkfifo PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.pipe >> ../files/PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.sim & ./PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF -abortSlowSimulation -alarm=240  -lv LOG_STATS > PowerGrids_dev_PowerGrids.Electrical.Test.TestControlledSynchronousMachine4WindingsPF.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 4.712076366879046]