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.004225/0.004225, allocations: 101.9 kB / 15.73 MB, free: 6.059 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001129/0.001129, allocations: 188.6 kB / 16.67 MB, free: 5.969 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.204/1.204, allocations: 222.9 MB / 240.4 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.07048/0.07048, allocations: 16.78 MB / 307.3 MB, free: 14.39 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001081/0.001081, allocations: 59.91 kB / 426.6 MB, free: 10.54 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08352/0.08461, allocations: 53.56 MB / 480.2 MB, free: 4.922 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.IslandOperation.TwoGeneratorsLocalInitialization): time 0.2431/0.3278, allocations: 95.07 MB / 0.5618 GB, free: 13.61 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03855/0.3664, allocations: 23.58 MB / 0.5848 GB, free: 8.305 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01065/0.377, allocations: 250.6 kB / 0.585 GB, free: 8.059 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.009269/0.3863, allocations: 3.049 MB / 0.588 GB, free: 4.988 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0252/0.4115, allocations: 8.264 MB / 0.5961 GB, free: 12.78 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01155/0.4231, allocations: 5.336 MB / 0.6013 GB, free: 7.531 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02441/0.4475, allocations: 20.91 MB / 0.6217 GB, free: 2.555 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00848/0.456, allocations: 5.16 MB / 0.6267 GB, free: 13.32 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.009586/0.4656, allocations: 6.935 MB / 0.6335 GB, free: 6.355 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01074/0.4764, allocations: 7.287 MB / 0.6406 GB, free: 15.04 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003285/0.4797, allocations: 1.207 MB / 0.6418 GB, free: 13.83 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006593/0.4863, allocations: 2.129 MB / 0.6439 GB, free: 11.7 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003235/0.4895, allocations: 2.552 MB / 0.6464 GB, free: 9.141 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01277/0.5023, allocations: 6.866 MB / 0.6531 GB, free: 2.242 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.03263/0.535, allocations: 17.88 MB / 0.6705 GB, free: 304 kB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 5.681e-06/0.535, allocations: 0 / 0.6705 GB, free: 304 kB / 0.4981 GB
Notification: Performance of FrontEnd: time 2.264e-06/0.535, allocations: 0 / 0.6705 GB, free: 304 kB / 0.4981 GB
Notification: Performance of Transformations before backend: time 0.000498/0.5355, allocations: 0 / 0.6705 GB, free: 304 kB / 0.4981 GB
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.05062/0.5862, allocations: 15.96 MB / 0.6861 GB, free: 216 kB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.297e-05/0.5862, allocations: 12.03 kB / 0.6861 GB, free: 204 kB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.013/0.5992, allocations: 2.628 MB / 0.6887 GB, free: 13.56 MB / 0.5294 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01445/0.6137, allocations: 7.68 MB / 0.6962 GB, free: 5.809 MB / 0.5294 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004785/0.6142, allocations: 0.7008 MB / 0.6969 GB, free: 5.098 MB / 0.5294 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001242/0.6155, allocations: 0.8516 MB / 0.6977 GB, free: 4.246 MB / 0.5294 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0143/0.6298, allocations: 9.476 MB / 0.707 GB, free: 9.801 MB / 0.545 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001423/0.63, allocations: 47.56 kB / 0.707 GB, free: 9.754 MB / 0.545 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007164/0.6307, allocations: 356 kB / 0.7074 GB, free: 9.406 MB / 0.545 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001286/0.6308, allocations: 208 kB / 0.7076 GB, free: 9.203 MB / 0.545 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01249/0.6433, allocations: 7.677 MB / 0.7151 GB, free: 1.508 MB / 0.545 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.442/1.085, allocations: 58.96 MB / 0.7726 GB, free: 132.3 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01155/1.097, allocations: 8.744 MB / 0.7812 GB, free: 126.9 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003851/1.101, allocations: 3.049 MB / 0.7842 GB, free: 125.3 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003228/1.101, allocations: 104.2 kB / 0.7843 GB, free: 125.3 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.511e-05/1.101, allocations: 119.7 kB / 0.7844 GB, free: 125.2 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=725): time 8.326e-06/1.101, allocations: 0 / 0.7844 GB, free: 125.2 MB / 0.545 GB
Notification: Performance of matching and sorting (n=725): time 0.04834/1.15, allocations: 16.91 MB / 0.8009 GB, free: 115.9 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.136e-05/1.15, allocations: 372.4 kB / 0.8012 GB, free: 115.5 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009733/1.159, allocations: 8.385 MB / 0.8094 GB, free: 107.3 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009238/1.16, allocations: 189.7 kB / 0.8096 GB, free: 107.1 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002473/1.163, allocations: 4.444 MB / 0.8139 GB, free: 103.2 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002308/1.165, allocations: 2.958 MB / 0.8168 GB, free: 100.6 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004183/1.169, allocations: 3.763 MB / 0.8205 GB, free: 96.85 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 3.965e-05/1.169, allocations: 305.1 kB / 0.8208 GB, free: 96.55 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007774/1.177, allocations: 5.924 MB / 0.8266 GB, free: 90.61 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009003/1.186, allocations: 8.076 MB / 0.8345 GB, free: 82.09 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01427/1.2, allocations: 11.47 MB / 0.8457 GB, free: 70.2 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.154e-05/1.2, allocations: 8 kB / 0.8457 GB, free: 70.2 MB / 0.545 GB
Notification: Performance of matching and sorting (n=2671) (initialization): time 0.03177/1.232, allocations: 20.68 MB / 0.8659 GB, free: 49.44 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 5.333e-05/1.232, allocations: 20 kB / 0.8659 GB, free: 49.42 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.541e-05/1.232, allocations: 32 kB / 0.8659 GB, free: 49.39 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02536/1.258, allocations: 5.336 MB / 0.8711 GB, free: 43.99 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01424/1.272, allocations: 3.963 MB / 0.875 GB, free: 40.02 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.2689/1.541, allocations: 58.21 MB / 0.9319 GB, free: 166.2 MB / 0.5607 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01511/1.556, allocations: 1.472 MB / 0.9333 GB, free: 166.2 MB / 0.5607 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001705/1.558, allocations: 0.6439 MB / 0.9339 GB, free: 166.2 MB / 0.5607 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01286/1.571, allocations: 7.159 MB / 0.9409 GB, free: 166.1 MB / 0.5607 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01151/1.582, allocations: 7.841 MB / 0.9486 GB, free: 162.4 MB / 0.5607 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0139/1.596, allocations: 11.15 MB / 0.9595 GB, free: 156.3 MB / 0.5607 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.539e-05/1.596, allocations: 7.188 kB / 0.9595 GB, free: 156.3 MB / 0.5607 GB
Notification: Performance of matching and sorting (n=2671) (initialization_lambda0): time 0.03036/1.627, allocations: 18.95 MB / 0.978 GB, free: 149.1 MB / 0.5607 GB
Notification: Performance of prepare postOptimizeDAE: time 5.158e-05/1.627, allocations: 15.72 kB / 0.978 GB, free: 149.1 MB / 0.5607 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.272e-05/1.627, allocations: 30.44 kB / 0.978 GB, free: 149.1 MB / 0.5607 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02146/1.648, allocations: 4.213 MB / 0.9821 GB, free: 148.5 MB / 0.5607 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01541/1.664, allocations: 4.111 MB / 0.9861 GB, free: 144.9 MB / 0.5607 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03713/1.701, allocations: 49.72 MB / 1.035 GB, free: 95.06 MB / 0.5607 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01452/1.715, allocations: 1.15 MB / 1.036 GB, free: 94.42 MB / 0.5607 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001448/1.717, allocations: 0.6392 MB / 1.036 GB, free: 93.8 MB / 0.5607 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.002674/1.72, allocations: 1.034 MB / 1.037 GB, free: 92.78 MB / 0.5607 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002646/1.722, allocations: 1.008 MB / 1.038 GB, free: 91.97 MB / 0.5607 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01311/1.735, allocations: 7.94 MB / 1.046 GB, free: 84.96 MB / 0.5607 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.545e-05/1.735, allocations: 39.11 kB / 1.046 GB, free: 84.92 MB / 0.5607 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.133e-05/1.735, allocations: 0 / 1.046 GB, free: 84.92 MB / 0.5607 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00385/1.739, allocations: 2.061 MB / 1.048 GB, free: 82.9 MB / 0.5607 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001358/1.741, allocations: 128 kB / 1.048 GB, free: 82.77 MB / 0.5607 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005556/1.746, allocations: 326.9 kB / 1.049 GB, free: 82.46 MB / 0.5607 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007231/1.747, allocations: 0.5359 MB / 1.049 GB, free: 81.92 MB / 0.5607 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01501/1.762, allocations: 7.904 MB / 1.057 GB, free: 74.12 MB / 0.5607 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01309/1.775, allocations: 9.618 MB / 1.066 GB, free: 64.41 MB / 0.5607 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003073/1.778, allocations: 2.618 MB / 1.069 GB, free: 61.78 MB / 0.5607 GB
Notification: Performance of sorting global known variables: time 0.00648/1.785, allocations: 5.1 MB / 1.074 GB, free: 56.72 MB / 0.5607 GB
Notification: Performance of Backend: time 6.61e-07/1.785, allocations: 0 / 1.074 GB, free: 56.72 MB / 0.5607 GB
Notification: Performance of simCode: created initialization part: time 0.04886/1.834, allocations: 32.57 MB / 1.106 GB, free: 23.62 MB / 0.5607 GB
Notification: Performance of SimCode: time 0.03506/1.869, allocations: 18.77 MB / 1.124 GB, free: 4.977 MB / 0.5607 GB
Notification: Performance of Templates: time 0.7512/2.62, allocations: 241.7 MB / 1.36 GB, free: 182.7 MB / 0.5919 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)