Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.0007991/0.0007991, allocations: 72.5 kB / 19.93 MB, free: 4.633 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.8729/0.8729, allocations: 177.1 MB / 200.2 MB, free: 5.965 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001055/0.001055, allocations: 156.6 kB / 256.7 MB, free: 13.36 MB / 250.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo): time 0.2602/0.2602, allocations: 80.24 MB / 393.4 MB, free: 2.449 MB / 314.7 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1")
translateModel(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.703e-06/1.703e-06, allocations: 0 / 0.556 GB, free: 12.18 MB / 490.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.136e-05/2.306e-05, allocations: 2.281 kB / 0.556 GB, free: 12.17 MB / 490.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1): time 0.005517/0.00554, allocations: 7.044 MB / 0.5629 GB, free: 5.09 MB / 490.7 MB
Notification: Performance of NFInst.instExpressions: time 0.004046/0.009586, allocations: 2.42 MB / 0.5653 GB, free: 2.66 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005302/0.01012, allocations: 19.88 kB / 0.5653 GB, free: 2.641 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006388/0.01075, allocations: 309.4 kB / 0.5656 GB, free: 2.336 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001509/0.01226, allocations: 1.067 MB / 0.5666 GB, free: 1.266 MB / 490.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00107/0.01333, allocations: 0.7689 MB / 0.5674 GB, free: 0.5 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.001616/0.01495, allocations: 2.133 MB / 0.5695 GB, free: 14.36 MB / 0.4948 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003456/0.0153, allocations: 242.1 kB / 0.5697 GB, free: 14.1 MB / 0.4948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001239/0.01653, allocations: 1.188 MB / 0.5709 GB, free: 12.91 MB / 0.4948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007853/0.01732, allocations: 0.8801 MB / 0.5717 GB, free: 12.03 MB / 0.4948 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002325/0.01755, allocations: 176 kB / 0.5719 GB, free: 11.86 MB / 0.4948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004311/0.01798, allocations: 343.8 kB / 0.5722 GB, free: 11.52 MB / 0.4948 GB
Notification: Performance of NFScalarize.scalarize: time 0.0002547/0.01824, allocations: 454.1 kB / 0.5727 GB, free: 11.07 MB / 0.4948 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003605/0.0186, allocations: 0.5919 MB / 0.5732 GB, free: 10.48 MB / 0.4948 GB
Notification: Performance of NFConvertDAE.convert: time 0.001504/0.0201, allocations: 2.262 MB / 0.5754 GB, free: 8.207 MB / 0.4948 GB
Notification: Performance of FrontEnd - DAE generated: time 2.885e-06/0.0201, allocations: 4 kB / 0.5754 GB, free: 8.203 MB / 0.4948 GB
Notification: Performance of FrontEnd: time 1.723e-06/0.02011, allocations: 0 / 0.5754 GB, free: 8.203 MB / 0.4948 GB
Notification: Performance of Transformations before backend: time 2.401e-05/0.02013, allocations: 0 / 0.5754 GB, free: 8.203 MB / 0.4948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 229
 * Number of variables: 229
Notification: Performance of Generate backend data structure: time 0.006216/0.02635, allocations: 3.636 MB / 0.579 GB, free: 4.516 MB / 0.4948 GB
Notification: Performance of prepare preOptimizeDAE: time 4e-05/0.02639, allocations: 8.031 kB / 0.579 GB, free: 4.508 MB / 0.4948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002138/0.02852, allocations: 0.756 MB / 0.5797 GB, free: 3.75 MB / 0.4948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00117/0.02969, allocations: 1.037 MB / 0.5808 GB, free: 2.676 MB / 0.4948 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003195/0.03001, allocations: 0.5617 MB / 0.5813 GB, free: 2.012 MB / 0.4948 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000143/0.03016, allocations: 104 kB / 0.5814 GB, free: 1.91 MB / 0.4948 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001673/0.03183, allocations: 1.376 MB / 0.5827 GB, free: 0.5156 MB / 0.4948 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.894e-05/0.03185, allocations: 3.984 kB / 0.5827 GB, free: 0.5117 MB / 0.4948 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.089e-05/0.03193, allocations: 39.94 kB / 0.5828 GB, free: 484 kB / 0.4948 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.384e-05/0.03194, allocations: 24 kB / 0.5828 GB, free: 460 kB / 0.4948 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001748/0.03369, allocations: 1.304 MB / 0.5841 GB, free: 15.14 MB / 0.5105 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.004563/0.03825, allocations: 3.453 MB / 0.5875 GB, free: 11.63 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004123/0.04238, allocations: 2.349 MB / 0.5897 GB, free: 9.254 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001094/0.04347, allocations: 0.8253 MB / 0.5906 GB, free: 8.418 MB / 0.5105 GB
Notification: Performance of preOpt evalFunc (simulation): time 9.723e-05/0.04357, allocations: 21 kB / 0.5906 GB, free: 8.398 MB / 0.5105 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001315/0.04488, allocations: 1.065 MB / 0.5916 GB, free: 7.309 MB / 0.5105 GB
Notification: Performance of pre-optimization done (n=125): time 2.454e-06/0.04489, allocations: 0 / 0.5916 GB, free: 7.309 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=125): time 0.2424/0.2872, allocations: 10.14 MB / 0.6015 GB, free: 94.21 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.238e-05/0.2873, allocations: 116.2 kB / 0.6016 GB, free: 94.09 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001456/0.2887, allocations: 1.322 MB / 0.6029 GB, free: 93.93 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002148/0.289, allocations: 51.36 kB / 0.603 GB, free: 93.9 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002435/0.2892, allocations: 0.586 MB / 0.6035 GB, free: 93.64 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003047/0.2895, allocations: 472.9 kB / 0.604 GB, free: 93.39 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000344/0.2899, allocations: 315.4 kB / 0.6043 GB, free: 93.25 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 3.19e-05/0.2899, allocations: 305 kB / 0.6046 GB, free: 92.95 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001188/0.2911, allocations: 0.9564 MB / 0.6055 GB, free: 92.6 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00124/0.2923, allocations: 1.153 MB / 0.6066 GB, free: 92.09 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002327/0.2946, allocations: 2.061 MB / 0.6087 GB, free: 91.14 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.859e-06/0.2946, allocations: 0 / 0.6087 GB, free: 91.14 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=225) (initialization): time 0.005866/0.3005, allocations: 3.732 MB / 0.6123 GB, free: 89.89 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 3.111e-05/0.3005, allocations: 6.688 kB / 0.6123 GB, free: 89.89 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.375e-05/0.3006, allocations: 4.031 kB / 0.6123 GB, free: 89.89 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006011/0.3066, allocations: 2.73 MB / 0.615 GB, free: 89.47 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000967/0.3075, allocations: 290.6 kB / 0.6153 GB, free: 89.45 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00406/0.3116, allocations: 2.714 MB / 0.6179 GB, free: 88.18 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001184/0.3128, allocations: 246.1 kB / 0.6181 GB, free: 88.07 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001175/0.3129, allocations: 65.58 kB / 0.6182 GB, free: 88.06 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 15
 * Number of states: 0 ()
 * Number of discrete variables: 2 ($whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (170):
 * Single equations (assignments): 169
 * 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
   {(30,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004541/0.3133, allocations: 287.5 kB / 0.6185 GB, free: 87.83 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001799/0.3135, allocations: 168.7 kB / 0.6186 GB, free: 87.74 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009626/0.3232, allocations: 4.764 MB / 0.6233 GB, free: 86.65 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.32e-05/0.3232, allocations: 13.72 kB / 0.6233 GB, free: 86.65 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.456e-06/0.3232, allocations: 1.938 kB / 0.6233 GB, free: 86.65 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.503e-05/0.3232, allocations: 11.31 kB / 0.6233 GB, free: 86.65 MB / 0.5105 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 postOpt removeSimpleEquations (simulation): time 0.01053/0.3337, allocations: 6.052 MB / 0.6292 GB, free: 84.86 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.939e-06/0.3337, allocations: 0 / 0.6292 GB, free: 84.86 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004397/0.3342, allocations: 137.9 kB / 0.6294 GB, free: 84.85 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004182/0.3383, allocations: 2.2 MB / 0.6315 GB, free: 83.98 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.342e-05/0.3384, allocations: 32.05 kB / 0.6315 GB, free: 83.96 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01408/0.3525, allocations: 9.826 MB / 0.6411 GB, free: 77.92 MB / 0.5105 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.124e-06/0.3525, allocations: 4 kB / 0.6411 GB, free: 77.91 MB / 0.5105 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.009496/0.362, allocations: 5.964 MB / 0.647 GB, free: 71.93 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004145/0.3624, allocations: 198.6 kB / 0.6471 GB, free: 71.73 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001394/0.3626, allocations: 30.17 kB / 0.6472 GB, free: 71.7 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007692/0.3633, allocations: 135.8 kB / 0.6473 GB, free: 71.57 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004854/0.3638, allocations: 269.3 kB / 0.6476 GB, free: 71.31 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.135e-05/0.3639, allocations: 36 kB / 0.6476 GB, free: 71.27 MB / 0.5105 GB
Notification: Performance of sorting global known variables: time 0.0008256/0.3647, allocations: 0.897 MB / 0.6485 GB, free: 70.38 MB / 0.5105 GB
Notification: Performance of sort global known variables: time 3.6e-07/0.3647, allocations: 0 / 0.6485 GB, free: 70.38 MB / 0.5105 GB
Notification: Performance of remove unused functions: time 0.0009645/0.3657, allocations: 0.5152 MB / 0.649 GB, free: 69.86 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 11 (gENCLS.omega,gENCLS.delta,gENCLS.eq,gENROE.w,gENROE.delta,gENROE.Epd,gENROE.Epq,gENROE.PSIkd,gENROE.PSIkq,tGOV1.imLeadLag.TF.x_scaled[1],tGOV1.simpleLagLim.state)
 * Number of discrete variables: 2 ($whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (82):
 * Single equations (assignments): 81
 * 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
   {(26,23)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002847/0.366, allocations: 156.8 kB / 0.6491 GB, free: 69.7 MB / 0.5105 GB
Notification: Performance of simCode: created initialization part: time 0.004486/0.3704, allocations: 2.707 MB / 0.6518 GB, free: 67.01 MB / 0.5105 GB
Notification: Performance of simCode: created event and clocks part: time 4.008e-06/0.3704, allocations: 0 / 0.6518 GB, free: 67.01 MB / 0.5105 GB
Notification: Performance of simCode: created simulation system equations: time 0.00303/0.3735, allocations: 1.871 MB / 0.6536 GB, free: 65.12 MB / 0.5105 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0006453/0.3741, allocations: 127.3 kB / 0.6537 GB, free: 65 MB / 0.5105 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003134/0.3772, allocations: 2.827 MB / 0.6565 GB, free: 62.19 MB / 0.5105 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003045/0.3776, allocations: 0.6208 MB / 0.6571 GB, free: 61.53 MB / 0.5105 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002642/0.3778, allocations: 101.3 kB / 0.6572 GB, free: 61.43 MB / 0.5105 GB
Notification: Performance of SimCode: time 1.002e-06/0.3778, allocations: 0 / 0.6572 GB, free: 61.43 MB / 0.5105 GB
Notification: Performance of Templates: time 0.01751/0.3953, allocations: 25.94 MB / 0.6825 GB, free: 35.51 MB / 0.5105 GB
"
[Timeout remaining time 660]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.sim & ./OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 5.904474512673914]
Failed to read output from testmodel.py, exit status != 0:
0.4011226939037442 0.40792149 0.38635435500000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x79149fec1340>