Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.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/Modelica 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/OpenIPSL 3.0.1/package.mo", uses=false)
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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 Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE")
translateModel(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001536/0.001536, allocations: 114.9 kB / 16.42 MB, free: 6.012 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.346/1.346, allocations: 222.9 MB / 240.1 MB, free: 4.391 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001345/0.001345, allocations: 198.8 kB / 290.4 MB, free: 2.113 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4421/0.4421, allocations: 78.53 MB / 419.1 MB, free: 3.121 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001526/0.001526, allocations: 79.88 kB / 0.5543 GB, free: 0.9453 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2799/0.2814, allocations: 68.18 MB / 0.6209 GB, free: 9.699 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE): time 0.01069/0.2921, allocations: 14.96 MB / 0.6355 GB, free: 11.17 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008548/0.3007, allocations: 4.6 MB / 0.64 GB, free: 6.547 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002125/0.3029, allocations: 28 kB / 0.64 GB, free: 6.52 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002066/0.3049, allocations: 0.7789 MB / 0.6407 GB, free: 5.734 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007071/0.3121, allocations: 2.571 MB / 0.6433 GB, free: 3.18 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003406/0.3155, allocations: 1.333 MB / 0.6446 GB, free: 1.863 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006615/0.3221, allocations: 4.891 MB / 0.6493 GB, free: 12.96 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00132/0.3235, allocations: 0.7994 MB / 0.6501 GB, free: 12.12 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002819/0.3263, allocations: 1.887 MB / 0.652 GB, free: 10.23 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003071/0.3294, allocations: 2.455 MB / 0.6544 GB, free: 7.762 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008381/0.3302, allocations: 407.9 kB / 0.6547 GB, free: 7.363 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0014/0.3317, allocations: 0.6326 MB / 0.6554 GB, free: 6.73 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0008256/0.3325, allocations: 0.9689 MB / 0.6563 GB, free: 5.758 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002373/0.3349, allocations: 2.009 MB / 0.6583 GB, free: 3.738 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.005573/0.3405, allocations: 5.331 MB / 0.6635 GB, free: 14.39 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.31e-06/0.3405, allocations: 0 / 0.6635 GB, free: 14.39 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.383e-06/0.3405, allocations: 0 / 0.6635 GB, free: 14.39 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 8.757e-05/0.3406, allocations: 0 / 0.6635 GB, free: 14.39 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 493
 * Number of variables: 493
Notification: Performance of Generate backend data structure: time 0.03982/0.3804, allocations: 9.504 MB / 0.6728 GB, free: 4.812 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.525e-05/0.3805, allocations: 8.031 kB / 0.6728 GB, free: 4.805 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01155/0.392, allocations: 1.755 MB / 0.6745 GB, free: 3.043 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003847/0.3959, allocations: 2.171 MB / 0.6766 GB, free: 0.8125 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007387/0.3967, allocations: 0.6702 MB / 0.6773 GB, free: 52 kB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006024/0.3973, allocations: 235.9 kB / 0.6775 GB, free: 15.82 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006067/0.4033, allocations: 2.685 MB / 0.6801 GB, free: 13.12 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.429e-05/0.4034, allocations: 3.938 kB / 0.6801 GB, free: 13.12 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002508/0.4036, allocations: 80 kB / 0.6802 GB, free: 13.04 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.05e-05/0.4037, allocations: 44 kB / 0.6802 GB, free: 13 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006176/0.4099, allocations: 2.834 MB / 0.683 GB, free: 10.16 MB / 494.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.01862/0.4285, allocations: 11.01 MB / 0.6937 GB, free: 14.61 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01242/0.4409, allocations: 4.975 MB / 0.6986 GB, free: 9.422 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004069/0.445, allocations: 1.927 MB / 0.7005 GB, free: 7.496 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002513/0.4453, allocations: 45.86 kB / 0.7005 GB, free: 7.453 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.778e-05/0.4453, allocations: 64.56 kB / 0.7006 GB, free: 7.383 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=249): time 4.819e-06/0.4454, allocations: 0 / 0.7006 GB, free: 7.383 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=249): time 0.1325/0.5779, allocations: 29.55 MB / 0.7294 GB, free: 9.766 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.102e-05/0.578, allocations: 77.41 kB / 0.7295 GB, free: 9.676 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004377/0.5823, allocations: 2.902 MB / 0.7324 GB, free: 6.773 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005497/0.5829, allocations: 81.7 kB / 0.7324 GB, free: 6.688 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001034/0.584, allocations: 1.256 MB / 0.7337 GB, free: 5.418 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001107/0.5851, allocations: 0.8975 MB / 0.7345 GB, free: 4.508 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001626/0.5867, allocations: 0.7443 MB / 0.7353 GB, free: 3.75 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 8.486e-05/0.5868, allocations: 313.1 kB / 0.7356 GB, free: 3.441 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005075/0.5919, allocations: 2.28 MB / 0.7378 GB, free: 1.152 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00535/0.5972, allocations: 2.677 MB / 0.7404 GB, free: 14.38 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01013/0.6074, allocations: 4.847 MB / 0.7451 GB, free: 9.453 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.062e-05/0.6074, allocations: 4 kB / 0.7451 GB, free: 9.449 MB / 0.545 GB
Notification: Performance of matching and sorting (n=531) (initialization): time 0.01621/0.6236, allocations: 7.224 MB / 0.7522 GB, free: 2.211 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 2.557e-05/0.6237, allocations: 8 kB / 0.7522 GB, free: 2.203 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.914e-05/0.6237, allocations: 11.98 kB / 0.7522 GB, free: 2.191 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02003/0.6437, allocations: 5.73 MB / 0.7578 GB, free: 12.46 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003243/0.647, allocations: 0.6631 MB / 0.7585 GB, free: 11.79 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01433/0.6613, allocations: 5.45 MB / 0.7638 GB, free: 6.316 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00448/0.6658, allocations: 0.5078 MB / 0.7643 GB, free: 5.805 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004695/0.6663, allocations: 140 kB / 0.7644 GB, free: 5.668 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (410):
 * Single equations (assignments): 409
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(62,60)}
Notification: Performance of prepare postOptimizeDAE: time 0.00129/0.6676, allocations: 0.5245 MB / 0.7649 GB, free: 5.133 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007569/0.6684, allocations: 331.8 kB / 0.7652 GB, free: 4.805 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.2768/0.9452, allocations: 10.53 MB / 0.7755 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.882e-05/0.9453, allocations: 11.39 kB / 0.7755 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.334e-06/0.9453, allocations: 0 / 0.7755 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009529/0.9463, allocations: 0.4933 MB / 0.776 GB, free: 130.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007114/0.947, allocations: 47.97 kB / 0.7761 GB, free: 130.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003005/0.95, allocations: 245.7 kB / 0.7763 GB, free: 130.6 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003292/0.9533, allocations: 1.211 MB / 0.7775 GB, free: 130.6 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01634/0.9696, allocations: 5.428 MB / 0.7828 GB, free: 129.2 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01071/0.9804, allocations: 5.505 MB / 0.7881 GB, free: 127 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003151/0.9836, allocations: 1.512 MB / 0.7896 GB, free: 126.5 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002225/0.9858, allocations: 1.879 MB / 0.7915 GB, free: 125.8 MB / 0.5606 GB
Notification: Performance of Backend: time 4.3e-07/0.9858, allocations: 0 / 0.7915 GB, free: 125.8 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01404/0.9998, allocations: 4.722 MB / 0.7961 GB, free: 123.8 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.007661/1.008, allocations: 7.138 MB / 0.803 GB, free: 116.6 MB / 0.5606 GB
Notification: Performance of Templates: time 0.06673/1.074, allocations: 55.09 MB / 0.8568 GB, free: 61.57 MB / 0.5606 GB
make -j1 -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.makefile
(rm -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.sim & ./OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe 2>&1)