Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.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 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo", uses=false)
Using package PowerGrids with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+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(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ,tolerance=1e-06,outputFormat="empty",numberOfIntervals=1000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ")
translateModel(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ,tolerance=1e-06,outputFormat="empty",numberOfIntervals=1000,variableFilter="",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.005132/0.005132, allocations: 97.41 kB / 15.73 MB, free: 6.039 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.001307/0.001307, allocations: 195.8 kB / 16.67 MB, free: 5.961 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.432/1.432, allocations: 222.9 MB / 240.4 MB, free: 4.32 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.1736/0.1736, allocations: 16.67 MB / 307.2 MB, free: 14.78 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002053/0.002053, allocations: 62.81 kB / 426.5 MB, free: 36.94 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08577/0.08784, allocations: 53.56 MB / 480 MB, free: 10.68 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ): time 0.01245/0.1003, allocations: 17.56 MB / 497.6 MB, free: 11.5 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00786/0.1082, allocations: 4.895 MB / 0.4907 GB, free: 6.59 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009782/0.1092, allocations: 31.88 kB / 0.4908 GB, free: 6.559 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001558/0.1108, allocations: 0.6484 MB / 0.4914 GB, free: 5.906 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004154/0.115, allocations: 2.081 MB / 0.4934 GB, free: 3.832 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001766/0.1167, allocations: 0.8608 MB / 0.4943 GB, free: 2.988 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003583/0.1203, allocations: 3.319 MB / 0.4975 GB, free: 15.66 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008313/0.1212, allocations: 0.5793 MB / 0.4981 GB, free: 15.05 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001341/0.1225, allocations: 1.113 MB / 0.4992 GB, free: 13.94 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001371/0.1239, allocations: 1.144 MB / 0.5003 GB, free: 12.79 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002283/0.1241, allocations: 148 kB / 0.5004 GB, free: 12.64 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001065/0.1252, allocations: 0.4919 MB / 0.5009 GB, free: 12.15 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003045/0.1255, allocations: 362.4 kB / 0.5012 GB, free: 11.8 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001066/0.1266, allocations: 1.049 MB / 0.5023 GB, free: 10.74 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003152/0.1298, allocations: 2.74 MB / 0.5049 GB, free: 7.992 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.328e-06/0.1298, allocations: 0 / 0.5049 GB, free: 7.992 MB / 382.1 MB
Notification: Performance of FrontEnd: time 1.222e-06/0.1298, allocations: 0 / 0.5049 GB, free: 7.992 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 2.578e-05/0.1298, allocations: 4 kB / 0.5049 GB, free: 7.988 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 312
 * Number of variables: 312
Notification: Performance of Generate backend data structure: time 0.007958/0.1378, allocations: 2.816 MB / 0.5077 GB, free: 5.109 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.187e-05/0.1378, allocations: 12.03 kB / 0.5077 GB, free: 5.098 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002339/0.1402, allocations: 467.3 kB / 0.5081 GB, free: 4.641 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002267/0.1425, allocations: 1.273 MB / 0.5094 GB, free: 3.312 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.123e-05/0.1426, allocations: 98.83 kB / 0.5095 GB, free: 3.215 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001536/0.1427, allocations: 127.9 kB / 0.5096 GB, free: 3.09 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002141/0.1449, allocations: 1.464 MB / 0.511 GB, free: 1.434 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.947e-05/0.1449, allocations: 7.938 kB / 0.511 GB, free: 1.426 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001112/0.145, allocations: 56 kB / 0.5111 GB, free: 1.371 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.973e-05/0.1451, allocations: 36 kB / 0.5111 GB, free: 1.336 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001723/0.1468, allocations: 1.082 MB / 0.5122 GB, free: 256 kB / 382.1 MB
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.01169/0.1585, allocations: 9.861 MB / 0.5218 GB, free: 5.82 MB / 398.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002077/0.1606, allocations: 1.277 MB / 0.5231 GB, free: 4.473 MB / 398.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005708/0.1612, allocations: 392.6 kB / 0.5234 GB, free: 4.086 MB / 398.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 7.048e-05/0.1613, allocations: 21.05 kB / 0.5235 GB, free: 4.062 MB / 398.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.768e-05/0.1613, allocations: 55.56 kB / 0.5235 GB, free: 4 MB / 398.1 MB
Notification: Performance of pre-optimization done (n=106): time 3.076e-06/0.1613, allocations: 0 / 0.5235 GB, free: 4 MB / 398.1 MB
Notification: Performance of matching and sorting (n=106): time 0.004932/0.1662, allocations: 1.904 MB / 0.5254 GB, free: 2.051 MB / 398.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.092e-05/0.1663, allocations: 86.11 kB / 0.5255 GB, free: 1.949 MB / 398.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002023/0.1683, allocations: 1.424 MB / 0.5268 GB, free: 0.5117 MB / 398.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001203/0.1685, allocations: 53.64 kB / 0.5269 GB, free: 464 kB / 398.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0006506/0.1691, allocations: 0.7156 MB / 0.5276 GB, free: 15.72 MB / 414.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0005102/0.1696, allocations: 452.5 kB / 0.528 GB, free: 15.28 MB / 414.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009682/0.1706, allocations: 0.633 MB / 0.5286 GB, free: 14.64 MB / 414.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001243/0.1708, allocations: 304.9 kB / 0.5289 GB, free: 14.34 MB / 414.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001518/0.1723, allocations: 0.9089 MB / 0.5298 GB, free: 13.43 MB / 414.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001803/0.1741, allocations: 1.184 MB / 0.531 GB, free: 12.21 MB / 414.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002602/0.1767, allocations: 1.702 MB / 0.5326 GB, free: 10.46 MB / 414.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.528e-06/0.1767, allocations: 0 / 0.5326 GB, free: 10.46 MB / 414.1 MB
Notification: Performance of matching and sorting (n=449) (initialization): time 0.005772/0.1825, allocations: 3.031 MB / 0.5356 GB, free: 7.406 MB / 414.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.592e-05/0.1826, allocations: 4 kB / 0.5356 GB, free: 7.402 MB / 414.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.158e-05/0.1826, allocations: 4 kB / 0.5356 GB, free: 7.398 MB / 414.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002942/0.1855, allocations: 0.5169 MB / 0.5361 GB, free: 6.875 MB / 414.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002823/0.1884, allocations: 0.7178 MB / 0.5368 GB, free: 6.156 MB / 414.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03465/0.223, allocations: 4.801 MB / 0.5415 GB, free: 1.086 MB / 414.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002803/0.2259, allocations: 230.6 kB / 0.5417 GB, free: 0.8594 MB / 414.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002815/0.2262, allocations: 108 kB / 0.5418 GB, free: 0.7539 MB / 414.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * Number of states: 0 ()
 * Number of discrete variables: 3 (LOAD2.componentPF.isSlackBus,BUSINF.componentPF.isSlackBus,LOAD1.componentPF.isSlackBus)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (429):
 * Single equations (assignments): 427
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(8,0), (14,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007873/0.227, allocations: 401.1 kB / 0.5422 GB, free: 360 kB / 414.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003776/0.2274, allocations: 167 kB / 0.5424 GB, free: 192 kB / 414.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0027/0.2301, allocations: 1.185 MB / 0.5435 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.406e-05/0.2301, allocations: 3.984 kB / 0.5435 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.46e-06/0.2301, allocations: 0 / 0.5435 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008873/0.231, allocations: 399.8 kB / 0.5439 GB, free: 14.57 MB / 430.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002399/0.2313, allocations: 20 kB / 0.5439 GB, free: 14.55 MB / 430.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001169/0.2324, allocations: 51.97 kB / 0.544 GB, free: 14.5 MB / 430.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001619/0.2326, allocations: 80.86 kB / 0.5441 GB, free: 14.42 MB / 430.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.002914/0.2355, allocations: 1.256 MB / 0.5453 GB, free: 13.15 MB / 430.1 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.002434/0.238, allocations: 1.473 MB / 0.5467 GB, free: 11.65 MB / 430.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00054/0.2385, allocations: 331.8 kB / 0.547 GB, free: 11.33 MB / 430.1 MB
Notification: Performance of sorting global known variables: time 0.001515/0.2401, allocations: 0.8829 MB / 0.5479 GB, free: 10.45 MB / 430.1 MB
Notification: Performance of Backend: time 6.91e-07/0.2401, allocations: 0 / 0.5479 GB, free: 10.45 MB / 430.1 MB
Notification: Performance of simCode: created initialization part: time 0.005876/0.246, allocations: 2.453 MB / 0.5503 GB, free: 7.965 MB / 430.1 MB
Notification: Performance of SimCode: time 0.005043/0.251, allocations: 2.936 MB / 0.5532 GB, free: 4.992 MB / 430.1 MB
Notification: Performance of Templates: time 0.1134/0.3645, allocations: 25.65 MB / 0.5782 GB, free: 11.35 MB / 462.1 MB
make -j1 -f PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.makefile
(rm -f PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.pipe ; mkfifo PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.pipe ; head -c 1048576 < PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.pipe >> ../files/PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.sim & ./PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ  -abortSlowSimulation -alarm=50 -lv LOG_STATS > PowerGrids_dev_PowerGrids.Examples.Tutorial.HowToBuild.Examples.TestLoadPQ.pipe 2>&1)