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.0009437/0.0009437, allocations: 93.06 kB / 19.65 MB, free: 280 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.001062/0.001062, allocations: 165 kB / 23.02 MB, free: 1.551 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.9523/0.9523, allocations: 177.1 MB / 203.4 MB, free: 5.633 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.0507/0.0507, allocations: 12.94 MB / 272.9 MB, free: 0.4961 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 1.904e-06/1.904e-06, allocations: 0 / 347 MB, free: 2.68 MB / 314.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.879e-05/3.07e-05, allocations: 6.25 kB / 347 MB, free: 2.672 MB / 314.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGenerators): time 0.05353/0.05356, allocations: 79.06 MB / 426.1 MB, free: 3.312 MB / 394.7 MB
Notification: Performance of NFInst.instExpressions: time 0.1863/0.2399, allocations: 23 MB / 449.1 MB, free: 62.29 MB / 410.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0103/0.2502, allocations: 334 kB / 449.4 MB, free: 62.29 MB / 410.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.009316/0.2595, allocations: 3.011 MB / 452.4 MB, free: 62.22 MB / 410.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.02178/0.2813, allocations: 9.055 MB / 461.5 MB, free: 61.1 MB / 410.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.009848/0.2911, allocations: 5.231 MB / 466.7 MB, free: 60.98 MB / 410.7 MB
Notification: Performance of NFFlatten.flatten: time 0.02295/0.3141, allocations: 22.44 MB / 489.1 MB, free: 50.45 MB / 410.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01098/0.325, allocations: 6.099 MB / 495.2 MB, free: 46.93 MB / 410.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0224/0.3474, allocations: 10.59 MB / 0.494 GB, free: 41.64 MB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01066/0.3581, allocations: 5.897 MB / 0.4997 GB, free: 39.59 MB / 410.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.004092/0.3622, allocations: 1.191 MB / 0.5009 GB, free: 39.59 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.008316/0.3705, allocations: 2.111 MB / 0.5029 GB, free: 39.46 MB / 410.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.003315/0.3738, allocations: 2.587 MB / 0.5055 GB, free: 37.68 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.01322/0.387, allocations: 4.799 MB / 0.5102 GB, free: 35.37 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01623/0.4033, allocations: 9.873 MB / 0.5198 GB, free: 29.96 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 7.193e-06/0.4033, allocations: 0 / 0.5198 GB, free: 29.96 MB / 410.7 MB
Notification: Performance of FrontEnd: time 2.024e-06/0.4033, allocations: 2.594 kB / 0.5198 GB, free: 29.96 MB / 410.7 MB
Notification: Performance of Transformations before backend: time 0.0004863/0.4038, allocations: 0 / 0.5198 GB, free: 29.96 MB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1982
 * Number of variables: 1982
Notification: Performance of Generate backend data structure: time 0.02716/0.4309, allocations: 16.24 MB / 0.5357 GB, free: 15.88 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.603e-05/0.431, allocations: 8.031 kB / 0.5357 GB, free: 15.87 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007757/0.4387, allocations: 3.262 MB / 0.5389 GB, free: 12.62 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01168/0.4504, allocations: 7.81 MB / 0.5465 GB, free: 5.074 MB / 410.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003425/0.4507, allocations: 0.6249 MB / 0.5471 GB, free: 4.449 MB / 410.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00106/0.4518, allocations: 0.8558 MB / 0.5479 GB, free: 3.594 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01036/0.4622, allocations: 9.738 MB / 0.5574 GB, free: 8.254 MB / 426.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001442/0.4623, allocations: 49.8 kB / 0.5575 GB, free: 8.234 MB / 426.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007515/0.4631, allocations: 352.4 kB / 0.5578 GB, free: 7.891 MB / 426.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001218/0.4632, allocations: 207.9 kB / 0.558 GB, free: 7.688 MB / 426.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007686/0.4709, allocations: 4.277 MB / 0.5622 GB, free: 3.648 MB / 426.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.2356/0.7065, allocations: 60.96 MB / 0.6217 GB, free: 46.32 MB / 458.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006501/0.713, allocations: 4.859 MB / 0.6265 GB, free: 45.97 MB / 458.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002215/0.7152, allocations: 1.543 MB / 0.628 GB, free: 45.91 MB / 458.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002585/0.7154, allocations: 146.1 kB / 0.6281 GB, free: 45.9 MB / 458.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.469e-05/0.7155, allocations: 126.1 kB / 0.6282 GB, free: 45.85 MB / 458.7 MB
Notification: Performance of pre-optimization done (n=701): time 9.338e-06/0.7155, allocations: 0 / 0.6282 GB, free: 45.85 MB / 458.7 MB
Notification: Performance of matching and sorting (n=701): time 0.03098/0.7465, allocations: 13.49 MB / 0.6414 GB, free: 45.23 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.336e-05/0.7466, allocations: 360 kB / 0.6418 GB, free: 44.88 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008463/0.7551, allocations: 7.223 MB / 0.6488 GB, free: 44.29 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.00111/0.7562, allocations: 188.7 kB / 0.649 GB, free: 44.26 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002603/0.7588, allocations: 4.729 MB / 0.6536 GB, free: 43.1 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.00255/0.7613, allocations: 2.888 MB / 0.6564 GB, free: 41.54 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004801/0.7661, allocations: 3.718 MB / 0.6601 GB, free: 41.27 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 3.495e-05/0.7662, allocations: 480.7 kB / 0.6605 GB, free: 40.8 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004939/0.7711, allocations: 3.398 MB / 0.6638 GB, free: 40.65 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006619/0.7777, allocations: 6.743 MB / 0.6704 GB, free: 37.68 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009868/0.7876, allocations: 8.852 MB / 0.6791 GB, free: 34.81 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.947e-05/0.7877, allocations: 10.28 kB / 0.6791 GB, free: 34.81 MB / 458.7 MB
Notification: Performance of matching and sorting (n=2557) (initialization): time 0.02354/0.8112, allocations: 14.85 MB / 0.6936 GB, free: 29.52 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.288e-05/0.8112, allocations: 20 kB / 0.6936 GB, free: 29.5 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.673e-05/0.8113, allocations: 34.66 kB / 0.6936 GB, free: 29.47 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0142/0.8255, allocations: 4.829 MB / 0.6984 GB, free: 25.94 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01045/0.836, allocations: 4.481 MB / 0.7027 GB, free: 22.32 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03386/0.8698, allocations: 65.16 MB / 0.7664 GB, free: 1.891 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01071/0.8805, allocations: 1.327 MB / 0.7677 GB, free: 0.5547 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002061/0.8826, allocations: 0.6367 MB / 0.7683 GB, free: 15.91 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008998/0.8916, allocations: 3.773 MB / 0.772 GB, free: 12.14 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009002/0.9006, allocations: 6.715 MB / 0.7785 GB, free: 4.613 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.1877/1.088, allocations: 8.82 MB / 0.7871 GB, free: 128.4 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001307/1.088, allocations: 10.69 kB / 0.7871 GB, free: 128.4 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=2557) (initialization_lambda0): time 0.02897/1.117, allocations: 14.8 MB / 0.8016 GB, free: 127.7 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 4.9e-05/1.117, allocations: 19.59 kB / 0.8016 GB, free: 127.7 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.236e-05/1.118, allocations: 40.16 kB / 0.8017 GB, free: 127.7 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01586/1.133, allocations: 4.836 MB / 0.8064 GB, free: 127.5 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01039/1.144, allocations: 3.883 MB / 0.8102 GB, free: 127.5 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02282/1.167, allocations: 43 MB / 0.8522 GB, free: 92.88 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01197/1.179, allocations: 1.088 MB / 0.8532 GB, free: 92.87 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002371/1.181, allocations: 0.6351 MB / 0.8538 GB, free: 92.87 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 152
 * Number of states: 0 ()
 * Number of discrete variables: 26 (NTLV2.componentPF.isSlackBus,GRIDL2.componentPF.isSlackBus,GRIDL1.componentPF.isSlackBus,NTHV2.componentPF.isSlackBus,GEN2.GEN.componentPF.isSlackBus,GEN2.GEN.isSlackBus,GEN2.GEN.isReferenceBus,GEN2.isSlackBus,GEN2.isReferenceBus,GEN1.GEN.componentPF.isSlackBus,GEN1.GEN.isSlackBus,GEN1.GEN.isReferenceBus,GEN1.isSlackBus,GEN1.isReferenceBus,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 (2411):
 * Single equations (assignments): 2379
 * 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), (56,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.00284/1.184, allocations: 1.083 MB / 0.8549 GB, free: 92.6 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002691/1.186, allocations: 1.209 MB / 0.8561 GB, free: 92.6 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008271/1.195, allocations: 5.315 MB / 0.8613 GB, free: 92.17 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.187e-05/1.195, allocations: 34.81 kB / 0.8613 GB, free: 92.17 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.167e-06/1.195, allocations: 0 / 0.8613 GB, free: 92.17 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005481/1.2, allocations: 1.927 MB / 0.8632 GB, free: 92.17 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002451/1.203, allocations: 123.9 kB / 0.8633 GB, free: 92.17 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005269/1.208, allocations: 250 kB / 0.8635 GB, free: 92.16 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009639/1.209, allocations: 0.5579 MB / 0.8641 GB, free: 92.16 MB / 0.5105 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009658/1.219, allocations: 4.934 MB / 0.8689 GB, free: 91.63 MB / 0.5105 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.009678/1.228, allocations: 6.024 MB / 0.8748 GB, free: 90.25 MB / 0.5105 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002234/1.231, allocations: 1.391 MB / 0.8762 GB, free: 90.14 MB / 0.5105 GB
Notification: Performance of sorting global known variables: time 0.005765/1.236, allocations: 3.787 MB / 0.8799 GB, free: 89.54 MB / 0.5105 GB
Notification: Performance of Backend: time 8.42e-07/1.236, allocations: 3.562 kB / 0.8799 GB, free: 89.54 MB / 0.5105 GB
Notification: Performance of simCode: created initialization part: time 0.04142/1.278, allocations: 27.11 MB / 0.9063 GB, free: 66.69 MB / 0.5105 GB
Notification: Performance of SimCode: time 0.02679/1.305, allocations: 17.99 MB / 0.9239 GB, free: 49.75 MB / 0.5105 GB
Notification: Performance of Templates: time 0.5645/1.869, allocations: 252 MB / 1.17 GB, free: 103.7 MB / 0.5261 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: 12.069560163654387]