Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.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.001887/0.001887, allocations: 85.48 kB / 19.44 MB, free: 344 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.001933/0.001933, allocations: 173.2 kB / 22.76 MB, free: 1.664 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 1.592/1.592, allocations: 177.1 MB / 203.1 MB, free: 5.633 MB / 186.7 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.09119/0.09119, allocations: 12.74 MB / 272.3 MB, free: 0.7617 MB / 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.Examples.Tutorial.IslandOperation.TwoGenerators,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators")
translateModel(PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 2.284e-06/2.284e-06, allocations: 4 kB / 346 MB, free: 2.27 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.754e-05/1.983e-05, allocations: 2.312 kB / 346 MB, free: 2.266 MB / 266.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:4:3-5:21:writable] Warning: Parameter GEN1.componentPF.UNom has no value, and is fixed during initialization (fixed=true), using available start value (start=400e3) as default value.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:6:3-7:21:writable] Warning: Parameter GEN1.componentPF.SNom has no value, and is fixed during initialization (fixed=true), using available start value (start=100e6) as default value.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:4:3-5:21:writable] Warning: Parameter GEN2.componentPF.UNom has no value, and is fixed during initialization (fixed=true), using available start value (start=400e3) as default value.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:6:3-7:21:writable] Warning: Parameter GEN2.componentPF.SNom has no value, and is fixed during initialization (fixed=true), using available start value (start=100e6) as default value.
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators): time 0.2293/0.2293, allocations: 76.85 MB / 422.9 MB, free: 28.59 MB / 330.7 MB
Notification: Performance of NFInst.instExpressions: time 0.0356/0.2649, allocations: 23.11 MB / 446 MB, free: 22.89 MB / 330.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01213/0.277, allocations: 334.5 kB / 446.3 MB, free: 22.72 MB / 330.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.009799/0.2868, allocations: 3.02 MB / 449.3 MB, free: 20.66 MB / 330.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.02408/0.3109, allocations: 9.117 MB / 458.4 MB, free: 14.11 MB / 330.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01208/0.323, allocations: 5.296 MB / 463.7 MB, free: 10.76 MB / 330.7 MB
Notification: Performance of NFFlatten.flatten: time 0.02709/0.3501, allocations: 22.69 MB / 486.4 MB, free: 7.438 MB / 346.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01407/0.3642, allocations: 6.176 MB / 492.6 MB, free: 1.398 MB / 346.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0269/0.3911, allocations: 10.72 MB / 0.4915 GB, free: 6.855 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01454/0.4056, allocations: 5.961 MB / 0.4973 GB, free: 1.172 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.004647/0.4102, allocations: 1.211 MB / 0.4985 GB, free: 15.96 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.009015/0.4193, allocations: 2.136 MB / 0.5006 GB, free: 13.84 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.004706/0.424, allocations: 2.627 MB / 0.5032 GB, free: 11.36 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.01347/0.4374, allocations: 4.86 MB / 0.5079 GB, free: 6.648 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.03197/0.4694, allocations: 18.18 MB / 0.5257 GB, free: 4.871 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.42e-06/0.4694, allocations: 0 / 0.5257 GB, free: 4.871 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.182e-06/0.4694, allocations: 0 / 0.5257 GB, free: 4.871 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 0.000565/0.47, allocations: 4 kB / 0.5257 GB, free: 4.867 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: 2006
 * Number of variables: 2006
Notification: Performance of Generate backend data structure: time 0.06481/0.5348, allocations: 17.58 MB / 0.5428 GB, free: 3.172 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 6.029e-05/0.5348, allocations: 8.031 kB / 0.5429 GB, free: 3.164 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01811/0.553, allocations: 3.28 MB / 0.5461 GB, free: 15.88 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02392/0.5769, allocations: 8.361 MB / 0.5542 GB, free: 7.418 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001035/0.5779, allocations: 0.629 MB / 0.5548 GB, free: 6.789 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003118/0.581, allocations: 0.8675 MB / 0.5557 GB, free: 5.922 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2744/0.8554, allocations: 12.41 MB / 0.5678 GB, free: 134.5 MB / 426.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002173/0.8557, allocations: 53.09 kB / 0.5679 GB, free: 134.5 MB / 426.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0009918/0.8567, allocations: 351.1 kB / 0.5682 GB, free: 134.5 MB / 426.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001887/0.8568, allocations: 207.6 kB / 0.5684 GB, free: 134.5 MB / 426.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01456/0.8714, allocations: 7.352 MB / 0.5756 GB, free: 132.5 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.06986/0.9413, allocations: 68.96 MB / 0.6429 GB, free: 72.29 MB / 426.8 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01151/0.9528, allocations: 6.978 MB / 0.6497 GB, free: 65.47 MB / 426.8 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004693/0.9575, allocations: 2.778 MB / 0.6524 GB, free: 62.67 MB / 426.8 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004479/0.9579, allocations: 152.6 kB / 0.6526 GB, free: 62.52 MB / 426.8 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.058e-05/0.958, allocations: 131.8 kB / 0.6527 GB, free: 62.38 MB / 426.8 MB
Notification: Performance of pre-optimization done (n=717): time 1.198e-05/0.958, allocations: 0 / 0.6527 GB, free: 62.38 MB / 426.8 MB
Notification: Performance of matching and sorting (n=717): time 0.05242/1.01, allocations: 16.05 MB / 0.6684 GB, free: 46.37 MB / 426.8 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001028/1.011, allocations: 364.4 kB / 0.6687 GB, free: 45.95 MB / 426.8 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01408/1.025, allocations: 10.94 MB / 0.6794 GB, free: 35.25 MB / 426.8 MB
Notification: Performance of collectPreVariables (initialization): time 0.001515/1.026, allocations: 189.7 kB / 0.6796 GB, free: 35.06 MB / 426.8 MB
Notification: Performance of collectInitialEqns (initialization): time 0.006044/1.032, allocations: 5.53 MB / 0.685 GB, free: 30.04 MB / 426.8 MB
Notification: Performance of collectInitialBindings (initialization): time 0.004433/1.037, allocations: 3.37 MB / 0.6883 GB, free: 27.08 MB / 426.8 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01118/1.048, allocations: 4.799 MB / 0.693 GB, free: 22.26 MB / 426.8 MB
Notification: Performance of setup shared object (initialization): time 0.0001625/1.048, allocations: 480.8 kB / 0.6934 GB, free: 21.79 MB / 426.8 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0112/1.059, allocations: 5.405 MB / 0.6987 GB, free: 16.36 MB / 426.8 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0168/1.076, allocations: 9.518 MB / 0.708 GB, free: 6.066 MB / 426.8 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.03073/1.107, allocations: 14.2 MB / 0.7219 GB, free: 7.297 MB / 442.8 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001657/1.107, allocations: 8 kB / 0.7219 GB, free: 7.289 MB / 442.8 MB
Notification: Performance of matching and sorting (n=2581) (initialization): time 0.2454/1.352, allocations: 20.16 MB / 0.7416 GB, free: 130.1 MB / 442.8 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001367/1.352, allocations: 16.66 kB / 0.7416 GB, free: 130.1 MB / 442.8 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001189/1.352, allocations: 31.45 kB / 0.7416 GB, free: 130.1 MB / 442.8 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.03906/1.392, allocations: 6.425 MB / 0.7479 GB, free: 129.8 MB / 442.8 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01571/1.407, allocations: 3.79 MB / 0.7516 GB, free: 129.5 MB / 442.8 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0592/1.466, allocations: 64 MB / 0.8141 GB, free: 75.27 MB / 442.8 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01723/1.484, allocations: 1.333 MB / 0.8154 GB, free: 74.54 MB / 442.8 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002686/1.486, allocations: 0.6288 MB / 0.816 GB, free: 73.91 MB / 442.8 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01414/1.5, allocations: 6.728 MB / 0.8226 GB, free: 67.17 MB / 442.8 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01177/1.512, allocations: 9.323 MB / 0.8317 GB, free: 57.07 MB / 442.8 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0187/1.531, allocations: 13.95 MB / 0.8453 GB, free: 42.55 MB / 442.8 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001127/1.531, allocations: 15.98 kB / 0.8453 GB, free: 42.54 MB / 442.8 MB
Notification: Performance of matching and sorting (n=2581) (initialization_lambda0): time 0.04738/1.578, allocations: 18.86 MB / 0.8637 GB, free: 23.81 MB / 442.8 MB
Notification: Performance of prepare postOptimizeDAE: time 6.163e-05/1.579, allocations: 19.94 kB / 0.8638 GB, free: 23.79 MB / 442.8 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.965e-05/1.579, allocations: 28 kB / 0.8638 GB, free: 23.76 MB / 442.8 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.03141/1.61, allocations: 5.037 MB / 0.8687 GB, free: 18.63 MB / 442.8 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01677/1.627, allocations: 3.936 MB / 0.8726 GB, free: 14.69 MB / 442.8 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.2352/1.862, allocations: 53.57 MB / 0.9249 GB, free: 109.2 MB / 458.8 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01831/1.88, allocations: 1.091 MB / 0.9259 GB, free: 109.2 MB / 458.8 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003102/1.883, allocations: 0.6312 MB / 0.9266 GB, free: 109.2 MB / 458.8 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 128
 * Number of states: 0 ()
 * Number of discrete variables: 20 (NTLV2.componentPF.isSlackBus,GRIDL2.componentPF.isSlackBus,GRIDL1.componentPF.isSlackBus,NTHV2.componentPF.isSlackBus,GEN2.GEN.componentPF.isSlackBus,GEN2.isSlackBus,GEN1.GEN.componentPF.isSlackBus,GEN1.isSlackBus,NTHV1.componentPF.isSlackBus,NTLV1.componentPF.isSlackBus,GEN2.PSS.firstWashoutVsi2.integrator.local_reset,GEN2.PSS.secondWashoutVsi1.integrator.local_reset,GEN2.PSS.firstWashoutVsi1.integrator.local_reset,GEN2.AVR.firstOrderLim.I.local_reset,GEN2.TGOV.firstOrderLim.I.local_reset,GEN1.PSS.firstWashoutVsi2.integrator.local_reset,GEN1.PSS.secondWashoutVsi1.integrator.local_reset,GEN1.PSS.firstWashoutVsi1.integrator.local_reset,GEN1.AVR.firstOrderLim.I.local_reset,GEN1.TGOV.firstOrderLim.I.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2388):
 * Single equations (assignments): 2364
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 24
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 10 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%), (3,0,66.7%), (3,0,66.7%), (3,0,66.7%), (3,0,66.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 14 systems
   {(38,0), (10,0), (19,0), (60,0), (10,0), (40,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.004046/1.887, allocations: 1.084 MB / 0.9276 GB, free: 108.9 MB / 458.8 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00376/1.891, allocations: 1.225 MB / 0.9288 GB, free: 108.9 MB / 458.8 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01537/1.907, allocations: 7.65 MB / 0.9363 GB, free: 108.5 MB / 458.8 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.375e-05/1.907, allocations: 34.73 kB / 0.9363 GB, free: 108.5 MB / 458.8 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.269e-05/1.907, allocations: 3.656 kB / 0.9363 GB, free: 108.5 MB / 458.8 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.006855/1.913, allocations: 1.956 MB / 0.9382 GB, free: 108.4 MB / 458.8 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002903/1.916, allocations: 128 kB / 0.9383 GB, free: 108.4 MB / 458.8 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008042/1.924, allocations: 253.5 kB / 0.9386 GB, free: 108.4 MB / 458.8 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001243/1.926, allocations: 0.5664 MB / 0.9391 GB, free: 108.4 MB / 458.8 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01833/1.944, allocations: 7.949 MB / 0.9469 GB, free: 107.4 MB / 458.8 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01644/1.96, allocations: 9.99 MB / 0.9567 GB, free: 102.9 MB / 458.8 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004133/1.965, allocations: 2.794 MB / 0.9594 GB, free: 101.7 MB / 458.8 MB
Notification: Performance of sorting global known variables: time 0.01039/1.975, allocations: 5.789 MB / 0.965 GB, free: 98.13 MB / 458.8 MB
Notification: Performance of Backend: time 5.51e-07/1.975, allocations: 0 / 0.965 GB, free: 98.13 MB / 458.8 MB
Notification: Performance of simCode: created initialization part: time 0.06786/2.043, allocations: 34.14 MB / 0.9984 GB, free: 64.59 MB / 458.8 MB
Notification: Performance of SimCode: time 0.03475/2.078, allocations: 19.35 MB / 1.017 GB, free: 45.3 MB / 458.8 MB
Notification: Performance of Templates: time 0.6732/2.751, allocations: 260.9 MB / 1.272 GB, free: 116.7 MB / 490.8 MB
"
[Timeout remaining time 657]
make -j1 -f PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.pipe ; mkfifo PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.pipe >> ../files/PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.sim & ./PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators -abortSlowSimulation -alarm=240  -lv LOG_STATS > PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 18.37643772305455]