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.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo", uses=false)
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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization")
translateModel(PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=20000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001748/0.001748, allocations: 108 kB / 15.61 MB, free: 152 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002282/0.002282, allocations: 197.2 kB / 16.53 MB, free: 6.664 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.339/1.339, allocations: 222.9 MB / 240.2 MB, free: 4.371 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.1731/0.1731, allocations: 16.78 MB / 307.2 MB, free: 14.81 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001266/0.001266, allocations: 63.89 kB / 371.8 MB, free: 3.543 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2541/0.2554, allocations: 53.55 MB / 425.3 MB, free: 39.37 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization): time 0.06757/0.323, allocations: 95.09 MB / 0.5082 GB, free: 2.594 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.04422/0.3673, allocations: 23.57 MB / 0.5312 GB, free: 14.45 MB / 414.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 UNom has annotation(Evaluate=true) and no binding.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:6:3-7:21:writable] Warning: Parameter SNom has annotation(Evaluate=true) and no binding.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:4:3-5:21:writable] Warning: Parameter UNom has annotation(Evaluate=true) and no binding.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/Electrical/BaseClasses/OnePortACVI.mo:6:3-7:21:writable] Warning: Parameter SNom has annotation(Evaluate=true) and no binding.
Notification: Performance of NFInst.updateImplicitVariability: time 0.01614/0.3835, allocations: 250.6 kB / 0.5315 GB, free: 14.21 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01206/0.3956, allocations: 3.049 MB / 0.5345 GB, free: 11.14 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.02785/0.4235, allocations: 8.264 MB / 0.5425 GB, free: 2.934 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01402/0.4375, allocations: 5.343 MB / 0.5477 GB, free: 13.68 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02951/0.4671, allocations: 20.82 MB / 0.5681 GB, free: 8.816 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.009534/0.4766, allocations: 5.175 MB / 0.5731 GB, free: 3.562 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.2395/0.7162, allocations: 6.889 MB / 0.5798 GB, free: 27.09 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0147/0.7309, allocations: 7.303 MB / 0.587 GB, free: 26.87 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.006894/0.7379, allocations: 1.202 MB / 0.5882 GB, free: 26.87 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0117/0.7496, allocations: 2.127 MB / 0.5902 GB, free: 26.87 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.004921/0.7546, allocations: 2.55 MB / 0.5927 GB, free: 26.45 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01436/0.769, allocations: 6.869 MB / 0.5994 GB, free: 24.75 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02964/0.7986, allocations: 17.52 MB / 0.6165 GB, free: 22.73 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.983e-06/0.7987, allocations: 2.062 kB / 0.6165 GB, free: 22.73 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.993e-06/0.7987, allocations: 1.344 kB / 0.6165 GB, free: 22.73 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0006388/0.7993, allocations: 0 / 0.6165 GB, free: 22.73 MB / 446.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.06756/0.8669, allocations: 15.67 MB / 0.6318 GB, free: 17.71 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.45e-05/0.867, allocations: 7.438 kB / 0.6319 GB, free: 17.71 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01725/0.8842, allocations: 2.575 MB / 0.6344 GB, free: 16.71 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02278/0.9071, allocations: 7.383 MB / 0.6416 GB, free: 13.91 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008257/0.9079, allocations: 0.7012 MB / 0.6423 GB, free: 13.55 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003572/0.9115, allocations: 0.8429 MB / 0.6431 GB, free: 13.27 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02237/0.9339, allocations: 9.488 MB / 0.6524 GB, free: 5.254 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002013/0.9342, allocations: 47.38 kB / 0.6524 GB, free: 5.234 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001713/0.9359, allocations: 352 kB / 0.6527 GB, free: 4.891 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001252/0.936, allocations: 211.5 kB / 0.6529 GB, free: 4.688 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01555/0.9516, allocations: 7.684 MB / 0.6604 GB, free: 13.24 MB / 462.1 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.09/1.042, allocations: 59.16 MB / 0.7182 GB, free: 352 kB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01491/1.057, allocations: 8.733 MB / 0.7267 GB, free: 7.426 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006272/1.063, allocations: 3.048 MB / 0.7297 GB, free: 4.363 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00041/1.063, allocations: 103 kB / 0.7298 GB, free: 4.27 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001114/1.063, allocations: 120.3 kB / 0.7299 GB, free: 4.141 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=725): time 9.758e-06/1.063, allocations: 0 / 0.7299 GB, free: 4.141 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=725): time 0.0608/1.124, allocations: 16.85 MB / 0.7464 GB, free: 3.293 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002192/1.125, allocations: 362 kB / 0.7467 GB, free: 2.871 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01864/1.143, allocations: 8.376 MB / 0.7549 GB, free: 10.54 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.002315/1.146, allocations: 189.7 kB / 0.7551 GB, free: 10.35 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005654/1.151, allocations: 4.328 MB / 0.7593 GB, free: 6.066 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.005311/1.157, allocations: 2.838 MB / 0.7621 GB, free: 3.254 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.2483/1.405, allocations: 3.754 MB / 0.7658 GB, free: 208.2 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 4.503e-05/1.405, allocations: 305 kB / 0.766 GB, free: 207.9 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01537/1.42, allocations: 5.925 MB / 0.7718 GB, free: 207.7 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0159/1.436, allocations: 8.076 MB / 0.7797 GB, free: 206.3 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02286/1.459, allocations: 11.48 MB / 0.7909 GB, free: 202 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001067/1.459, allocations: 12.06 kB / 0.7909 GB, free: 202 MB / 0.545 GB
Notification: Performance of matching and sorting (n=2671) (initialization): time 0.06297/1.522, allocations: 20.61 MB / 0.8111 GB, free: 194.5 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 6.574e-05/1.522, allocations: 16.39 kB / 0.8111 GB, free: 194.5 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.614e-05/1.522, allocations: 29.34 kB / 0.8111 GB, free: 194.5 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02885/1.551, allocations: 5.392 MB / 0.8164 GB, free: 193.6 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01741/1.569, allocations: 3.961 MB / 0.8202 GB, free: 190.1 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06144/1.63, allocations: 58.26 MB / 0.8771 GB, free: 133.1 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0214/1.652, allocations: 1.436 MB / 0.8785 GB, free: 132.4 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004299/1.656, allocations: 0.6468 MB / 0.8792 GB, free: 131.8 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02188/1.678, allocations: 7.161 MB / 0.8862 GB, free: 125.1 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01659/1.695, allocations: 7.842 MB / 0.8938 GB, free: 117.7 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01989/1.714, allocations: 11.15 MB / 0.9047 GB, free: 106.4 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.653e-05/1.715, allocations: 8 kB / 0.9047 GB, free: 106.4 MB / 0.545 GB
Notification: Performance of matching and sorting (n=2671) (initialization_lambda0): time 0.05297/1.768, allocations: 18.88 MB / 0.9232 GB, free: 88.15 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 5.245e-05/1.768, allocations: 20 kB / 0.9232 GB, free: 88.13 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.268e-05/1.768, allocations: 36.16 kB / 0.9232 GB, free: 88.09 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02343/1.791, allocations: 4.246 MB / 0.9274 GB, free: 83.78 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0174/1.809, allocations: 4.108 MB / 0.9314 GB, free: 79.66 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03924/1.848, allocations: 49.72 MB / 0.9799 GB, free: 26.53 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01977/1.868, allocations: 1.129 MB / 0.981 GB, free: 25.61 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003731/1.872, allocations: 0.6405 MB / 0.9816 GB, free: 24.96 MB / 0.545 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 (2471):
 * Single equations (assignments): 2445
 * 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), (41,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0), (2,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.005442/1.877, allocations: 1.022 MB / 0.9826 GB, free: 23.95 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004388/1.881, allocations: 0.9571 MB / 0.9836 GB, free: 22.98 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01848/1.9, allocations: 7.939 MB / 0.9913 GB, free: 14.97 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001563/1.9, allocations: 39.86 kB / 0.9914 GB, free: 14.93 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.342e-05/1.9, allocations: 0 / 0.9914 GB, free: 14.93 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.008244/1.908, allocations: 2.017 MB / 0.9933 GB, free: 12.95 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004121/1.913, allocations: 132 kB / 0.9935 GB, free: 12.82 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009959/1.922, allocations: 322.7 kB / 0.9938 GB, free: 12.5 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001667/1.924, allocations: 0.5437 MB / 0.9943 GB, free: 11.96 MB / 0.545 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02252/1.947, allocations: 7.93 MB / 1.002 GB, free: 4.105 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.2497/2.196, allocations: 9.559 MB / 1.011 GB, free: 181.4 MB / 0.545 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004512/2.201, allocations: 2.611 MB / 1.014 GB, free: 181.3 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.009625/2.211, allocations: 5.098 MB / 1.019 GB, free: 181.1 MB / 0.545 GB
Notification: Performance of Backend: time 1.182e-06/2.211, allocations: 0 / 1.019 GB, free: 181.1 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.07394/2.285, allocations: 32.49 MB / 1.051 GB, free: 171.3 MB / 0.545 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of SimCode: time 0.04589/2.331, allocations: 18.66 MB / 1.069 GB, free: 162.9 MB / 0.545 GB
Notification: Performance of Templates: time 0.5698/2.9, allocations: 237.7 MB / 1.301 GB, free: 115.3 MB / 0.545 GB
make -j1 -f PowerGrids_dev_PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization.makefile
(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)