Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.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 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.1.0/package.mo", uses=false) Using package ScalableTestGrids with version 1.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0/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 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 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(ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2") translateModel(ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008161/0.0008161, allocations: 100.1 kB / 17.32 MB, free: 6.344 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.001028/0.001028, allocations: 183.8 kB / 18.22 MB, free: 5.516 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.41/1.41, allocations: 205.4 MB / 224.4 MB, free: 13.06 MB / 190.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.06045/0.06045, allocations: 14.17 MB / 286.9 MB, free: 13.88 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0/package.mo): time 2.927/2.927, allocations: 421.4 MB / 0.7423 GB, free: 4.152 MB / 0.5764 GB Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Performance of FrontEnd - loaded program: time 0.0006509/0.000651, allocations: 24.38 kB / 0.917 GB, free: 9.727 MB / 0.6548 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.152/1.152, allocations: 164.7 MB / 1.078 GB, free: 14.68 MB / 0.7959 GB Notification: Performance of NFInst.instantiate(ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2): time 0.2272/1.38, allocations: 278.6 MB / 1.35 GB, free: 14.72 MB / 1.046 GB Notification: Performance of NFInst.instExpressions: time 0.1828/1.562, allocations: 98.35 MB / 1.446 GB, free: 12.05 MB / 1.14 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.07455/1.637, allocations: 1.03 MB / 1.447 GB, free: 11.01 MB / 1.14 GB Notification: Performance of NFTyping.typeComponents: time 0.05732/1.694, allocations: 12.47 MB / 1.459 GB, free: 14.48 MB / 1.155 GB Notification: Performance of NFTyping.typeBindings: time 0.1425/1.837, allocations: 34.15 MB / 1.492 GB, free: 12.29 MB / 1.187 GB Notification: Performance of NFTyping.typeClassSections: time 0.04032/1.877, allocations: 11.36 MB / 1.504 GB, free: 0.9141 MB / 1.187 GB Notification: Performance of NFFlatten.flatten: time 0.8951/2.772, allocations: 94.2 MB / 1.596 GB, free: 35.41 MB / 1.187 GB Notification: Performance of NFFlatten.resolveConnections: time 0.04514/2.818, allocations: 10.58 MB / 1.606 GB, free: 48.48 MB / 1.202 GB Notification: Performance of NFEvalConstants.evaluate: time 0.07204/2.89, allocations: 23.55 MB / 1.629 GB, free: 46.08 MB / 1.202 GB Notification: Performance of NFSimplifyModel.simplify: time 0.06457/2.954, allocations: 27.11 MB / 1.655 GB, free: 35.48 MB / 1.202 GB Notification: Performance of NFPackage.collectConstants: time 0.02095/2.975, allocations: 1.477 MB / 1.657 GB, free: 34 MB / 1.202 GB Notification: Performance of NFFlatten.collectFunctions: time 0.04907/3.024, allocations: 4.079 MB / 1.661 GB, free: 30.44 MB / 1.202 GB Notification: Performance of NFScalarize.scalarize: time 0.01827/3.043, allocations: 6.48 MB / 1.667 GB, free: 27.05 MB / 1.202 GB Notification: Performance of NFVerifyModel.verify: time 0.07461/3.117, allocations: 25.18 MB / 1.692 GB, free: 9.898 MB / 1.202 GB Notification: Performance of NFConvertDAE.convert: time 0.1295/3.247, allocations: 62.53 MB / 1.753 GB, free: 4.98 MB / 1.249 GB Notification: Performance of FrontEnd - DAE generated: time 8.356e-06/3.247, allocations: 0 / 1.753 GB, free: 4.98 MB / 1.249 GB Notification: Performance of FrontEnd: time 4.95e-06/3.247, allocations: 0 / 1.753 GB, free: 4.98 MB / 1.249 GB Notification: Performance of Transformations before backend: time 0.003114/3.25, allocations: 3.938 kB / 1.753 GB, free: 4.977 MB / 1.249 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 9966 * Number of variables: 9966 Notification: Performance of Generate backend data structure: time 0.254/3.504, allocations: 60.59 MB / 1.812 GB, free: 9.211 MB / 1.312 GB Notification: Performance of prepare preOptimizeDAE: time 8.205e-05/3.504, allocations: 16.02 kB / 1.812 GB, free: 9.195 MB / 1.312 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04837/3.553, allocations: 7.437 MB / 1.819 GB, free: 1.691 MB / 1.312 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.1032/3.656, allocations: 29.08 MB / 1.848 GB, free: 5.004 MB / 1.343 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.005037/3.661, allocations: 2.939 MB / 1.85 GB, free: 2.062 MB / 1.343 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.01779/3.679, allocations: 3.902 MB / 1.854 GB, free: 14.16 MB / 1.358 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.1343/3.813, allocations: 40.83 MB / 1.894 GB, free: 3.668 MB / 1.39 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.002147/3.815, allocations: 83.23 kB / 1.894 GB, free: 3.586 MB / 1.39 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01202/3.827, allocations: 1.574 MB / 1.896 GB, free: 2.012 MB / 1.39 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001007/3.828, allocations: 0.9492 MB / 1.897 GB, free: 1.062 MB / 1.39 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1218/3.95, allocations: 39.09 MB / 1.935 GB, free: 9.945 MB / 1.437 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 1.128/5.079, allocations: 246.4 MB / 2.175 GB, free: 471.4 MB / 1.53 GB Notification: Performance of preOpt comSubExp (simulation): time 0.1327/5.211, allocations: 62.58 MB / 2.237 GB, free: 436.2 MB / 1.53 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0481/5.259, allocations: 18.36 MB / 2.254 GB, free: 428.1 MB / 1.53 GB Notification: Performance of preOpt evalFunc (simulation): time 0.005018/5.264, allocations: 486.3 kB / 2.255 GB, free: 428 MB / 1.53 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0009355/5.265, allocations: 462.1 kB / 2.255 GB, free: 427.7 MB / 1.53 GB Notification: Performance of pre-optimization done (n=4107): time 0.0003173/5.266, allocations: 0 / 2.255 GB, free: 427.7 MB / 1.53 GB Notification: Performance of matching and sorting (n=4115): time 0.3207/5.586, allocations: 92.51 MB / 2.346 GB, free: 374.2 MB / 1.53 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0005454/5.587, allocations: 1.279 MB / 2.347 GB, free: 372.7 MB / 1.53 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.06619/5.653, allocations: 25.23 MB / 2.372 GB, free: 347.9 MB / 1.53 GB Notification: Performance of collectPreVariables (initialization): time 0.01201/5.665, allocations: 0.7399 MB / 2.372 GB, free: 347.2 MB / 1.53 GB Notification: Performance of collectInitialEqns (initialization): time 0.0278/5.693, allocations: 18.87 MB / 2.391 GB, free: 329.2 MB / 1.53 GB Notification: Performance of collectInitialBindings (initialization): time 0.02424/5.718, allocations: 14.28 MB / 2.405 GB, free: 315.1 MB / 1.53 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.01532/5.733, allocations: 3.685 MB / 2.408 GB, free: 311.4 MB / 1.53 GB Notification: Performance of setup shared object (initialization): time 7.289e-05/5.733, allocations: 309.1 kB / 2.409 GB, free: 311.1 MB / 1.53 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.03921/5.772, allocations: 14.56 MB / 2.423 GB, free: 296.5 MB / 1.53 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.05336/5.826, allocations: 23.87 MB / 2.446 GB, free: 269.3 MB / 1.53 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.1033/5.929, allocations: 39.17 MB / 2.484 GB, free: 227 MB / 1.53 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003047/5.929, allocations: 65.59 kB / 2.484 GB, free: 227 MB / 1.53 GB Notification: Performance of matching and sorting (n=6081) (initialization): time 0.1808/6.11, allocations: 63.7 MB / 2.547 GB, free: 163 MB / 1.53 GB Notification: Performance of prepare postOptimizeDAE: time 0.003729/6.114, allocations: 6.953 MB / 2.553 GB, free: 152.9 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004371/6.114, allocations: 220 kB / 2.554 GB, free: 152.7 MB / 1.53 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1308/6.245, allocations: 28.31 MB / 2.581 GB, free: 124.2 MB / 1.53 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03833/6.283, allocations: 8.691 MB / 2.59 GB, free: 115.5 MB / 1.53 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.151/7.434, allocations: 228.4 MB / 2.813 GB, free: 488.1 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05016/7.484, allocations: 2.513 MB / 2.815 GB, free: 487.4 MB / 1.53 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.009841/7.494, allocations: 1.648 MB / 2.817 GB, free: 487 MB / 1.53 GB Notification: Performance of prepare postOptimizeDAE: time 1.547e-05/7.494, allocations: 7.719 kB / 2.817 GB, free: 487 MB / 1.53 GB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.1664/7.661, allocations: 51.56 MB / 2.867 GB, free: 466.4 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001024/7.662, allocations: 222.2 kB / 2.867 GB, free: 466.4 MB / 1.53 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1078/7.769, allocations: 22.58 MB / 2.89 GB, free: 461.2 MB / 1.53 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04167/7.811, allocations: 9.256 MB / 2.899 GB, free: 456.1 MB / 1.53 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3285/8.14, allocations: 184.3 MB / 3.079 GB, free: 264.6 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05112/8.191, allocations: 1.965 MB / 3.081 GB, free: 262.8 MB / 1.53 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01086/8.202, allocations: 1.644 MB / 3.082 GB, free: 261.2 MB / 1.53 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 1007 * Number of states: 0 () * Number of discrete variables: 40 (GEN_4_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_2.AVR.firstOrderLim.I.local_reset,GEN_4_2.TGOV.firstOrderLim.I.local_reset,GEN_4_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_4_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_4_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_4_1.AVR.firstOrderLim.I.local_reset,GEN_4_1.TGOV.firstOrderLim.I.local_reset,GEN_3_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_2.AVR.firstOrderLim.I.local_reset,GEN_3_2.TGOV.firstOrderLim.I.local_reset,GEN_3_1.PSS.firstWashoutVsi2.integrator.local_reset,GEN_3_1.PSS.secondWashoutVsi1.integrator.local_reset,GEN_3_1.PSS.firstWashoutVsi1.integrator.local_reset,GEN_3_1.AVR.firstOrderLim.I.local_reset,GEN_3_1.TGOV.firstOrderLim.I.local_reset,GEN_2_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_2_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_2_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_2_2.AVR.firstOrderLim.I.local_reset,GEN_2_2.TGOV.firstOrderLim.I.local_reset,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_2.PSS.firstWashoutVsi2.integrator.local_reset,GEN_1_2.PSS.secondWashoutVsi1.integrator.local_reset,GEN_1_2.PSS.firstWashoutVsi1.integrator.local_reset,GEN_1_2.AVR.firstOrderLim.I.local_reset,GEN_1_2.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 (4729): * Single equations (assignments): 4655 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 74 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 32 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%), (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%), (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%), (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): 42 systems {(38,0), (1164,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,0), (2,0), (2,0), (2,0), (2,0), (10,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.03781/8.24, allocations: 7.071 MB / 3.089 GB, free: 254.2 MB / 1.53 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01692/8.257, allocations: 3.999 MB / 3.093 GB, free: 250.2 MB / 1.53 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1409/8.398, allocations: 45.2 MB / 3.137 GB, free: 204.9 MB / 1.53 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001441/8.399, allocations: 199.2 kB / 3.137 GB, free: 204.7 MB / 1.53 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001492/8.399, allocations: 0 / 3.137 GB, free: 204.7 MB / 1.53 GB Notification: Performance of postOpt removeConstants (simulation): time 0.03462/8.434, allocations: 6.804 MB / 3.144 GB, free: 197.8 MB / 1.53 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01959/8.453, allocations: 0.6797 MB / 3.145 GB, free: 197.1 MB / 1.53 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.04176/8.495, allocations: 1.677 MB / 3.146 GB, free: 195.6 MB / 1.53 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01149/8.507, allocations: 3.252 MB / 3.149 GB, free: 192.4 MB / 1.53 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.1457/8.653, allocations: 42.97 MB / 3.191 GB, free: 150.1 MB / 1.53 GB Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.133/8.786, allocations: 48.63 MB / 3.239 GB, free: 101.4 MB / 1.53 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.9334/9.719, allocations: 13.62 MB / 3.252 GB, free: 477.6 MB / 1.53 GB Notification: Performance of sorting global known variables: time 0.05514/9.774, allocations: 16.11 MB / 3.268 GB, free: 476.7 MB / 1.53 GB Notification: Performance of Backend: time 2.434e-06/9.774, allocations: 0 / 3.268 GB, free: 476.7 MB / 1.53 GB Notification: Performance of simCode: created initialization part: time 0.2996/10.07, allocations: 108.3 MB / 3.374 GB, free: 446.2 MB / 1.53 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.3009/10.37, allocations: 104.1 MB / 3.475 GB, free: 388.1 MB / 1.53 GB Notification: Performance of Templates: time 2.426/12.8, allocations: 0.7283 GB / 4.204 GB, free: 162 MB / 1.53 GB make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.makefile (rm -f ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.pipe ; mkfifo ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.pipe ; head -c 1048576 < ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.pipe >> ../files/ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.sim & ./ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2 -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=480 -lv LOG_STATS > ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.pipe 2>&1)