Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_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.GridModelBuilders.Type1Sample_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2")
translateModel(ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_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.001374/0.001374, allocations: 192.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.228/1.228, 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.0009453/0.0009453, allocations: 102 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.07275/0.07275, allocations: 14.16 MB / 332 MB, free: 10.81 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.0.0-master/package.mo): time 2.449/2.449, allocations: 418.5 MB / 0.7826 GB, free: 13.87 MB / 0.5575 GB
Notification: Performance of FrontEnd - loaded program: time 0.00124/0.00124, allocations: 73.05 kB / 0.9536 GB, free: 74.55 MB / 0.714 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.221/0.2223, allocations: 160.9 MB / 1.111 GB, free: 6.676 MB / 0.7765 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2): time 0.04662/0.269, allocations: 64.69 MB / 1.174 GB, free: 5.727 MB / 0.839 GB
Notification: Performance of NFInst.instExpressions: time 0.03417/0.3032, allocations: 23.34 MB / 1.197 GB, free: 14.31 MB / 0.8703 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0119/0.3151, allocations: 246.8 kB / 1.197 GB, free: 14.07 MB / 0.8703 GB
Notification: Performance of NFTyping.typeComponents: time 0.01079/0.326, allocations: 2.794 MB / 1.2 GB, free: 11.26 MB / 0.8703 GB
Notification: Performance of NFTyping.typeBindings: time 0.02539/0.3514, allocations: 8.056 MB / 1.207 GB, free: 3.238 MB / 0.8703 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009656/0.3611, allocations: 3.027 MB / 1.21 GB, free: 216 kB / 0.8703 GB
Notification: Performance of NFFlatten.flatten: time 0.03047/0.3916, allocations: 21.06 MB / 1.231 GB, free: 11.07 MB / 0.9015 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00494/0.3966, allocations: 2.384 MB / 1.233 GB, free: 8.613 MB / 0.9015 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01272/0.4093, allocations: 5.476 MB / 1.239 GB, free: 3.109 MB / 0.9015 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01097/0.4203, allocations: 6.205 MB / 1.245 GB, free: 12.87 MB / 0.9171 GB
Notification: Performance of NFPackage.collectConstants: time 0.002812/0.4232, allocations: 416 kB / 1.245 GB, free: 12.46 MB / 0.9171 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007275/0.4305, allocations: 1.156 MB / 1.246 GB, free: 11.31 MB / 0.9171 GB
Notification: Performance of NFScalarize.scalarize: time 0.002349/0.4329, allocations: 1.577 MB / 1.248 GB, free: 9.727 MB / 0.9171 GB
Notification: Performance of NFVerifyModel.verify: time 0.01007/0.443, allocations: 5.745 MB / 1.253 GB, free: 3.949 MB / 0.9171 GB
Notification: Performance of NFConvertDAE.convert: time 0.01892/0.4619, allocations: 14.61 MB / 1.268 GB, free: 5.297 MB / 0.9328 GB
Notification: Performance of FrontEnd - DAE generated: time 5.46e-06/0.462, allocations: 0 / 1.268 GB, free: 5.297 MB / 0.9328 GB
Notification: Performance of FrontEnd: time 1.623e-06/0.462, allocations: 4 kB / 1.268 GB, free: 5.293 MB / 0.9328 GB
Notification: Performance of Transformations before backend: time 0.0004899/0.4625, allocations: 0 / 1.268 GB, free: 5.293 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.05095/0.5134, allocations: 15.61 MB / 1.283 GB, free: 5.566 MB / 0.9484 GB
Notification: Performance of prepare preOptimizeDAE: time 4.678e-05/0.5135, allocations: 8.031 kB / 1.283 GB, free: 5.559 MB / 0.9484 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007405/0.5209, allocations: 1.65 MB / 1.285 GB, free: 3.895 MB / 0.9484 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01727/0.5382, allocations: 6.638 MB / 1.291 GB, free: 13.16 MB / 0.964 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007101/0.539, allocations: 0.6574 MB / 1.292 GB, free: 12.5 MB / 0.964 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002382/0.5414, allocations: 0.8632 MB / 1.292 GB, free: 11.64 MB / 0.964 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02158/0.563, allocations: 8.98 MB / 1.301 GB, free: 2.25 MB / 0.964 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003622/0.5634, allocations: 19.81 kB / 1.301 GB, free: 2.23 MB / 0.964 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001823/0.5652, allocations: 356 kB / 1.302 GB, free: 1.883 MB / 0.964 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001904/0.5654, allocations: 212 kB / 1.302 GB, free: 1.676 MB / 0.964 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0186/0.584, allocations: 8.512 MB / 1.31 GB, free: 9.145 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.8385/1.423, allocations: 53.74 MB / 1.363 GB, free: 135.3 MB / 0.9801 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02162/1.444, allocations: 10.66 MB / 1.373 GB, free: 129.3 MB / 0.9801 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008267/1.453, allocations: 4.012 MB / 1.377 GB, free: 127.3 MB / 0.9801 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007075/1.453, allocations: 110.1 kB / 1.377 GB, free: 127.3 MB / 0.9801 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001226/1.453, allocations: 125.1 kB / 1.377 GB, free: 127.2 MB / 0.9801 GB
Notification: Performance of pre-optimization done (n=909): time 1.88e-05/1.453, allocations: 0 / 1.377 GB, free: 127.2 MB / 0.9801 GB
Notification: Performance of matching and sorting (n=911): time 0.04227/1.496, allocations: 20.42 MB / 1.397 GB, free: 116.6 MB / 0.9801 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001211/1.496, allocations: 351.2 kB / 1.397 GB, free: 116.2 MB / 0.9801 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00939/1.505, allocations: 5.792 MB / 1.403 GB, free: 113.3 MB / 0.9801 GB
Notification: Performance of collectPreVariables (initialization): time 0.001502/1.507, allocations: 189.4 kB / 1.403 GB, free: 113.1 MB / 0.9801 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002251/1.509, allocations: 4.057 MB / 1.407 GB, free: 109.7 MB / 0.9801 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00261/1.512, allocations: 3.144 MB / 1.41 GB, free: 107 MB / 0.9801 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002114/1.514, allocations: 1.733 MB / 1.412 GB, free: 106 MB / 0.9801 GB
Notification: Performance of setup shared object (initialization): time 4.938e-05/1.514, allocations: 305.1 kB / 1.412 GB, free: 105.7 MB / 0.9801 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004972/1.519, allocations: 3.271 MB / 1.415 GB, free: 104 MB / 0.9801 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005861/1.525, allocations: 5.267 MB / 1.421 GB, free: 99.98 MB / 0.9801 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01108/1.536, allocations: 8.59 MB / 1.429 GB, free: 94.24 MB / 0.9801 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.055e-05/1.536, allocations: 19 kB / 1.429 GB, free: 94.24 MB / 0.9801 GB
Notification: Performance of matching and sorting (n=1353) (initialization): time 0.02564/1.562, allocations: 13.88 MB / 1.443 GB, free: 82.63 MB / 0.9801 GB
Notification: Performance of prepare postOptimizeDAE: time 4.844e-05/1.562, allocations: 24 kB / 1.443 GB, free: 82.61 MB / 0.9801 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.409e-05/1.562, allocations: 48 kB / 1.443 GB, free: 82.56 MB / 0.9801 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02648/1.588, allocations: 6.183 MB / 1.449 GB, free: 76.33 MB / 0.9801 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007587/1.596, allocations: 1.954 MB / 1.451 GB, free: 74.37 MB / 0.9801 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06772/1.663, allocations: 53.43 MB / 1.503 GB, free: 18.27 MB / 0.9801 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008612/1.672, allocations: 0.529 MB / 1.503 GB, free: 17.73 MB / 0.9801 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001538/1.674, allocations: 376 kB / 1.504 GB, free: 17.37 MB / 0.9801 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008982/1.683, allocations: 4.258 MB / 1.508 GB, free: 13.1 MB / 0.9801 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006673/1.689, allocations: 5.032 MB / 1.513 GB, free: 7.344 MB / 0.9801 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01313/1.703, allocations: 8.268 MB / 1.521 GB, free: 14.36 MB / 0.9958 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.549e-05/1.703, allocations: 15.94 kB / 1.521 GB, free: 14.34 MB / 0.9958 GB
Notification: Performance of matching and sorting (n=1353) (initialization_lambda0): time 0.02417/1.727, allocations: 12.19 MB / 1.533 GB, free: 2.02 MB / 0.9958 GB
Notification: Performance of prepare postOptimizeDAE: time 4.147e-05/1.727, allocations: 24 kB / 1.533 GB, free: 1.996 MB / 0.9958 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.23e-05/1.727, allocations: 48 kB / 1.533 GB, free: 1.949 MB / 0.9958 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02389/1.751, allocations: 5.107 MB / 1.538 GB, free: 12.8 MB / 1.011 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008257/1.759, allocations: 2.102 MB / 1.54 GB, free: 10.7 MB / 1.011 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05385/1.813, allocations: 43.79 MB / 1.583 GB, free: 12.51 MB / 1.058 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008231/1.821, allocations: 462 kB / 1.583 GB, free: 12.06 MB / 1.058 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008608/1.822, allocations: 368 kB / 1.583 GB, free: 11.7 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.001711/1.824, allocations: 0.7539 MB / 1.584 GB, free: 10.94 MB / 1.058 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001755/1.826, allocations: 0.8955 MB / 1.585 GB, free: 10.03 MB / 1.058 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0179/1.844, allocations: 10 MB / 1.595 GB, free: 15.98 MB / 1.074 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.676e-05/1.844, allocations: 47.78 kB / 1.595 GB, free: 15.94 MB / 1.074 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.258e-05/1.844, allocations: 0 / 1.595 GB, free: 15.94 MB / 1.074 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003905/1.848, allocations: 1.587 MB / 1.596 GB, free: 14.32 MB / 1.074 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001063/1.849, allocations: 156 kB / 1.597 GB, free: 14.17 MB / 1.074 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00433/1.853, allocations: 358.3 kB / 1.597 GB, free: 13.82 MB / 1.074 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00107/1.854, allocations: 0.7283 MB / 1.598 GB, free: 13.09 MB / 1.074 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02225/1.876, allocations: 9.411 MB / 1.607 GB, free: 3.855 MB / 1.074 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0804/1.957, allocations: 46.57 MB / 1.652 GB, free: 5.016 MB / 1.121 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004461/1.961, allocations: 3.008 MB / 1.655 GB, free: 2 MB / 1.121 GB
Notification: Performance of sorting global known variables: time 0.004783/1.966, allocations: 3.675 MB / 1.659 GB, free: 14.37 MB / 1.136 GB
Notification: Performance of Backend: time 3.7e-07/1.966, allocations: 0 / 1.659 GB, free: 14.37 MB / 1.136 GB
Notification: Performance of simCode: created initialization part: time 0.04985/2.016, allocations: 23.73 MB / 1.682 GB, free: 6.16 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.04511/2.061, allocations: 32.73 MB / 1.714 GB, free: 5.355 MB / 1.183 GB
Notification: Performance of Templates: time 0.7457/2.807, allocations: 204.5 MB / 1.914 GB, free: 343.3 MB / 1.261 GB
make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.makefile
(rm -f ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=500 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_1_M_2.pipe 2>&1)