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.001318/0.001318, allocations: 80.94 kB / 19.94 MB, free: 4.543 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.001433/0.001433, allocations: 177 kB / 23.36 MB, free: 1.129 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.9428/0.9428, allocations: 177.2 MB / 203.8 MB, free: 5.484 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.05203/0.05203, allocations: 13.12 MB / 273.4 MB, free: 236 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.113e-06/1.113e-06, allocations: 0 / 294.5 MB, free: 11.9 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.104e-05/1.215e-05, allocations: 7.094 kB / 294.5 MB, free: 11.89 MB / 266.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators): time 0.04585/0.04586, allocations: 77.02 MB / 371.5 MB, free: 3.906 MB / 330.7 MB
Notification: Performance of NFInst.instExpressions: time 0.03423/0.08009, allocations: 23.65 MB / 395.1 MB, free: 12.2 MB / 362.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008599/0.08869, allocations: 333.2 kB / 395.5 MB, free: 11.87 MB / 362.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.006149/0.09484, allocations: 3.129 MB / 398.6 MB, free: 8.719 MB / 362.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01716/0.112, allocations: 9.184 MB / 407.8 MB, free: 15.54 MB / 378.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00746/0.1195, allocations: 5.221 MB / 413 MB, free: 10.32 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.01993/0.1394, allocations: 22.52 MB / 435.5 MB, free: 3.738 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.141/0.2804, allocations: 6.255 MB / 441.8 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01534/0.2957, allocations: 10.93 MB / 452.7 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.007183/0.3029, allocations: 5.639 MB / 458.4 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.002111/0.305, allocations: 1.144 MB / 459.5 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003475/0.3085, allocations: 1.994 MB / 461.5 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001149/0.3096, allocations: 2.504 MB / 464 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.00491/0.3146, allocations: 4.567 MB / 468.6 MB, free: 30.95 MB / 394.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.009414/0.324, allocations: 9.714 MB / 478.3 MB, free: 30.13 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.411e-06/0.324, allocations: 2.406 kB / 478.3 MB, free: 30.13 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.122e-06/0.324, allocations: 0 / 478.3 MB, free: 30.13 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 0.000337/0.3243, allocations: 3.594 kB / 478.3 MB, free: 30.13 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.02398/0.3483, allocations: 16.14 MB / 494.4 MB, free: 25.28 MB / 394.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.757e-05/0.3483, allocations: 14.06 kB / 494.4 MB, free: 25.28 MB / 394.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006807/0.3551, allocations: 3.087 MB / 497.5 MB, free: 25.02 MB / 394.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005611/0.3607, allocations: 4.506 MB / 0.4903 GB, free: 23.11 MB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002192/0.361, allocations: 0.6243 MB / 0.4909 GB, free: 23.1 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006108/0.3616, allocations: 0.8731 MB / 0.4917 GB, free: 23.09 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004695/0.3663, allocations: 8.426 MB / 0.5 GB, free: 14.89 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001064/0.3664, allocations: 45.06 kB / 0.5 GB, free: 14.87 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003713/0.3668, allocations: 353.6 kB / 0.5003 GB, free: 14.77 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.819e-05/0.3668, allocations: 207.9 kB / 0.5005 GB, free: 14.57 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002985/0.3698, allocations: 3.164 MB / 0.5036 GB, free: 11.75 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.02779/0.3976, allocations: 41.31 MB / 0.544 GB, free: 2.195 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004177/0.4018, allocations: 3.885 MB / 0.5478 GB, free: 14.48 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00123/0.403, allocations: 1.134 MB / 0.5489 GB, free: 13.37 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002385/0.4032, allocations: 152 kB / 0.549 GB, free: 13.22 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.509e-05/0.4033, allocations: 120.1 kB / 0.5491 GB, free: 13.09 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=699): time 8.226e-06/0.4033, allocations: 0 / 0.5491 GB, free: 13.09 MB / 442.7 MB
Notification: Performance of matching and sorting (n=699): time 0.02605/0.4294, allocations: 11.4 MB / 0.5603 GB, free: 1.844 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000119/0.4295, allocations: 360.2 kB / 0.5606 GB, free: 1.422 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005385/0.4349, allocations: 6.664 MB / 0.5671 GB, free: 12.18 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003892/0.4353, allocations: 185.7 kB / 0.5673 GB, free: 12 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001832/0.4371, allocations: 3.713 MB / 0.5709 GB, free: 8.742 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002048/0.4391, allocations: 2.848 MB / 0.5737 GB, free: 6.109 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003273/0.4424, allocations: 3.609 MB / 0.5772 GB, free: 2.984 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 6.085e-05/0.4425, allocations: 301.1 kB / 0.5775 GB, free: 2.688 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004418/0.4469, allocations: 2.96 MB / 0.5804 GB, free: 15.73 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006081/0.453, allocations: 6.054 MB / 0.5863 GB, free: 9.395 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00935/0.4623, allocations: 8.087 MB / 0.5942 GB, free: 1.344 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.909e-05/0.4624, allocations: 12 kB / 0.5942 GB, free: 1.332 MB / 474.7 MB
Notification: Performance of matching and sorting (n=2553) (initialization): time 0.1598/0.6221, allocations: 11.64 MB / 0.6056 GB, free: 105.2 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.768e-05/0.6222, allocations: 18.75 kB / 0.6056 GB, free: 105.2 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.73e-05/0.6223, allocations: 34.75 kB / 0.6056 GB, free: 105.2 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01325/0.6355, allocations: 4.679 MB / 0.6102 GB, free: 105 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01129/0.6468, allocations: 4.952 MB / 0.615 GB, free: 104.9 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02035/0.6672, allocations: 45.21 MB / 0.6592 GB, free: 67.68 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009447/0.6766, allocations: 1.619 MB / 0.6608 GB, free: 67.68 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009705/0.6776, allocations: 476.4 kB / 0.6612 GB, free: 67.68 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.006622/0.6842, allocations: 3.327 MB / 0.6645 GB, free: 67.55 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004984/0.6892, allocations: 6.032 MB / 0.6704 GB, free: 64.97 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.007543/0.6967, allocations: 8.065 MB / 0.6782 GB, free: 59.53 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.983e-05/0.6968, allocations: 8 kB / 0.6782 GB, free: 59.52 MB / 474.7 MB
Notification: Performance of matching and sorting (n=2553) (initialization_lambda0): time 0.01387/0.7106, allocations: 11.61 MB / 0.6896 GB, free: 51.08 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.312e-05/0.7107, allocations: 22.66 kB / 0.6896 GB, free: 51.06 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.905e-05/0.7107, allocations: 32 kB / 0.6896 GB, free: 51.03 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01226/0.723, allocations: 4.68 MB / 0.6942 GB, free: 46.48 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007777/0.7308, allocations: 4.366 MB / 0.6985 GB, free: 42.5 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01426/0.745, allocations: 30.32 MB / 0.7281 GB, free: 13.23 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007492/0.7525, allocations: 1.348 MB / 0.7294 GB, free: 12.84 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005735/0.7531, allocations: 469.8 kB / 0.7298 GB, free: 12.54 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.001617/0.7547, allocations: 1.01 MB / 0.7308 GB, free: 12.19 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001123/0.7558, allocations: 0.8139 MB / 0.7316 GB, free: 11.46 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004993/0.7608, allocations: 4.056 MB / 0.7356 GB, free: 8.09 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.427e-05/0.7608, allocations: 35.88 kB / 0.7356 GB, free: 8.055 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.647e-06/0.7609, allocations: 3.938 kB / 0.7356 GB, free: 8.051 MB / 474.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.002179/0.763, allocations: 1.027 MB / 0.7366 GB, free: 7.672 MB / 474.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007071/0.7637, allocations: 123.2 kB / 0.7367 GB, free: 7.645 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002327/0.7661, allocations: 248 kB / 0.737 GB, free: 7.492 MB / 474.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005231/0.7666, allocations: 0.5481 MB / 0.7375 GB, free: 6.992 MB / 474.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005201/0.7718, allocations: 3.728 MB / 0.7412 GB, free: 3.719 MB / 474.7 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.004142/0.7759, allocations: 4.28 MB / 0.7453 GB, free: 15.84 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00116/0.7771, allocations: 1.153 MB / 0.7465 GB, free: 14.92 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.003258/0.7804, allocations: 3.257 MB / 0.7496 GB, free: 12.23 MB / 490.7 MB
Notification: Performance of Backend: time 3.61e-07/0.7804, allocations: 4 kB / 0.7496 GB, free: 12.23 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.1674/0.9478, allocations: 23.73 MB / 0.7728 GB, free: 104.7 MB / 490.7 MB
Notification: Performance of SimCode: time 0.02114/0.9689, allocations: 18.34 MB / 0.7907 GB, free: 97.22 MB / 490.7 MB
Notification: Performance of Templates: time 0.4089/1.378, allocations: 271.3 MB / 1.056 GB, free: 56.01 MB / 0.5105 GB
"
[Timeout remaining time 659]
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 -s gbode -gbm=radauIIA3 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 10.141507154796273]