Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_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.001356/0.001356, allocations: 88.72 kB / 19.44 MB, free: 356 kB / 13.93 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 1.468/1.468, allocations: 177.1 MB / 199.7 MB, free: 5.805 MB / 186.7 MB
"
[Timeout remaining time 178]
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.001771/0.001771, allocations: 168.5 kB / 256.3 MB, free: 13.16 MB / 250.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.5052/0.5052, allocations: 80.37 MB / 393.1 MB, free: 2.156 MB / 314.7 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.2.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/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_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1")
translateModel(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.644e-06/1.644e-06, allocations: 0 / 489.2 MB, free: 11.84 MB / 410.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.003e-05/3.167e-05, allocations: 5.5 kB / 489.2 MB, free: 11.84 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1): time 0.009801/0.009833, allocations: 7.066 MB / 496.3 MB, free: 4.742 MB / 410.7 MB
Notification: Performance of NFInst.instExpressions: time 0.006067/0.0159, allocations: 2.424 MB / 498.7 MB, free: 2.328 MB / 410.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009002/0.0168, allocations: 12.72 kB / 498.7 MB, free: 2.316 MB / 410.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009756/0.01778, allocations: 310.4 kB / 499 MB, free: 2.012 MB / 410.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002793/0.02057, allocations: 1.05 MB / 0.4884 GB, free: 0.9609 MB / 410.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001923/0.02249, allocations: 0.7814 MB / 0.4891 GB, free: 196 kB / 410.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003141/0.02563, allocations: 2.122 MB / 0.4912 GB, free: 14.06 MB / 426.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005505/0.02618, allocations: 250 kB / 0.4914 GB, free: 13.79 MB / 426.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001918/0.0281, allocations: 1.186 MB / 0.4926 GB, free: 12.6 MB / 426.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001347/0.02945, allocations: 0.8763 MB / 0.4935 GB, free: 11.72 MB / 426.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002968/0.02975, allocations: 176 kB / 0.4936 GB, free: 11.55 MB / 426.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007571/0.0305, allocations: 349.4 kB / 0.494 GB, free: 11.21 MB / 426.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004779/0.03098, allocations: 450.2 kB / 0.4944 GB, free: 10.77 MB / 426.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007738/0.03175, allocations: 0.5957 MB / 0.495 GB, free: 10.17 MB / 426.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.00301/0.03476, allocations: 2.262 MB / 0.4972 GB, free: 7.902 MB / 426.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.739e-06/0.03477, allocations: 0 / 0.4972 GB, free: 7.902 MB / 426.7 MB
Notification: Performance of FrontEnd: time 1.383e-06/0.03477, allocations: 1.281 kB / 0.4972 GB, free: 7.902 MB / 426.7 MB
Notification: Performance of Transformations before backend: time 3.394e-05/0.0348, allocations: 4 kB / 0.4972 GB, free: 7.898 MB / 426.7 MB
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.01299/0.0478, allocations: 3.634 MB / 0.5007 GB, free: 4.254 MB / 426.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.22e-05/0.04784, allocations: 8.031 kB / 0.5007 GB, free: 4.246 MB / 426.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004693/0.05253, allocations: 0.9003 MB / 0.5016 GB, free: 3.34 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00224/0.05477, allocations: 1.056 MB / 0.5027 GB, free: 2.25 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000702/0.05548, allocations: 0.5617 MB / 0.5032 GB, free: 1.582 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002407/0.05572, allocations: 104 kB / 0.5033 GB, free: 1.48 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003118/0.05884, allocations: 1.376 MB / 0.5046 GB, free: 92 kB / 426.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.094e-05/0.05886, allocations: 0 / 0.5046 GB, free: 92 kB / 426.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001329/0.05899, allocations: 43.94 kB / 0.5047 GB, free: 48 kB / 426.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.646e-05/0.05902, allocations: 24 kB / 0.5047 GB, free: 24 kB / 426.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003302/0.06232, allocations: 1.312 MB / 0.506 GB, free: 14.7 MB / 442.7 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.008617/0.07093, allocations: 3.469 MB / 0.5094 GB, free: 11.18 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.008741/0.07967, allocations: 2.35 MB / 0.5117 GB, free: 8.805 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002148/0.08182, allocations: 0.8251 MB / 0.5125 GB, free: 7.977 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001414/0.08196, allocations: 32.92 kB / 0.5125 GB, free: 7.941 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002447/0.08441, allocations: 1.058 MB / 0.5135 GB, free: 6.859 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=125): time 3.045e-06/0.08441, allocations: 0 / 0.5135 GB, free: 6.859 MB / 442.7 MB
Notification: Performance of matching and sorting (n=125): time 0.04626/0.1307, allocations: 10.2 MB / 0.5235 GB, free: 12.63 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001054/0.1308, allocations: 115.8 kB / 0.5236 GB, free: 12.5 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002399/0.1332, allocations: 1.324 MB / 0.5249 GB, free: 11.16 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002717/0.1335, allocations: 49.7 kB / 0.525 GB, free: 11.1 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005707/0.134, allocations: 0.581 MB / 0.5255 GB, free: 10.52 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0006096/0.1346, allocations: 472 kB / 0.526 GB, free: 10.05 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006774/0.1353, allocations: 319.5 kB / 0.5263 GB, free: 9.738 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001496/0.1355, allocations: 301.1 kB / 0.5266 GB, free: 9.441 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002449/0.1379, allocations: 0.9605 MB / 0.5275 GB, free: 8.48 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002761/0.1407, allocations: 1.151 MB / 0.5286 GB, free: 7.281 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004937/0.1456, allocations: 2.066 MB / 0.5306 GB, free: 5.176 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.833e-06/0.1456, allocations: 0 / 0.5306 GB, free: 5.176 MB / 458.7 MB
Notification: Performance of matching and sorting (n=225) (initialization): time 0.01141/0.157, allocations: 3.801 MB / 0.5344 GB, free: 1.363 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.542e-05/0.157, allocations: 14.38 kB / 0.5344 GB, free: 1.352 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.176e-05/0.1571, allocations: 4 kB / 0.5344 GB, free: 1.348 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01116/0.1682, allocations: 2.724 MB / 0.537 GB, free: 14.61 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001632/0.1699, allocations: 291.6 kB / 0.5373 GB, free: 14.33 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007534/0.1774, allocations: 2.733 MB / 0.54 GB, free: 11.57 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002603/0.18, allocations: 242.9 kB / 0.5402 GB, free: 11.33 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002067/0.1802, allocations: 63.97 kB / 0.5403 GB, free: 11.27 MB / 474.7 MB
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.0006873/0.1809, allocations: 293.5 kB / 0.5406 GB, free: 10.97 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004634/0.1813, allocations: 199 kB / 0.5407 GB, free: 10.78 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01785/0.1992, allocations: 4.759 MB / 0.5454 GB, free: 5.996 MB / 474.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.302e-05/0.1992, allocations: 16 kB / 0.5454 GB, free: 5.98 MB / 474.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.308e-06/0.1992, allocations: 0 / 0.5454 GB, free: 5.98 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.959e-05/0.1993, allocations: 15.91 kB / 0.5454 GB, free: 5.965 MB / 474.7 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 postOpt removeSimpleEquations (simulation): time 0.02102/0.2203, allocations: 6.061 MB / 0.5513 GB, free: 15.86 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.394e-05/0.2203, allocations: 0 / 0.5513 GB, free: 15.86 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009677/0.2213, allocations: 139.8 kB / 0.5515 GB, free: 15.72 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01582/0.2371, allocations: 3.157 MB / 0.5546 GB, free: 12.55 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000247/0.2374, allocations: 31.98 kB / 0.5546 GB, free: 12.52 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02997/0.2673, allocations: 9.882 MB / 0.5642 GB, free: 2.523 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.356e-06/0.2673, allocations: 0 / 0.5642 GB, free: 2.523 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0214/0.2887, allocations: 6.003 MB / 0.5701 GB, free: 12.49 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007541/0.2895, allocations: 198.6 kB / 0.5703 GB, free: 12.29 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003146/0.2898, allocations: 24 kB / 0.5703 GB, free: 12.27 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001654/0.2915, allocations: 139.8 kB / 0.5704 GB, free: 12.13 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009314/0.2924, allocations: 269.3 kB / 0.5707 GB, free: 11.87 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001182/0.2925, allocations: 35.98 kB / 0.5707 GB, free: 11.84 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.0016/0.2941, allocations: 0.8931 MB / 0.5716 GB, free: 10.94 MB / 0.4948 GB
Notification: Performance of sort global known variables: time 1e-07/0.2941, allocations: 0 / 0.5716 GB, free: 10.94 MB / 0.4948 GB
Notification: Performance of remove unused functions: time 0.001529/0.2956, allocations: 0.5229 MB / 0.5721 GB, free: 10.42 MB / 0.4948 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.0003709/0.296, allocations: 153.3 kB / 0.5723 GB, free: 10.26 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.009488/0.3055, allocations: 2.695 MB / 0.5749 GB, free: 7.566 MB / 0.4948 GB
Notification: Performance of simCode: created event and clocks part: time 7.183e-06/0.3055, allocations: 7.938 kB / 0.5749 GB, free: 7.559 MB / 0.4948 GB
Notification: Performance of simCode: created simulation system equations: time 0.00698/0.3125, allocations: 1.883 MB / 0.5767 GB, free: 5.66 MB / 0.4948 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001276/0.3138, allocations: 167.2 kB / 0.5769 GB, free: 5.496 MB / 0.4948 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006127/0.3199, allocations: 2.826 MB / 0.5797 GB, free: 2.625 MB / 0.4948 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008248/0.3207, allocations: 0.6247 MB / 0.5803 GB, free: 1.965 MB / 0.4948 GB
Notification: Performance of simCode: alias equations: time 0.0006089/0.3213, allocations: 133.5 kB / 0.5804 GB, free: 1.832 MB / 0.4948 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004049/0.3217, allocations: 100.5 kB / 0.5805 GB, free: 1.734 MB / 0.4948 GB
Notification: Performance of SimCode: time 9.22e-07/0.3217, allocations: 0 / 0.5805 GB, free: 1.734 MB / 0.4948 GB
Notification: Performance of Templates: time 0.225/0.5468, allocations: 29.97 MB / 0.6098 GB, free: 167.7 MB / 0.5105 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.TG.TGOV1.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.8754558329237625]