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.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0013/0.0013, allocations: 103.2 kB / 16.45 MB, free: 6.449 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.388/1.388, allocations: 225.6 MB / 244.8 MB, free: 14.18 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001448/0.001448, allocations: 187.1 kB / 299.8 MB, free: 9.84 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4333/0.4333, allocations: 79.72 MB / 434.4 MB, free: 6.23 MB / 302.1 MB
"
[Timeout remaining time 179]
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.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 0.002277/0.002277, allocations: 79.88 kB / 0.5789 GB, free: 9.492 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.128/0.1303, allocations: 72.09 MB / 0.6493 GB, free: 1.301 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.TG.TGOV1): time 0.009949/0.1402, allocations: 8.007 MB / 0.6571 GB, free: 9.25 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005864/0.1461, allocations: 2.513 MB / 0.6595 GB, free: 6.727 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009591/0.147, allocations: 12 kB / 0.6595 GB, free: 6.715 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009137/0.148, allocations: 313.4 kB / 0.6598 GB, free: 6.406 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003223/0.1512, allocations: 1.249 MB / 0.6611 GB, free: 5.156 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001651/0.1528, allocations: 0.6866 MB / 0.6617 GB, free: 4.477 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002815/0.1556, allocations: 2.279 MB / 0.664 GB, free: 2.188 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004517/0.1561, allocations: 261.5 kB / 0.6642 GB, free: 1.91 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001127/0.1572, allocations: 0.8638 MB / 0.6651 GB, free: 1.043 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001383/0.1586, allocations: 1.12 MB / 0.6661 GB, free: 15.92 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002676/0.1589, allocations: 175.9 kB / 0.6663 GB, free: 15.75 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006721/0.1595, allocations: 355.8 kB / 0.6667 GB, free: 15.4 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004076/0.16, allocations: 442.3 kB / 0.6671 GB, free: 14.96 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0008433/0.1608, allocations: 0.878 MB / 0.6679 GB, free: 14.08 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002573/0.1634, allocations: 2.194 MB / 0.6701 GB, free: 11.88 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.418e-06/0.1634, allocations: 0 / 0.6701 GB, free: 11.88 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.633e-06/0.1634, allocations: 8 kB / 0.6701 GB, free: 11.87 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 2.615e-05/0.1634, allocations: 0 / 0.6701 GB, free: 11.87 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: 228
 * Number of variables: 228
Notification: Performance of Generate backend data structure: time 0.01445/0.1779, allocations: 3.85 MB / 0.6738 GB, free: 7.957 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.922e-05/0.1779, allocations: 12.03 kB / 0.6739 GB, free: 7.945 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004718/0.1826, allocations: 0.7482 MB / 0.6746 GB, free: 7.195 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002221/0.1848, allocations: 1.105 MB / 0.6757 GB, free: 6.039 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005023/0.1853, allocations: 421 kB / 0.6761 GB, free: 5.562 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000217/0.1856, allocations: 100 kB / 0.6762 GB, free: 5.465 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003009/0.1886, allocations: 1.368 MB / 0.6775 GB, free: 4.078 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.328e-05/0.1886, allocations: 7.984 kB / 0.6775 GB, free: 4.07 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001254/0.1887, allocations: 36 kB / 0.6775 GB, free: 4.035 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.166e-05/0.1887, allocations: 24 kB / 0.6776 GB, free: 4.012 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003053/0.1918, allocations: 1.296 MB / 0.6788 GB, free: 2.719 MB / 478.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.008927/0.2007, allocations: 4.65 MB / 0.6834 GB, free: 13.86 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.008421/0.2091, allocations: 2.793 MB / 0.6861 GB, free: 10.93 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001924/0.2111, allocations: 0.8136 MB / 0.6869 GB, free: 10.1 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001486/0.2112, allocations: 20.98 kB / 0.6869 GB, free: 10.08 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002275/0.2135, allocations: 1.05 MB / 0.6879 GB, free: 9.008 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=124): time 3.277e-06/0.2135, allocations: 0 / 0.6879 GB, free: 9.008 MB / 494.1 MB
Notification: Performance of matching and sorting (n=124): time 0.287/0.5005, allocations: 9.871 MB / 0.6976 GB, free: 43.81 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.854e-05/0.5005, allocations: 116.2 kB / 0.6977 GB, free: 43.68 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002243/0.5027, allocations: 1.359 MB / 0.699 GB, free: 43.52 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003114/0.5031, allocations: 49.05 kB / 0.6991 GB, free: 43.49 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004119/0.5035, allocations: 0.5742 MB / 0.6996 GB, free: 43.41 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004179/0.5039, allocations: 471.8 kB / 0.7001 GB, free: 43.25 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000644/0.5045, allocations: 331.7 kB / 0.7004 GB, free: 43.22 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 3.977e-05/0.5046, allocations: 305.1 kB / 0.7007 GB, free: 42.92 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002204/0.5068, allocations: 0.9515 MB / 0.7016 GB, free: 42.77 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002342/0.5091, allocations: 1.155 MB / 0.7027 GB, free: 42.26 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004416/0.5135, allocations: 2.05 MB / 0.7047 GB, free: 41.32 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.005e-06/0.5135, allocations: 0.8125 kB / 0.7047 GB, free: 41.32 MB / 494.1 MB
Notification: Performance of matching and sorting (n=229) (initialization): time 0.01098/0.5245, allocations: 3.787 MB / 0.7084 GB, free: 40.09 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.372e-05/0.5245, allocations: 6.344 kB / 0.7084 GB, free: 40.09 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.272e-05/0.5246, allocations: 5.578 kB / 0.7084 GB, free: 40.09 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009834/0.5344, allocations: 2.667 MB / 0.711 GB, free: 39.7 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001493/0.5359, allocations: 303.7 kB / 0.7113 GB, free: 39.66 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006419/0.5423, allocations: 2.696 MB / 0.714 GB, free: 38.39 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002212/0.5445, allocations: 242.5 kB / 0.7142 GB, free: 38.32 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001619/0.5447, allocations: 60.91 kB / 0.7143 GB, free: 38.31 MB / 494.1 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 (174):
 * Single equations (assignments): 173
 * 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.0005321/0.5452, allocations: 301.7 kB / 0.7145 GB, free: 38.07 MB / 494.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000259/0.5455, allocations: 162.2 kB / 0.7147 GB, free: 37.98 MB / 494.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01796/0.5634, allocations: 4.721 MB / 0.7193 GB, free: 36.89 MB / 494.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.675e-05/0.5635, allocations: 13.22 kB / 0.7193 GB, free: 36.89 MB / 494.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.127e-05/0.5635, allocations: 3.844 kB / 0.7193 GB, free: 36.89 MB / 494.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.157e-05/0.5635, allocations: 9.469 kB / 0.7193 GB, free: 36.89 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 postOpt removeSimpleEquations (simulation): time 0.02358/0.5871, allocations: 6.827 MB / 0.726 GB, free: 33.32 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.656e-05/0.5871, allocations: 0 / 0.726 GB, free: 33.32 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00115/0.5882, allocations: 135.2 kB / 0.7261 GB, free: 33.3 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01029/0.5985, allocations: 2.141 MB / 0.7282 GB, free: 32.37 MB / 494.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002757/0.5988, allocations: 23.95 kB / 0.7282 GB, free: 32.35 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02372/0.6225, allocations: 10.09 MB / 0.7381 GB, free: 22.96 MB / 494.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.355e-06/0.6225, allocations: 0 / 0.7381 GB, free: 22.96 MB / 494.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0165/0.639, allocations: 5.881 MB / 0.7438 GB, free: 17.06 MB / 494.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005639/0.6396, allocations: 278.4 kB / 0.7441 GB, free: 16.79 MB / 494.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002514/0.6398, allocations: 27.94 kB / 0.7441 GB, free: 16.76 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001477/0.6413, allocations: 135.8 kB / 0.7443 GB, free: 16.62 MB / 494.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007338/0.6421, allocations: 257.4 kB / 0.7445 GB, free: 16.37 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001011/0.6422, allocations: 32 kB / 0.7445 GB, free: 16.34 MB / 494.1 MB
Notification: Performance of sorting global known variables: time 0.001129/0.6433, allocations: 0.9172 MB / 0.7454 GB, free: 15.42 MB / 494.1 MB
Notification: Performance of sort global known variables: time 1e-07/0.6433, allocations: 0 / 0.7454 GB, free: 15.42 MB / 494.1 MB
Notification: Performance of remove unused functions: time 0.001276/0.6446, allocations: 0.5145 MB / 0.7459 GB, free: 14.92 MB / 494.1 MB
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 (81):
 * Single equations (assignments): 80
 * 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.0002926/0.6449, allocations: 150.4 kB / 0.7461 GB, free: 14.78 MB / 494.1 MB
Notification: Performance of simCode: created initialization part: time 0.00758/0.6524, allocations: 2.652 MB / 0.7487 GB, free: 12.13 MB / 494.1 MB
Notification: Performance of simCode: created event and clocks part: time 3.777e-06/0.6524, allocations: 1.969 kB / 0.7487 GB, free: 12.13 MB / 494.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.005418/0.6579, allocations: 1.838 MB / 0.7505 GB, free: 10.29 MB / 494.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001128/0.659, allocations: 138.5 kB / 0.7506 GB, free: 10.16 MB / 494.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004541/0.6635, allocations: 2.982 MB / 0.7535 GB, free: 7.195 MB / 494.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004538/0.664, allocations: 1.207 MB / 0.7547 GB, free: 5.957 MB / 494.1 MB
Notification: Performance of simCode: alias equations: time 0.0004647/0.6644, allocations: 141.7 kB / 0.7548 GB, free: 5.816 MB / 494.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003323/0.6648, allocations: 95.41 kB / 0.7549 GB, free: 5.723 MB / 494.1 MB
Notification: Performance of SimCode: time 8.62e-07/0.6648, allocations: 0 / 0.7549 GB, free: 5.723 MB / 494.1 MB
Notification: Performance of Templates: time 0.0484/0.7132, allocations: 29.84 MB / 0.7841 GB, free: 7.914 MB / 0.5137 GB
"
[Timeout remaining time 659]
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: 6.513198364526033]