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.001044/0.001044, allocations: 80.88 kB / 19.94 MB, free: 4.547 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.001059/0.001059, allocations: 181 kB / 23.36 MB, free: 1.133 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.9358/0.9358, allocations: 177.1 MB / 203.8 MB, free: 5.461 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.04912/0.04912, allocations: 13.12 MB / 273.4 MB, free: 212 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.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 1.282e-06/1.282e-06, allocations: 0 / 294.4 MB, free: 12.05 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.354e-05/1.482e-05, allocations: 2.938 kB / 294.4 MB, free: 12.05 MB / 266.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators): time 0.04593/0.04594, allocations: 76.97 MB / 371.4 MB, free: 4.062 MB / 330.7 MB
Notification: Performance of NFInst.instExpressions: time 0.03757/0.08351, allocations: 23.66 MB / 395.1 MB, free: 12.36 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01202/0.09553, allocations: 333.3 kB / 395.4 MB, free: 12.03 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.009873/0.1054, allocations: 3.125 MB / 398.5 MB, free: 8.883 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.02285/0.1282, allocations: 9.2 MB / 407.7 MB, free: 15.69 MB / 378.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01064/0.1389, allocations: 5.236 MB / 412.9 MB, free: 10.45 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.02525/0.1641, allocations: 22.52 MB / 435.5 MB, free: 3.875 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.1719/0.336, allocations: 6.259 MB / 441.7 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02382/0.3598, allocations: 10.93 MB / 452.7 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01137/0.3712, allocations: 5.643 MB / 458.3 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.003809/0.375, allocations: 1.144 MB / 459.4 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00647/0.3815, allocations: 1.969 MB / 461.4 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.00236/0.3838, allocations: 2.51 MB / 463.9 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.009389/0.3932, allocations: 4.565 MB / 468.5 MB, free: 31.02 MB / 394.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01414/0.4074, allocations: 9.712 MB / 478.2 MB, free: 30.21 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.56e-06/0.4074, allocations: 0 / 478.2 MB, free: 30.21 MB / 394.7 MB
Notification: Performance of FrontEnd: time 2.084e-06/0.4074, allocations: 0 / 478.2 MB, free: 30.21 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 0.0005363/0.4079, allocations: 0 / 478.2 MB, free: 30.21 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: 1980
 * Number of variables: 1980
Notification: Performance of Generate backend data structure: time 0.02809/0.436, allocations: 16.15 MB / 494.3 MB, free: 25.35 MB / 394.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.1e-05/0.4361, allocations: 9.594 kB / 494.4 MB, free: 25.35 MB / 394.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00763/0.4437, allocations: 3.081 MB / 497.4 MB, free: 25.18 MB / 394.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01123/0.4549, allocations: 4.515 MB / 0.4902 GB, free: 23.26 MB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003944/0.4553, allocations: 0.6237 MB / 0.4908 GB, free: 23.24 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001549/0.4569, allocations: 0.8656 MB / 0.4916 GB, free: 23.23 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007105/0.464, allocations: 8.424 MB / 0.4999 GB, free: 15.12 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001508/0.4641, allocations: 51.66 kB / 0.4999 GB, free: 15.1 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004788/0.4646, allocations: 351.4 kB / 0.5003 GB, free: 14.93 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001104/0.4647, allocations: 211.9 kB / 0.5005 GB, free: 14.73 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003443/0.4681, allocations: 3.151 MB / 0.5035 GB, free: 11.84 MB / 394.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.0401/0.5082, allocations: 41.31 MB / 0.5439 GB, free: 2.281 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004749/0.513, allocations: 3.872 MB / 0.5477 GB, free: 14.59 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001276/0.5143, allocations: 1.142 MB / 0.5488 GB, free: 13.46 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000245/0.5145, allocations: 141.5 kB / 0.5489 GB, free: 13.32 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.691e-05/0.5146, allocations: 126.5 kB / 0.549 GB, free: 13.19 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=699): time 8.546e-06/0.5146, allocations: 0 / 0.549 GB, free: 13.19 MB / 442.7 MB
Notification: Performance of matching and sorting (n=699): time 0.02737/0.542, allocations: 11.41 MB / 0.5602 GB, free: 1.934 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001264/0.5421, allocations: 360.2 kB / 0.5605 GB, free: 1.512 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007133/0.5492, allocations: 6.662 MB / 0.567 GB, free: 12.28 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.001322/0.5506, allocations: 193.7 kB / 0.5672 GB, free: 12.08 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002443/0.553, allocations: 3.704 MB / 0.5708 GB, free: 8.836 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002638/0.5556, allocations: 2.854 MB / 0.5736 GB, free: 6.203 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004233/0.5599, allocations: 3.609 MB / 0.5771 GB, free: 3.078 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 6.425e-05/0.5599, allocations: 301.1 kB / 0.5774 GB, free: 2.781 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004793/0.5647, allocations: 2.96 MB / 0.5803 GB, free: 15.82 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00744/0.5722, allocations: 6.06 MB / 0.5862 GB, free: 9.48 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01222/0.5844, allocations: 8.089 MB / 0.5941 GB, free: 1.43 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.604e-05/0.5845, allocations: 8 kB / 0.5941 GB, free: 1.422 MB / 474.7 MB
Notification: Performance of matching and sorting (n=2553) (initialization): time 0.1996/0.7841, allocations: 11.63 MB / 0.6055 GB, free: 105.7 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.234e-05/0.7842, allocations: 17.69 kB / 0.6055 GB, free: 105.7 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.89e-05/0.7842, allocations: 34.52 kB / 0.6055 GB, free: 105.7 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01253/0.7968, allocations: 4.684 MB / 0.6101 GB, free: 105.6 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01025/0.807, allocations: 4.446 MB / 0.6145 GB, free: 105.5 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02111/0.8281, allocations: 45.18 MB / 0.6586 GB, free: 68.29 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01259/0.8407, allocations: 1.622 MB / 0.6602 GB, free: 68.29 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002109/0.8428, allocations: 478.5 kB / 0.6606 GB, free: 68.29 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008547/0.8514, allocations: 3.324 MB / 0.6639 GB, free: 68.16 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006538/0.8579, allocations: 6.035 MB / 0.6698 GB, free: 65.58 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.009961/0.8679, allocations: 8.063 MB / 0.6776 GB, free: 60.49 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.229e-05/0.8679, allocations: 8 kB / 0.6776 GB, free: 60.48 MB / 474.7 MB
Notification: Performance of matching and sorting (n=2553) (initialization_lambda0): time 0.01766/0.8856, allocations: 11.61 MB / 0.689 GB, free: 52.07 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.034e-05/0.8857, allocations: 20 kB / 0.689 GB, free: 52.05 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.273e-05/0.8857, allocations: 32.66 kB / 0.689 GB, free: 52.02 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01236/0.8981, allocations: 4.687 MB / 0.6936 GB, free: 47.59 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009782/0.9079, allocations: 3.861 MB / 0.6974 GB, free: 44.11 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01551/0.9234, allocations: 30.29 MB / 0.727 GB, free: 14.86 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01148/0.9348, allocations: 1.345 MB / 0.7283 GB, free: 14.48 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001679/0.9365, allocations: 472.3 kB / 0.7287 GB, free: 14.17 MB / 474.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 152
 * Number of states: 0 ()
 * Number of discrete variables: 26 (GEN1.TGOV.firstOrderLim.I.local_reset,GEN1.AVR.firstOrderLim.I.local_reset,GEN1.PSS.firstWashoutVsi1.integrator.local_reset,GEN1.PSS.secondWashoutVsi1.integrator.local_reset,GEN1.PSS.firstWashoutVsi2.integrator.local_reset,GEN2.TGOV.firstOrderLim.I.local_reset,GEN2.AVR.firstOrderLim.I.local_reset,GEN2.PSS.firstWashoutVsi1.integrator.local_reset,GEN2.PSS.secondWashoutVsi1.integrator.local_reset,GEN2.PSS.firstWashoutVsi2.integrator.local_reset,NTLV1.componentPF.isSlackBus,NTHV1.componentPF.isSlackBus,GEN1.isReferenceBus,GEN1.isSlackBus,GEN1.GEN.isReferenceBus,GEN1.GEN.isSlackBus,GEN1.GEN.componentPF.isSlackBus,GEN2.isReferenceBus,GEN2.isSlackBus,GEN2.GEN.isReferenceBus,GEN2.GEN.isSlackBus,GEN2.GEN.componentPF.isSlackBus,NTHV2.componentPF.isSlackBus,GRIDL1.componentPF.isSlackBus,GRIDL2.componentPF.isSlackBus,NTLV2.componentPF.isSlackBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2409):
 * Single equations (assignments): 2377
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 12
 * Torn equation systems: 20
 * 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): 12 systems
   {1, 1, 1, 1, 1, 1, 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): 8 systems
   {(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): 12 systems
   {(6,0), (6,0), (54,0), (62,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.002249/0.9388, allocations: 1.018 MB / 0.7297 GB, free: 13.82 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001824/0.9406, allocations: 0.822 MB / 0.7305 GB, free: 13.09 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005174/0.9458, allocations: 4.053 MB / 0.7345 GB, free: 9.715 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.447e-05/0.9458, allocations: 35.88 kB / 0.7345 GB, free: 9.68 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.052e-05/0.9458, allocations: 4 kB / 0.7345 GB, free: 9.676 MB / 474.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.003253/0.9491, allocations: 1.025 MB / 0.7355 GB, free: 9.301 MB / 474.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001187/0.9503, allocations: 127.1 kB / 0.7356 GB, free: 9.27 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002829/0.9531, allocations: 249.4 kB / 0.7359 GB, free: 9.117 MB / 474.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005861/0.9537, allocations: 0.5478 MB / 0.7364 GB, free: 8.617 MB / 474.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005353/0.959, allocations: 3.728 MB / 0.7401 GB, free: 5.348 MB / 474.7 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.00442/0.9634, allocations: 4.275 MB / 0.7442 GB, free: 1.473 MB / 474.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001023/0.9645, allocations: 1.155 MB / 0.7454 GB, free: 0.5469 MB / 474.7 MB
Notification: Performance of sorting global known variables: time 0.003944/0.9684, allocations: 3.262 MB / 0.7485 GB, free: 13.86 MB / 490.7 MB
Notification: Performance of Backend: time 4.51e-07/0.9684, allocations: 0 / 0.7485 GB, free: 13.86 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.2102/1.179, allocations: 23.72 MB / 0.7717 GB, free: 104.1 MB / 490.7 MB
Notification: Performance of SimCode: time 0.03039/1.209, allocations: 18.35 MB / 0.7896 GB, free: 96.64 MB / 490.7 MB
Notification: Performance of Templates: time 0.536/1.745, allocations: 271.3 MB / 1.055 GB, free: 58.62 MB / 0.5105 GB
"
[Timeout remaining time 658]
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: 11.256716893054545]