Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.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.001505/0.001505, allocations: 80.3 kB / 19.18 MB, free: 2.516 MB / 14.72 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.001621/0.001621, allocations: 159.1 kB / 22.45 MB, free: 5.375 MB / 14.72 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.36/1.36, allocations: 177.2 MB / 202.8 MB, free: 9.16 MB / 190.1 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.07476/0.07476, allocations: 12.52 MB / 271.7 MB, free: 4.574 MB / 254.1 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.TwoGeneratorsLocalInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization")
translateModel(PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.212e-06/1.212e-06, allocations: 0 / 402.8 MB, free: 14.87 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.423e-05/1.544e-05, allocations: 2.281 kB / 402.8 MB, free: 14.87 MB / 334.1 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.TwoGeneratorsLocalInitialization): time 0.2209/0.2209, allocations: 81.19 MB / 484 MB, free: 14.69 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03071/0.2516, allocations: 22.53 MB / 0.4947 GB, free: 13.77 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00923/0.2608, allocations: 328.8 kB / 0.495 GB, free: 13.6 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.008324/0.2692, allocations: 2.975 MB / 0.4979 GB, free: 11.14 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.02391/0.2931, allocations: 8.985 MB / 0.5067 GB, free: 2.562 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01277/0.3059, allocations: 5.354 MB / 0.5119 GB, free: 13.29 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02916/0.335, allocations: 22.32 MB / 0.5337 GB, free: 6.914 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01173/0.3467, allocations: 6.086 MB / 0.5396 GB, free: 0.7383 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02174/0.3685, allocations: 10.52 MB / 0.5499 GB, free: 6.199 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01141/0.3799, allocations: 5.799 MB / 0.5556 GB, free: 392 kB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003372/0.3833, allocations: 1.203 MB / 0.5568 GB, free: 15.18 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.007085/0.3904, allocations: 2.136 MB / 0.5588 GB, free: 13.04 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003632/0.394, allocations: 2.591 MB / 0.5614 GB, free: 10.44 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.009966/0.404, allocations: 4.787 MB / 0.566 GB, free: 5.641 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02318/0.4271, allocations: 17.94 MB / 0.5836 GB, free: 3.641 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.14e-06/0.4271, allocations: 0 / 0.5836 GB, free: 3.641 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.413e-06/0.4271, allocations: 0 / 0.5836 GB, free: 3.641 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0003304/0.4275, allocations: 0 / 0.5836 GB, free: 3.641 MB / 462.1 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.05152/0.479, allocations: 15.79 MB / 0.599 GB, free: 3.75 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.253e-05/0.479, allocations: 8.031 kB / 0.599 GB, free: 3.742 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01387/0.4929, allocations: 2.581 MB / 0.6015 GB, free: 1.152 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01654/0.5095, allocations: 7.518 MB / 0.6089 GB, free: 9.531 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007759/0.5102, allocations: 0.7549 MB / 0.6096 GB, free: 8.754 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00206/0.5123, allocations: 0.844 MB / 0.6104 GB, free: 7.91 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01795/0.5302, allocations: 11.06 MB / 0.6212 GB, free: 11.5 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001565/0.5304, allocations: 51.56 kB / 0.6213 GB, free: 11.45 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008232/0.5312, allocations: 352 kB / 0.6216 GB, free: 11.11 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001794/0.5314, allocations: 211.9 kB / 0.6218 GB, free: 10.9 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01453/0.5459, allocations: 7.615 MB / 0.6292 GB, free: 3.266 MB / 0.4981 GB
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.3481/0.8941, allocations: 49.07 MB / 0.6772 GB, free: 134.3 MB / 0.4982 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01137/0.9054, allocations: 6.636 MB / 0.6836 GB, free: 131.5 MB / 0.4982 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004353/0.9098, allocations: 2.742 MB / 0.6863 GB, free: 130.4 MB / 0.4982 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003035/0.9101, allocations: 109.5 kB / 0.6864 GB, free: 130.4 MB / 0.4982 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.931e-05/0.9102, allocations: 129.2 kB / 0.6865 GB, free: 130.3 MB / 0.4982 GB
Notification: Performance of pre-optimization done (n=725): time 1.111e-05/0.9102, allocations: 3.984 kB / 0.6866 GB, free: 130.2 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=725): time 0.05794/0.9681, allocations: 16.86 MB / 0.703 GB, free: 117.6 MB / 0.4982 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001008/0.9682, allocations: 360.4 kB / 0.7034 GB, free: 117.2 MB / 0.4982 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01206/0.9803, allocations: 8.088 MB / 0.7113 GB, free: 109.3 MB / 0.4982 GB
Notification: Performance of collectPreVariables (initialization): time 0.001354/0.9817, allocations: 193.7 kB / 0.7114 GB, free: 109.1 MB / 0.4982 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002772/0.9844, allocations: 4.483 MB / 0.7158 GB, free: 105.2 MB / 0.4982 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002899/0.9873, allocations: 2.912 MB / 0.7187 GB, free: 102.7 MB / 0.4982 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.005728/0.9931, allocations: 3.604 MB / 0.7222 GB, free: 99.04 MB / 0.4982 GB
Notification: Performance of setup shared object (initialization): time 3.411e-05/0.9931, allocations: 305.1 kB / 0.7225 GB, free: 98.73 MB / 0.4982 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008468/1.002, allocations: 5.812 MB / 0.7282 GB, free: 92.9 MB / 0.4982 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01041/1.012, allocations: 8.539 MB / 0.7365 GB, free: 83.76 MB / 0.4982 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01566/1.028, allocations: 11.32 MB / 0.7475 GB, free: 72.03 MB / 0.4982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.541e-05/1.028, allocations: 11.94 kB / 0.7476 GB, free: 72.02 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=2597) (initialization): time 0.03963/1.067, allocations: 20.21 MB / 0.7673 GB, free: 51.71 MB / 0.4982 GB
Notification: Performance of prepare postOptimizeDAE: time 8.14e-05/1.067, allocations: 16 kB / 0.7673 GB, free: 51.7 MB / 0.4982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.425e-05/1.067, allocations: 28 kB / 0.7673 GB, free: 51.67 MB / 0.4982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02903/1.096, allocations: 5.292 MB / 0.7725 GB, free: 46.32 MB / 0.4982 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01552/1.112, allocations: 3.807 MB / 0.7762 GB, free: 42.5 MB / 0.4982 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06372/1.176, allocations: 54.15 MB / 0.8291 GB, free: 1.773 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01657/1.192, allocations: 1.381 MB / 0.8304 GB, free: 0.6602 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002673/1.195, allocations: 0.6327 MB / 0.8311 GB, free: 28 kB / 0.5138 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1844/1.379, allocations: 7.032 MB / 0.8379 GB, free: 164.9 MB / 0.5138 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01201/1.391, allocations: 8.291 MB / 0.846 GB, free: 162.1 MB / 0.5138 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01484/1.406, allocations: 10.99 MB / 0.8568 GB, free: 156 MB / 0.5138 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.735e-05/1.406, allocations: 8.375 kB / 0.8568 GB, free: 156 MB / 0.5138 GB
Notification: Performance of matching and sorting (n=2597) (initialization_lambda0): time 0.0309/1.437, allocations: 18.49 MB / 0.8748 GB, free: 145.7 MB / 0.5138 GB
Notification: Performance of prepare postOptimizeDAE: time 5.324e-05/1.437, allocations: 18.53 kB / 0.8748 GB, free: 145.7 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.836e-05/1.437, allocations: 31.75 kB / 0.8749 GB, free: 145.6 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02361/1.461, allocations: 4.144 MB / 0.8789 GB, free: 142.7 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01554/1.476, allocations: 3.961 MB / 0.8828 GB, free: 139.2 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03732/1.514, allocations: 45.84 MB / 0.9276 GB, free: 93.86 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01501/1.529, allocations: 1.097 MB / 0.9286 GB, free: 93.24 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001848/1.531, allocations: 0.6297 MB / 0.9292 GB, free: 92.63 MB / 0.5138 GB
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 (2398):
 * Single equations (assignments): 2372
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 26
 * 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): 16 systems
   {(32,0), (10,0), (10,0), (19,0), (32,0), (32,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.002232/1.533, allocations: 1.007 MB / 0.9302 GB, free: 91.64 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002273/1.535, allocations: 0.9752 MB / 0.9312 GB, free: 91.18 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01348/1.549, allocations: 7.828 MB / 0.9388 GB, free: 83.33 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.255e-05/1.549, allocations: 31.88 kB / 0.9389 GB, free: 83.3 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.281e-05/1.549, allocations: 7.656 kB / 0.9389 GB, free: 83.29 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004949/1.554, allocations: 1.941 MB / 0.9408 GB, free: 81.74 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001825/1.555, allocations: 128 kB / 0.9409 GB, free: 81.61 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006104/1.562, allocations: 320.6 kB / 0.9412 GB, free: 81.3 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008042/1.562, allocations: 0.5705 MB / 0.9417 GB, free: 80.73 MB / 0.5138 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01566/1.578, allocations: 7.702 MB / 0.9493 GB, free: 73.19 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01322/1.591, allocations: 9.585 MB / 0.9586 GB, free: 63.52 MB / 0.5138 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003193/1.594, allocations: 2.468 MB / 0.961 GB, free: 61.04 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.006169/1.601, allocations: 4.828 MB / 0.9657 GB, free: 56.25 MB / 0.5138 GB
Notification: Performance of Backend: time 5.11e-07/1.601, allocations: 4.438 kB / 0.9658 GB, free: 56.24 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.04906/1.65, allocations: 29 MB / 0.9941 GB, free: 26.73 MB / 0.5138 GB
Notification: Performance of SimCode: time 0.0237/1.673, allocations: 18.3 MB / 1.012 GB, free: 8.562 MB / 0.5138 GB
Notification: Performance of Templates: time 0.6499/2.323, allocations: 259.4 MB / 1.265 GB, free: 143.4 MB / 0.545 GB
"
[Timeout remaining time 658]
make -j1 -f PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.makefile [Timeout 660]
(rm -f PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.pipe ; mkfifo PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.pipe >> ../files/PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.sim & ./PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization  -abortSlowSimulation -alarm=480 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 17.122209880501032]
Failed to read output from testmodel.py, exit status != 0:
2.3318143114447594 2.340788083 1.911359237
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbc4024cfd0>