Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.conf.json
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/ModelicaServices 4.0.0+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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+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 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Running command: translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1")
translateModel(ScalableTestGrids.Models.Type1.Type1_N_1_M_1,tolerance=0.0001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0009168/0.0009168, allocations: 189.8 kB / 17.33 MB, free: 6.16 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 0.3216/0.3216, allocations: 205.6 MB / 223.6 MB, free: 15.53 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009093/0.0009093, allocations: 119 kB / 271.1 MB, free: 10.53 MB / 222.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.2/package.mo): time 0.01548/0.01548, allocations: 14.49 MB / 332.9 MB, free: 12.66 MB / 286.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ScalableTestGrids 1.1.0-master/package.mo): time 1.321/1.321, allocations: 438.4 MB / 0.8028 GB, free: 12.5 MB / 0.6233 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.001193/0.001193, allocations: 75.89 kB / 0.9789 GB, free: 32.39 MB / 0.686 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1765/0.1777, allocations: 166.1 MB / 1.141 GB, free: 10.04 MB / 0.8267 GB
Notification: Performance of NFInst.instantiate(ScalableTestGrids.Models.Type1.Type1_N_1_M_1): time 0.03542/0.2131, allocations: 56.31 MB / 1.196 GB, free: 1.5 MB / 0.8735 GB
Notification: Performance of NFInst.instExpressions: time 0.7316/0.9448, allocations: 20.54 MB / 1.216 GB, free: 14.34 MB / 0.8897 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00723/0.952, allocations: 224.2 kB / 1.216 GB, free: 14.34 MB / 0.8897 GB
Notification: Performance of NFTyping.typeComponents: time 0.005153/0.9572, allocations: 2.431 MB / 1.219 GB, free: 14.3 MB / 0.8897 GB
Notification: Performance of NFTyping.typeBindings: time 0.01459/0.9718, allocations: 6.98 MB / 1.226 GB, free: 14.04 MB / 0.8897 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004455/0.9763, allocations: 2.894 MB / 1.228 GB, free: 13.96 MB / 0.8897 GB
Notification: Performance of NFFlatten.flatten: time 0.01837/0.9947, allocations: 19.31 MB / 1.247 GB, free: 8.391 MB / 0.8897 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002816/0.9975, allocations: 2.368 MB / 1.249 GB, free: 7.262 MB / 0.8897 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00691/1.004, allocations: 5.629 MB / 1.255 GB, free: 4.379 MB / 0.8897 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004974/1.009, allocations: 5.989 MB / 1.261 GB, free: 1.27 MB / 0.8897 GB
Notification: Performance of NFPackage.collectConstants: time 0.0009528/1.01, allocations: 0.8336 MB / 1.262 GB, free: 1.266 MB / 0.8897 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003366/1.014, allocations: 1.71 MB / 1.263 GB, free: 1.082 MB / 0.8897 GB
Notification: Performance of NFScalarize.scalarize: time 0.001009/1.015, allocations: 1.876 MB / 1.265 GB, free: 15.5 MB / 0.9053 GB
Notification: Performance of NFVerifyModel.verify: time 0.004583/1.019, allocations: 5.716 MB / 1.271 GB, free: 11.47 MB / 0.9053 GB
Notification: Performance of NFConvertDAE.convert: time 0.01093/1.03, allocations: 13.56 MB / 1.284 GB, free: 2.254 MB / 0.9053 GB
Notification: Performance of FrontEnd - DAE generated: time 5.39e-06/1.03, allocations: 0 / 1.284 GB, free: 2.254 MB / 0.9053 GB
Notification: Performance of FrontEnd: time 1.763e-06/1.03, allocations: 5.5 kB / 1.284 GB, free: 2.25 MB / 0.9053 GB
Notification: Performance of Transformations before backend: time 0.0001789/1.031, allocations: 0 / 1.284 GB, free: 2.25 MB / 0.9053 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.0344/1.065, allocations: 13.8 MB / 1.297 GB, free: 9.77 MB / 0.9209 GB
Notification: Performance of prepare preOptimizeDAE: time 3.783e-05/1.065, allocations: 9.047 kB / 1.297 GB, free: 9.77 MB / 0.9209 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004319/1.069, allocations: 1.437 MB / 1.299 GB, free: 9.059 MB / 0.9209 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.007992/1.077, allocations: 5.939 MB / 1.305 GB, free: 4.691 MB / 0.9209 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002688/1.078, allocations: 0.575 MB / 1.305 GB, free: 4.426 MB / 0.9209 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007714/1.078, allocations: 0.7755 MB / 1.306 GB, free: 4.156 MB / 0.9209 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009935/1.088, allocations: 7.882 MB / 1.314 GB, free: 15.17 MB / 0.9365 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001084/1.088, allocations: 13.62 kB / 1.314 GB, free: 15.16 MB / 0.9365 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004906/1.089, allocations: 310.8 kB / 1.314 GB, free: 14.93 MB / 0.9365 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.167e-05/1.089, allocations: 188.8 kB / 1.314 GB, free: 14.93 MB / 0.9365 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009977/1.099, allocations: 7.499 MB / 1.322 GB, free: 9.508 MB / 0.9365 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.03988/1.139, allocations: 46.18 MB / 1.367 GB, free: 7.547 MB / 0.9834 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01135/1.15, allocations: 9.329 MB / 1.376 GB, free: 14.12 MB / 0.999 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004264/1.155, allocations: 3.539 MB / 1.379 GB, free: 10.57 MB / 0.999 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000305/1.155, allocations: 104.9 kB / 1.379 GB, free: 10.47 MB / 0.999 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.47e-05/1.155, allocations: 120.7 kB / 1.379 GB, free: 10.34 MB / 0.999 GB
Notification: Performance of pre-optimization done (n=787): time 8.415e-06/1.155, allocations: 0 / 1.379 GB, free: 10.34 MB / 0.999 GB
Notification: Performance of matching and sorting (n=789): time 0.02668/1.182, allocations: 17.82 MB / 1.397 GB, free: 8.387 MB / 1.015 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001178/1.182, allocations: 343.2 kB / 1.397 GB, free: 7.988 MB / 1.015 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005053/1.187, allocations: 5.209 MB / 1.402 GB, free: 2.801 MB / 1.015 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003324/1.187, allocations: 177.6 kB / 1.402 GB, free: 2.621 MB / 1.015 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001916/1.189, allocations: 3.59 MB / 1.406 GB, free: 15.03 MB / 1.03 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001555/1.191, allocations: 2.729 MB / 1.409 GB, free: 12.32 MB / 1.03 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001765/1.192, allocations: 1.55 MB / 1.41 GB, free: 10.76 MB / 1.03 GB
Notification: Performance of setup shared object (initialization): time 6.358e-05/1.192, allocations: 305.1 kB / 1.41 GB, free: 10.46 MB / 1.03 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004194/1.197, allocations: 2.936 MB / 1.413 GB, free: 7.504 MB / 1.03 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005094/1.202, allocations: 4.741 MB / 1.418 GB, free: 2.102 MB / 1.03 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009628/1.211, allocations: 7.645 MB / 1.425 GB, free: 9.855 MB / 1.046 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.076e-05/1.211, allocations: 12 kB / 1.425 GB, free: 9.844 MB / 1.046 GB
Notification: Performance of matching and sorting (n=1197) (initialization): time 0.02095/1.232, allocations: 12.41 MB / 1.437 GB, free: 13.36 MB / 1.062 GB
Notification: Performance of prepare postOptimizeDAE: time 3.444e-05/1.232, allocations: 24 kB / 1.437 GB, free: 13.34 MB / 1.062 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.097e-05/1.232, allocations: 44 kB / 1.438 GB, free: 13.29 MB / 1.062 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02159/1.254, allocations: 5.537 MB / 1.443 GB, free: 7.703 MB / 1.062 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005418/1.259, allocations: 1.747 MB / 1.445 GB, free: 5.953 MB / 1.062 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05845/1.318, allocations: 53.9 MB / 1.497 GB, free: 13.19 MB / 1.124 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006174/1.324, allocations: 482 kB / 1.498 GB, free: 12.72 MB / 1.124 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004168/1.325, allocations: 332 kB / 1.498 GB, free: 12.39 MB / 1.124 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005513/1.33, allocations: 3.803 MB / 1.502 GB, free: 8.582 MB / 1.124 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00444/1.335, allocations: 4.502 MB / 1.506 GB, free: 3.418 MB / 1.124 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.008549/1.343, allocations: 7.322 MB / 1.513 GB, free: 11.5 MB / 1.14 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.942e-05/1.343, allocations: 12 kB / 1.513 GB, free: 11.48 MB / 1.14 GB
Notification: Performance of matching and sorting (n=1197) (initialization_lambda0): time 0.01612/1.359, allocations: 10.73 MB / 1.524 GB, free: 0.6914 MB / 1.14 GB
Notification: Performance of prepare postOptimizeDAE: time 2.708e-05/1.359, allocations: 24 kB / 1.524 GB, free: 0.668 MB / 1.14 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.316e-05/1.359, allocations: 47.98 kB / 1.524 GB, free: 0.6211 MB / 1.14 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01771/1.377, allocations: 4.44 MB / 1.528 GB, free: 12.13 MB / 1.155 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005786/1.383, allocations: 1.892 MB / 1.53 GB, free: 10.24 MB / 1.155 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04086/1.424, allocations: 43.39 MB / 1.572 GB, free: 12.28 MB / 1.202 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005913/1.43, allocations: 410.3 kB / 1.573 GB, free: 11.88 MB / 1.202 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004007/1.43, allocations: 332 kB / 1.573 GB, free: 11.55 MB / 1.202 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.001149/1.431, allocations: 0.6765 MB / 1.574 GB, free: 10.87 MB / 1.202 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008608/1.432, allocations: 0.7951 MB / 1.575 GB, free: 10.06 MB / 1.202 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0131/1.445, allocations: 8.638 MB / 1.583 GB, free: 1.363 MB / 1.202 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.952e-05/1.445, allocations: 43.8 kB / 1.583 GB, free: 1.32 MB / 1.202 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.059e-05/1.445, allocations: 0 / 1.583 GB, free: 1.32 MB / 1.202 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002107/1.447, allocations: 1.42 MB / 1.584 GB, free: 15.87 MB / 1.218 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007817/1.448, allocations: 151.9 kB / 1.585 GB, free: 15.72 MB / 1.218 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003234/1.451, allocations: 314.5 kB / 1.585 GB, free: 15.41 MB / 1.218 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000711/1.452, allocations: 0.6222 MB / 1.585 GB, free: 14.79 MB / 1.218 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01555/1.468, allocations: 8.215 MB / 1.593 GB, free: 6.695 MB / 1.218 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.05772/1.525, allocations: 41.78 MB / 1.634 GB, free: 12.59 MB / 1.265 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003402/1.529, allocations: 2.661 MB / 1.637 GB, free: 9.91 MB / 1.265 GB
Notification: Performance of sorting global known variables: time 0.003461/1.532, allocations: 3.312 MB / 1.64 GB, free: 6.621 MB / 1.265 GB
Notification: Performance of Backend: time 2.91e-07/1.532, allocations: 4 kB / 1.64 GB, free: 6.617 MB / 1.265 GB
Warning: The hideResult annotation could not be evaluated, probably due to missing annotation(Evaluate=true). It is removed.
Notification: Performance of simCode: created initialization part: time 0.03316/1.566, allocations: 22.02 MB / 1.662 GB, free: 124 kB / 1.28 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.02654/1.592, allocations: 28.95 MB / 1.69 GB, free: 3.008 MB / 1.312 GB
Notification: Performance of Templates: time 0.4924/2.084, allocations: 181.1 MB / 1.867 GB, free: 355 MB / 1.327 GB
make -j1 -f ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.makefile
(rm -f ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe ; mkfifo ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe ; head -c 1048576 < ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe >> ../files/ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.sim & ./ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1  -nls=kinsol -lv=LOG_STATS -abortSlowSimulation -alarm=300 -lv LOG_STATS > ScalableTestGrids_AnalyticJacobian_ScalableTestGrids.Models.Type1.Type1_N_1_M_1.pipe 2>&1)