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-master/package.mo", uses=false) Using package ScalableTestGrids with version 1.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.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 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.001181/0.001181, allocations: 97.83 kB / 16.95 MB, free: 6.41 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.0013/0.0013, allocations: 187.4 kB / 17.84 MB, free: 5.992 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2.082/2.082, allocations: 205.4 MB / 224 MB, free: 13.18 MB / 190.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.06709/0.06709, allocations: 14.15 MB / 286.4 MB, free: 14.1 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 5.267/5.267, allocations: 463.5 MB / 0.783 GB, free: 108.6 MB / 0.6545 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.0007377/0.0007378, allocations: 19.94 kB / 0.9684 GB, free: 360 kB / 0.7327 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.876/1.877, allocations: 176 MB / 1.14 GB, free: 25.71 MB / 0.8735 GB Notification: Performance of NFInst.instantiate(ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2): time 0.2415/2.118, allocations: 233.7 MB / 1.368 GB, free: 8.605 MB / 1.045 GB Notification: Performance of NFInst.instExpressions: time 0.2348/2.353, allocations: 94.68 MB / 1.461 GB, free: 9.688 MB / 1.139 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.1075/2.461, allocations: 0.9872 MB / 1.462 GB, free: 8.695 MB / 1.139 GB Notification: Performance of NFTyping.typeComponents: time 0.07799/2.539, allocations: 9.866 MB / 1.472 GB, free: 14.77 MB / 1.155 GB Notification: Performance of NFTyping.typeBindings: time 0.1987/2.738, allocations: 34.07 MB / 1.505 GB, free: 12.72 MB / 1.186 GB Notification: Performance of NFTyping.typeClassSections: time 0.04358/2.781, allocations: 8.324 MB / 1.513 GB, free: 4.383 MB / 1.186 GB Notification: Performance of NFFlatten.flatten: time 0.1825/2.964, allocations: 64.89 MB / 1.576 GB, free: 3.363 MB / 1.249 GB Notification: Performance of NFFlatten.resolveConnections: time 0.03729/3.001, allocations: 8.778 MB / 1.585 GB, free: 10.5 MB / 1.264 GB Notification: Performance of NFEvalConstants.evaluate: time 2.226/5.227, allocations: 24.52 MB / 1.609 GB, free: 24.76 MB / 1.265 GB Notification: Performance of NFSimplifyModel.simplify: time 0.09077/5.318, allocations: 25.28 MB / 1.634 GB, free: 24.76 MB / 1.265 GB Notification: Performance of NFPackage.collectConstants: time 0.02959/5.348, allocations: 1.475 MB / 1.635 GB, free: 24.76 MB / 1.265 GB Notification: Performance of NFFlatten.collectFunctions: time 0.06209/5.41, allocations: 4.391 MB / 1.639 GB, free: 24.76 MB / 1.265 GB Notification: Performance of NFScalarize.scalarize: time 0.02858/5.439, allocations: 6.155 MB / 1.645 GB, free: 24.76 MB / 1.265 GB Notification: Performance of NFVerifyModel.verify: time 0.05115/5.49, allocations: 9.666 MB / 1.655 GB, free: 24.73 MB / 1.265 GB Notification: Performance of NFConvertDAE.convert: time 0.1235/5.613, allocations: 50.5 MB / 1.704 GB, free: 14.26 MB / 1.265 GB Notification: Performance of FrontEnd - DAE generated: time 6.402e-06/5.614, allocations: 0 / 1.704 GB, free: 14.26 MB / 1.265 GB Notification: Performance of FrontEnd: time 5.049e-06/5.614, allocations: 0 / 1.704 GB, free: 14.26 MB / 1.265 GB Notification: Performance of Transformations before backend: time 0.004699/5.618, allocations: 0 / 1.704 GB, free: 14.26 MB / 1.265 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.2759/5.894, allocations: 61.02 MB / 1.764 GB, free: 2.18 MB / 1.28 GB Notification: Performance of prepare preOptimizeDAE: time 7.271e-05/5.894, allocations: 7.875 kB / 1.764 GB, free: 2.18 MB / 1.28 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05251/5.947, allocations: 7.437 MB / 1.771 GB, free: 14.86 MB / 1.296 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.1253/6.072, allocations: 27.98 MB / 1.798 GB, free: 4.043 MB / 1.296 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.006244/6.078, allocations: 2.939 MB / 1.801 GB, free: 3.965 MB / 1.296 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.01943/6.098, allocations: 3.903 MB / 1.805 GB, free: 2.547 MB / 1.296 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.1722/6.27, allocations: 40.22 MB / 1.844 GB, free: 6.645 MB / 1.327 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.00376/6.274, allocations: 76.44 kB / 1.844 GB, free: 6.613 MB / 1.327 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.02069/6.295, allocations: 1.57 MB / 1.846 GB, free: 6.262 MB / 1.327 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00205/6.297, allocations: 0.9453 MB / 1.847 GB, free: 5.316 MB / 1.327 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1438/6.441, allocations: 38.8 MB / 1.885 GB, free: 11.89 MB / 1.358 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.5815/7.022, allocations: 246.3 MB / 2.125 GB, free: 15.1 MB / 1.577 GB Notification: Performance of preOpt comSubExp (simulation): time 1.15/8.172, allocations: 62.28 MB / 2.186 GB, free: 0.5744 GB / 1.593 GB Notification: Performance of preOpt evalFunc (simulation): time 0.006075/8.179, allocations: 487.4 kB / 2.186 GB, free: 0.5743 GB / 1.593 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0007273/8.179, allocations: 462.1 kB / 2.187 GB, free: 0.5743 GB / 1.593 GB Notification: Performance of pre-optimization done (n=4107): time 5.386e-05/8.179, allocations: 3 kB / 2.187 GB, free: 0.5743 GB / 1.593 GB Notification: Performance of matching and sorting (n=4115): time 0.3936/8.573, allocations: 91.84 MB / 2.276 GB, free: 0.5435 GB / 1.593 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.000683/8.574, allocations: 1.276 MB / 2.278 GB, free: 0.5424 GB / 1.593 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.09463/8.668, allocations: 24.96 MB / 2.302 GB, free: 0.5339 GB / 1.593 GB Notification: Performance of collectPreVariables (initialization): time 0.0112/8.68, allocations: 0.7406 MB / 2.303 GB, free: 0.5334 GB / 1.593 GB Notification: Performance of collectInitialEqns (initialization): time 0.03238/8.712, allocations: 18.87 MB / 2.321 GB, free: 0.5199 GB / 1.593 GB Notification: Performance of collectInitialBindings (initialization): time 0.03226/8.744, allocations: 14.28 MB / 2.335 GB, free: 0.5089 GB / 1.593 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.01119/8.756, allocations: 0.929 MB / 2.336 GB, free: 0.5081 GB / 1.593 GB Notification: Performance of setup shared object (initialization): time 0.005833/8.761, allocations: 2.085 MB / 2.338 GB, free: 0.507 GB / 1.593 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.04584/8.807, allocations: 14.35 MB / 2.352 GB, free: 0.5015 GB / 1.593 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0598/8.867, allocations: 23.48 MB / 2.375 GB, free: 497.7 MB / 1.593 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.05522/8.922, allocations: 17.74 MB / 2.392 GB, free: 484.3 MB / 1.593 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0005042/8.923, allocations: 64 kB / 2.392 GB, free: 484.2 MB / 1.593 GB Notification: Performance of matching and sorting (n=6081) (initialization): time 0.2491/9.172, allocations: 63.18 MB / 2.454 GB, free: 427.9 MB / 1.593 GB Notification: Performance of prepare postOptimizeDAE: time 0.06787/9.24, allocations: 208.6 MB / 2.658 GB, free: 209.6 MB / 1.593 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001376/9.241, allocations: 223.7 kB / 2.658 GB, free: 209.4 MB / 1.593 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1238/9.365, allocations: 26.23 MB / 2.684 GB, free: 187.5 MB / 1.593 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04138/9.407, allocations: 8.683 MB / 2.692 GB, free: 178.8 MB / 1.593 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.692/11.1, allocations: 234 MB / 2.921 GB, free: 0.4936 GB / 1.593 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0641/11.16, allocations: 2.513 MB / 2.923 GB, free: 0.4929 GB / 1.593 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01468/11.18, allocations: 1.648 MB / 2.925 GB, free: 0.4925 GB / 1.593 GB Notification: Performance of prepare postOptimizeDAE: time 2.241e-05/11.18, allocations: 3.688 kB / 2.925 GB, free: 0.4925 GB / 1.593 GB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.2015/11.38, allocations: 51.1 MB / 2.975 GB, free: 483.9 MB / 1.593 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001614/11.38, allocations: 218.8 kB / 2.975 GB, free: 483.9 MB / 1.593 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.1094/11.49, allocations: 22.54 MB / 2.997 GB, free: 481.5 MB / 1.593 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0494/11.54, allocations: 9.253 MB / 3.006 GB, free: 480.7 MB / 1.593 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.4665/12.01, allocations: 188.3 MB / 3.19 GB, free: 323.6 MB / 1.593 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.06996/12.08, allocations: 1.968 MB / 3.192 GB, free: 322.1 MB / 1.593 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01652/12.09, allocations: 1.644 MB / 3.193 GB, free: 320.5 MB / 1.593 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 (linear and non-linear blocks): 0 * Torn equation systems: 74 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 32 {(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0,(2,100.0%) 0,(2,100.0%) 0,(3,66.7%) 0,(3,66.7%) 0} * Non-linear torn systems: 42 {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.06285/12.16, allocations: 7.068 MB / 3.2 GB, free: 313.6 MB / 1.593 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.03322/12.19, allocations: 4.007 MB / 3.204 GB, free: 309.5 MB / 1.593 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1908/12.38, allocations: 44.89 MB / 3.248 GB, free: 264.6 MB / 1.593 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001676/12.38, allocations: 199.2 kB / 3.248 GB, free: 264.4 MB / 1.593 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001603/12.38, allocations: 0 / 3.248 GB, free: 264.4 MB / 1.593 GB Notification: Performance of postOpt removeConstants (simulation): time 0.04369/12.43, allocations: 6.794 MB / 3.255 GB, free: 257.5 MB / 1.593 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0281/12.45, allocations: 0.6836 MB / 3.255 GB, free: 256.8 MB / 1.593 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.05592/12.51, allocations: 1.674 MB / 3.257 GB, free: 255.3 MB / 1.593 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01708/12.53, allocations: 3.252 MB / 3.26 GB, free: 252 MB / 1.593 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.1891/12.72, allocations: 42.69 MB / 3.302 GB, free: 210.1 MB / 1.593 GB Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.1877/12.9, allocations: 48.25 MB / 3.349 GB, free: 161.7 MB / 1.593 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.04305/12.95, allocations: 13.49 MB / 3.362 GB, free: 148.2 MB / 1.593 GB Notification: Performance of sorting global known variables: time 0.05669/13, allocations: 15.84 MB / 3.378 GB, free: 132.5 MB / 1.593 GB Notification: Performance of Backend: time 3.396e-06/13, allocations: 0 / 3.378 GB, free: 132.5 MB / 1.593 GB Notification: Performance of simCode: created initialization part: time 1.487/14.49, allocations: 106.8 MB / 3.482 GB, free: 472.6 MB / 1.593 GB Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is set to 'isProtected' (=false) by default. Notification: Performance of SimCode: time 0.3465/14.84, allocations: 181.3 MB / 3.659 GB, free: 342.1 MB / 1.593 GB Notification: Performance of Templates: time 2.954/17.79, allocations: 0.7087 GB / 4.368 GB, free: 233 MB / 1.593 GB make -j1 -f ScalableTestGrids_noopt_ScalableTestGrids.GridModelBuilders.Type1Sample_N_2_M_2.makefile Environment - environmentSimulation: (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) Environment - environmentSimulation: