Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.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_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1")
translateModel(ScalableTestGrids.Models.Type1_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.001643/0.001643, allocations: 190.4 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.275/1.275, allocations: 205.1 MB / 223.1 MB, free: 8.453 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.0009662/0.0009664, allocations: 102.3 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.05721/0.05721, 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.271/2.271, allocations: 418.6 MB / 0.7826 GB, free: 13.86 MB / 0.5575 GB
Notification: Performance of FrontEnd - loaded program: time 0.001202/0.001203, allocations: 65.8 kB / 0.9536 GB, free: 74.56 MB / 0.714 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2339/0.2351, allocations: 160.9 MB / 1.111 GB, free: 6.668 MB / 0.7765 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1_N_1_M_1): time 0.04636/0.2815, allocations: 56.32 MB / 1.166 GB, free: 14.12 MB / 0.839 GB
Notification: Performance of NFInst.instExpressions: time 0.03206/0.3136, allocations: 20.56 MB / 1.186 GB, free: 9.492 MB / 0.8546 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01018/0.3238, allocations: 218.9 kB / 1.186 GB, free: 9.277 MB / 0.8546 GB
Notification: Performance of NFTyping.typeComponents: time 0.009089/0.3329, allocations: 2.429 MB / 1.188 GB, free: 6.836 MB / 0.8546 GB
Notification: Performance of NFTyping.typeBindings: time 0.02375/0.3567, allocations: 7.006 MB / 1.195 GB, free: 15.86 MB / 0.8703 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007408/0.3641, allocations: 2.895 MB / 1.198 GB, free: 12.96 MB / 0.8703 GB
Notification: Performance of NFFlatten.flatten: time 0.02317/0.3873, allocations: 18.35 MB / 1.216 GB, free: 10.55 MB / 0.8859 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004285/0.3916, allocations: 2.258 MB / 1.218 GB, free: 8.219 MB / 0.8859 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.008186/0.3998, allocations: 4.784 MB / 1.223 GB, free: 3.41 MB / 0.8859 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.007962/0.4078, allocations: 5.435 MB / 1.228 GB, free: 13.95 MB / 0.9015 GB
Notification: Performance of NFPackage.collectConstants: time 0.002334/0.4102, allocations: 376 kB / 1.228 GB, free: 13.58 MB / 0.9015 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005495/0.4157, allocations: 1.043 MB / 1.229 GB, free: 12.54 MB / 0.9015 GB
Notification: Performance of NFScalarize.scalarize: time 0.001485/0.4172, allocations: 1.414 MB / 1.231 GB, free: 11.12 MB / 0.9015 GB
Notification: Performance of NFVerifyModel.verify: time 0.007701/0.4249, allocations: 5.046 MB / 1.236 GB, free: 6.043 MB / 0.9015 GB
Notification: Performance of NFConvertDAE.convert: time 0.01293/0.4379, allocations: 13.03 MB / 1.249 GB, free: 8.977 MB / 0.9171 GB
Notification: Performance of FrontEnd - DAE generated: time 7.053e-06/0.4379, allocations: 4 kB / 1.249 GB, free: 8.973 MB / 0.9171 GB
Notification: Performance of FrontEnd: time 9.12e-07/0.4379, allocations: 0 / 1.249 GB, free: 8.973 MB / 0.9171 GB
Notification: Performance of Transformations before backend: time 0.0002408/0.4381, allocations: 0 / 1.249 GB, free: 8.973 MB / 0.9171 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1920
 * Number of variables: 1920
Notification: Performance of Generate backend data structure: time 0.0428/0.4809, allocations: 13.73 MB / 1.262 GB, free: 11.13 MB / 0.9328 GB
Notification: Performance of prepare preOptimizeDAE: time 4.634e-05/0.481, allocations: 16.02 kB / 1.262 GB, free: 11.12 MB / 0.9328 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006584/0.4876, allocations: 1.437 MB / 1.263 GB, free: 9.668 MB / 0.9328 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01328/0.5009, allocations: 5.927 MB / 1.269 GB, free: 3.672 MB / 0.9328 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005285/0.5015, allocations: 0.581 MB / 1.27 GB, free: 3.09 MB / 0.9328 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00197/0.5034, allocations: 0.7694 MB / 1.27 GB, free: 2.32 MB / 0.9328 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01674/0.5202, allocations: 7.883 MB / 1.278 GB, free: 10.11 MB / 0.9484 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001932/0.5204, allocations: 15.81 kB / 1.278 GB, free: 10.1 MB / 0.9484 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001028/0.5214, allocations: 312 kB / 1.278 GB, free: 9.793 MB / 0.9484 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001221/0.5216, allocations: 184 kB / 1.279 GB, free: 9.613 MB / 0.9484 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0123/0.5339, allocations: 7.504 MB / 1.286 GB, free: 2.098 MB / 0.9484 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.7871/1.321, allocations: 46.18 MB / 1.331 GB, free: 186.7 MB / 0.9958 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01511/1.336, allocations: 9.318 MB / 1.34 GB, free: 184.8 MB / 0.9958 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005326/1.342, allocations: 3.538 MB / 1.344 GB, free: 183.8 MB / 0.9958 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003671/1.342, allocations: 102.5 kB / 1.344 GB, free: 183.8 MB / 0.9958 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.283e-05/1.342, allocations: 114.5 kB / 1.344 GB, free: 183.7 MB / 0.9958 GB
Notification: Performance of pre-optimization done (n=787): time 9.598e-06/1.342, allocations: 1.406 kB / 1.344 GB, free: 183.7 MB / 0.9958 GB
Notification: Performance of matching and sorting (n=789): time 0.03474/1.377, allocations: 17.83 MB / 1.361 GB, free: 176.2 MB / 0.9958 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001261/1.377, allocations: 341.4 kB / 1.362 GB, free: 175.9 MB / 0.9958 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00744/1.384, allocations: 5.211 MB / 1.367 GB, free: 174.1 MB / 0.9958 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009873/1.385, allocations: 177.2 kB / 1.367 GB, free: 173.9 MB / 0.9958 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00207/1.387, allocations: 3.586 MB / 1.37 GB, free: 171.3 MB / 0.9958 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002369/1.39, allocations: 2.722 MB / 1.373 GB, free: 169.2 MB / 0.9958 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002253/1.392, allocations: 1.556 MB / 1.374 GB, free: 168.4 MB / 0.9958 GB
Notification: Performance of setup shared object (initialization): time 4.069e-05/1.392, allocations: 304.7 kB / 1.375 GB, free: 168.1 MB / 0.9958 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004423/1.397, allocations: 2.934 MB / 1.378 GB, free: 166.9 MB / 0.9958 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005/1.402, allocations: 4.736 MB / 1.382 GB, free: 163.8 MB / 0.9958 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009575/1.411, allocations: 7.654 MB / 1.39 GB, free: 159 MB / 0.9958 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.253e-05/1.411, allocations: 11.91 kB / 1.39 GB, free: 159 MB / 0.9958 GB
Notification: Performance of matching and sorting (n=1197) (initialization): time 0.02422/1.435, allocations: 12.41 MB / 1.402 GB, free: 153.5 MB / 0.9958 GB
Notification: Performance of prepare postOptimizeDAE: time 4.01e-05/1.436, allocations: 22.84 kB / 1.402 GB, free: 153.5 MB / 0.9958 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.876e-05/1.436, allocations: 47.09 kB / 1.402 GB, free: 153.5 MB / 0.9958 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02388/1.459, allocations: 5.51 MB / 1.407 GB, free: 151.2 MB / 0.9958 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00654/1.466, allocations: 1.75 MB / 1.409 GB, free: 150.8 MB / 0.9958 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05383/1.52, allocations: 49.73 MB / 1.458 GB, free: 98.82 MB / 0.9958 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01026/1.53, allocations: 493.9 kB / 1.458 GB, free: 98.34 MB / 0.9958 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008032/1.531, allocations: 336 kB / 1.458 GB, free: 98.01 MB / 0.9958 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.006126/1.537, allocations: 3.795 MB / 1.462 GB, free: 94.2 MB / 0.9958 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004091/1.541, allocations: 4.499 MB / 1.466 GB, free: 89.04 MB / 0.9958 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00797/1.549, allocations: 7.321 MB / 1.474 GB, free: 81.12 MB / 0.9958 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.028e-05/1.549, allocations: 16 kB / 1.474 GB, free: 81.11 MB / 0.9958 GB
Notification: Performance of matching and sorting (n=1197) (initialization_lambda0): time 0.01679/1.566, allocations: 10.72 MB / 1.484 GB, free: 70.32 MB / 0.9958 GB
Notification: Performance of prepare postOptimizeDAE: time 3.465e-05/1.566, allocations: 27.94 kB / 1.484 GB, free: 70.29 MB / 0.9958 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.936e-05/1.566, allocations: 41.53 kB / 1.484 GB, free: 70.25 MB / 0.9958 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01921/1.585, allocations: 4.416 MB / 1.489 GB, free: 65.79 MB / 0.9958 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007042/1.592, allocations: 1.896 MB / 1.49 GB, free: 63.89 MB / 0.9958 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0374/1.63, allocations: 40.03 MB / 1.529 GB, free: 21.44 MB / 0.9958 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007485/1.637, allocations: 406.3 kB / 1.53 GB, free: 21.04 MB / 0.9958 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001156/1.639, allocations: 332 kB / 1.53 GB, free: 20.71 MB / 0.9958 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 193
 * 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 (951):
 * Single equations (assignments): 931
 * 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), (172,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.001437/1.64, allocations: 0.6761 MB / 1.531 GB, free: 20.03 MB / 0.9958 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001834/1.642, allocations: 0.7913 MB / 1.532 GB, free: 19.23 MB / 0.9958 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01416/1.656, allocations: 8.642 MB / 1.54 GB, free: 10.52 MB / 0.9958 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.114e-05/1.656, allocations: 39.86 kB / 1.54 GB, free: 10.48 MB / 0.9958 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.14e-05/1.656, allocations: 0 / 1.54 GB, free: 10.48 MB / 0.9958 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003968/1.66, allocations: 1.42 MB / 1.541 GB, free: 9.035 MB / 0.9958 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001526/1.662, allocations: 136 kB / 1.542 GB, free: 8.902 MB / 0.9958 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004905/1.667, allocations: 318.5 kB / 1.542 GB, free: 8.59 MB / 0.9958 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00101/1.668, allocations: 0.6212 MB / 1.542 GB, free: 7.969 MB / 0.9958 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01734/1.685, allocations: 8.224 MB / 1.551 GB, free: 15.87 MB / 1.011 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.07967/1.765, allocations: 41.54 MB / 1.591 GB, free: 6.008 MB / 1.043 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004799/1.769, allocations: 2.668 MB / 1.594 GB, free: 3.324 MB / 1.043 GB
Notification: Performance of sorting global known variables: time 0.005309/1.775, allocations: 3.308 MB / 1.597 GB, free: 40 kB / 1.043 GB
Notification: Performance of Backend: time 5.11e-07/1.775, allocations: 0 / 1.597 GB, free: 40 kB / 1.043 GB
Notification: Performance of simCode: created initialization part: time 0.0481/1.823, allocations: 21.25 MB / 1.618 GB, free: 10.35 MB / 1.074 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.04426/1.867, allocations: 28.7 MB / 1.646 GB, free: 13.62 MB / 1.105 GB
Notification: Performance of Templates: time 0.7512/2.618, allocations: 179.4 MB / 1.821 GB, free: 396.6 MB / 1.246 GB
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.makefile
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=500 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.Models.Type1_N_1_M_1.pipe 2>&1)