Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.0.0-master/package.mo", uses=false)
Using package ScalableTestGrids with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.0.0-master/package.mo)
Using package PowerGrids with version 1.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(ScalableTestGrids.Models.Type1_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2")
translateModel(ScalableTestGrids.Models.Type1_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.00121/0.00121, allocations: 188.9 kB / 17.27 MB, free: 4.5 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.32/1.32, allocations: 205.1 MB / 223.1 MB, free: 8.461 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.0009493/0.0009495, allocations: 92.17 kB / 270.5 MB, free: 8.422 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.05528/0.05528, allocations: 14.17 MB / 332 MB, free: 10.8 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.0.0-master/package.mo): time 2.254/2.254, allocations: 418.5 MB / 0.7826 GB, free: 13.86 MB / 0.5575 GB
Notification: Performance of FrontEnd - loaded program: time 0.001114/0.001114, allocations: 68.77 kB / 0.9536 GB, free: 74.55 MB / 0.714 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2152/0.2163, allocations: 160.9 MB / 1.111 GB, free: 6.648 MB / 0.7765 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1_N_1_M_2): time 0.04534/0.2617, allocations: 64.69 MB / 1.174 GB, free: 5.695 MB / 0.839 GB
Notification: Performance of NFInst.instExpressions: time 0.03326/0.295, allocations: 23.34 MB / 1.197 GB, free: 14.28 MB / 0.8703 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0103/0.3053, allocations: 250.8 kB / 1.197 GB, free: 14.03 MB / 0.8703 GB
Notification: Performance of NFTyping.typeComponents: time 0.01023/0.3156, allocations: 2.798 MB / 1.2 GB, free: 11.22 MB / 0.8703 GB
Notification: Performance of NFTyping.typeBindings: time 0.02553/0.3412, allocations: 8.043 MB / 1.207 GB, free: 3.203 MB / 0.8703 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01086/0.352, allocations: 3.024 MB / 1.21 GB, free: 180 kB / 0.8703 GB
Notification: Performance of NFFlatten.flatten: time 0.02731/0.3793, allocations: 21.05 MB / 1.231 GB, free: 11.04 MB / 0.9015 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005808/0.3852, allocations: 2.388 MB / 1.233 GB, free: 8.582 MB / 0.9015 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.009938/0.3951, allocations: 5.468 MB / 1.239 GB, free: 3.086 MB / 0.9015 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.009802/0.405, allocations: 6.209 MB / 1.245 GB, free: 12.84 MB / 0.9171 GB
Notification: Performance of NFPackage.collectConstants: time 0.003058/0.4081, allocations: 416 kB / 1.245 GB, free: 12.44 MB / 0.9171 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006813/0.4149, allocations: 1.156 MB / 1.246 GB, free: 11.29 MB / 0.9171 GB
Notification: Performance of NFScalarize.scalarize: time 0.002791/0.4177, allocations: 1.577 MB / 1.248 GB, free: 9.699 MB / 0.9171 GB
Notification: Performance of NFVerifyModel.verify: time 0.01112/0.4288, allocations: 5.738 MB / 1.253 GB, free: 3.93 MB / 0.9171 GB
Notification: Performance of NFConvertDAE.convert: time 0.0188/0.4477, allocations: 14.61 MB / 1.268 GB, free: 5.27 MB / 0.9328 GB
Notification: Performance of FrontEnd - DAE generated: time 6.341e-06/0.4477, allocations: 0 / 1.268 GB, free: 5.27 MB / 0.9328 GB
Notification: Performance of FrontEnd: time 4.358e-06/0.4477, allocations: 0 / 1.268 GB, free: 5.27 MB / 0.9328 GB
Notification: Performance of Transformations before backend: time 0.0004189/0.4481, allocations: 3.938 kB / 1.268 GB, free: 5.266 MB / 0.9328 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2196
 * Number of variables: 2196
Notification: Performance of Generate backend data structure: time 0.04898/0.4971, allocations: 15.61 MB / 1.283 GB, free: 5.535 MB / 0.9484 GB
Notification: Performance of prepare preOptimizeDAE: time 4.367e-05/0.4972, allocations: 8.031 kB / 1.283 GB, free: 5.527 MB / 0.9484 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006171/0.5034, allocations: 1.65 MB / 1.285 GB, free: 3.863 MB / 0.9484 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01314/0.5165, allocations: 6.641 MB / 1.291 GB, free: 13.12 MB / 0.964 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000445/0.517, allocations: 0.6496 MB / 1.292 GB, free: 12.47 MB / 0.964 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001444/0.5184, allocations: 0.871 MB / 1.292 GB, free: 11.6 MB / 0.964 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01484/0.5333, allocations: 8.973 MB / 1.301 GB, free: 2.223 MB / 0.964 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001189/0.5334, allocations: 15.88 kB / 1.301 GB, free: 2.207 MB / 0.964 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006345/0.5341, allocations: 360 kB / 1.302 GB, free: 1.855 MB / 0.964 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001352/0.5342, allocations: 216 kB / 1.302 GB, free: 1.645 MB / 0.964 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01394/0.5482, allocations: 8.507 MB / 1.31 GB, free: 9.117 MB / 0.9796 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.845/1.393, allocations: 53.75 MB / 1.363 GB, free: 135.2 MB / 0.9801 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01668/1.41, allocations: 10.66 MB / 1.373 GB, free: 129.2 MB / 0.9801 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006066/1.416, allocations: 4.013 MB / 1.377 GB, free: 127.2 MB / 0.9801 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004326/1.416, allocations: 113.2 kB / 1.377 GB, free: 127.2 MB / 0.9801 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.667e-05/1.417, allocations: 127.7 kB / 1.377 GB, free: 127.1 MB / 0.9801 GB
Notification: Performance of pre-optimization done (n=909): time 1.173e-05/1.417, allocations: 2.125 kB / 1.377 GB, free: 127.1 MB / 0.9801 GB
Notification: Performance of matching and sorting (n=911): time 0.0374/1.454, allocations: 20.42 MB / 1.397 GB, free: 116.5 MB / 0.9801 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000103/1.454, allocations: 351.1 kB / 1.397 GB, free: 116.2 MB / 0.9801 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006246/1.46, allocations: 5.788 MB / 1.403 GB, free: 113.2 MB / 0.9801 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007327/1.461, allocations: 193.1 kB / 1.403 GB, free: 113 MB / 0.9801 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001943/1.463, allocations: 4.054 MB / 1.407 GB, free: 109.6 MB / 0.9801 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002184/1.465, allocations: 3.141 MB / 1.41 GB, free: 106.9 MB / 0.9801 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002185/1.467, allocations: 1.741 MB / 1.412 GB, free: 105.9 MB / 0.9801 GB
Notification: Performance of setup shared object (initialization): time 4.242e-05/1.468, allocations: 301.1 kB / 1.412 GB, free: 105.6 MB / 0.9801 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004794/1.472, allocations: 3.269 MB / 1.415 GB, free: 103.9 MB / 0.9801 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005246/1.478, allocations: 5.271 MB / 1.421 GB, free: 99.89 MB / 0.9801 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01068/1.488, allocations: 8.586 MB / 1.429 GB, free: 94.16 MB / 0.9801 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.764e-05/1.488, allocations: 13.47 kB / 1.429 GB, free: 94.16 MB / 0.9801 GB
Notification: Performance of matching and sorting (n=1353) (initialization): time 0.02558/1.514, allocations: 13.88 MB / 1.443 GB, free: 82.67 MB / 0.9801 GB
Notification: Performance of prepare postOptimizeDAE: time 4.6e-05/1.514, allocations: 28.88 kB / 1.443 GB, free: 82.64 MB / 0.9801 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.338e-05/1.514, allocations: 50.97 kB / 1.443 GB, free: 82.6 MB / 0.9801 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02625/1.54, allocations: 6.194 MB / 1.449 GB, free: 76.36 MB / 0.9801 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007341/1.548, allocations: 1.954 MB / 1.451 GB, free: 74.4 MB / 0.9801 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06003/1.608, allocations: 53.43 MB / 1.503 GB, free: 18.29 MB / 0.9801 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008209/1.616, allocations: 0.529 MB / 1.503 GB, free: 17.76 MB / 0.9801 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001273/1.617, allocations: 376 kB / 1.504 GB, free: 17.39 MB / 0.9801 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007175/1.624, allocations: 4.262 MB / 1.508 GB, free: 13.12 MB / 0.9801 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004836/1.629, allocations: 5.024 MB / 1.513 GB, free: 7.371 MB / 0.9801 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.009925/1.639, allocations: 8.268 MB / 1.521 GB, free: 14.39 MB / 0.9958 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.784e-05/1.639, allocations: 12 kB / 1.521 GB, free: 14.38 MB / 0.9958 GB
Notification: Performance of matching and sorting (n=1353) (initialization_lambda0): time 0.02281/1.662, allocations: 12.19 MB / 1.533 GB, free: 2.051 MB / 0.9958 GB
Notification: Performance of prepare postOptimizeDAE: time 4.076e-05/1.662, allocations: 24.22 kB / 1.533 GB, free: 2.027 MB / 0.9958 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.65e-05/1.662, allocations: 52 kB / 1.533 GB, free: 1.977 MB / 0.9958 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02376/1.686, allocations: 5.107 MB / 1.538 GB, free: 12.83 MB / 1.011 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009275/1.695, allocations: 2.095 MB / 1.54 GB, free: 10.73 MB / 1.011 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05723/1.753, allocations: 43.8 MB / 1.583 GB, free: 12.53 MB / 1.058 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009318/1.762, allocations: 458 kB / 1.583 GB, free: 12.08 MB / 1.058 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001883/1.764, allocations: 380 kB / 1.583 GB, free: 11.71 MB / 1.058 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 215
 * Number of states: 0 ()
 * Number of discrete variables: 10 (GEN_2_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_1.AVR.firstOrderLim.I.local_reset,GEN_2_1.TGOV.firstOrderLim.I.local_reset,GEN_1_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_1.AVR.firstOrderLim.I.local_reset,GEN_1_1.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 (1063):
 * Single equations (assignments): 1043
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 20
 * Mixed (continuous/discrete) equation systems: 0
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%), (3,0,66.7%), (3,0,66.7%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 12 systems
   {(38,0), (216,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.002312/1.766, allocations: 0.7384 MB / 1.584 GB, free: 10.96 MB / 1.058 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002464/1.769, allocations: 0.8916 MB / 1.585 GB, free: 10.06 MB / 1.058 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01991/1.789, allocations: 10.01 MB / 1.595 GB, free: 12 kB / 1.058 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001789/1.789, allocations: 43.89 kB / 1.595 GB, free: 15.96 MB / 1.074 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.403e-05/1.789, allocations: 0 / 1.595 GB, free: 15.96 MB / 1.074 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005185/1.794, allocations: 1.587 MB / 1.596 GB, free: 14.35 MB / 1.074 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001721/1.796, allocations: 160 kB / 1.597 GB, free: 14.2 MB / 1.074 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005779/1.802, allocations: 362.2 kB / 1.597 GB, free: 13.84 MB / 1.074 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001345/1.803, allocations: 0.7322 MB / 1.598 GB, free: 13.11 MB / 1.074 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02506/1.828, allocations: 9.421 MB / 1.607 GB, free: 3.863 MB / 1.074 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.107/1.935, allocations: 46.55 MB / 1.652 GB, free: 5.035 MB / 1.121 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.006222/1.941, allocations: 3.012 MB / 1.655 GB, free: 2.016 MB / 1.121 GB
Notification: Performance of sorting global known variables: time 0.00774/1.949, allocations: 3.675 MB / 1.659 GB, free: 14.38 MB / 1.136 GB
Notification: Performance of Backend: time 3.8e-07/1.949, allocations: 4 kB / 1.659 GB, free: 14.38 MB / 1.136 GB
Notification: Performance of simCode: created initialization part: time 0.06056/2.01, allocations: 23.71 MB / 1.682 GB, free: 6.203 MB / 1.152 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.04886/2.059, allocations: 32.74 MB / 1.714 GB, free: 5.406 MB / 1.183 GB
Notification: Performance of Templates: time 0.7757/2.834, allocations: 204.4 MB / 1.914 GB, free: 343.5 MB / 1.261 GB
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.makefile
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=500 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_2.pipe 2>&1)