Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.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.001406/0.001406, allocations: 77.55 kB / 19.76 MB, free: 1.898 MB / 14.72 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.439/1.439, allocations: 177.2 MB / 200.1 MB, free: 9.496 MB / 190.1 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.001639/0.001639, allocations: 158.6 kB / 256.6 MB, free: 0.9258 MB / 238.1 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.4499/0.4499, allocations: 80.32 MB / 393.3 MB, free: 8.383 MB / 318.1 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.WSIEG1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=100000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1")
translateModel(OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=100000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1") [Timeout 660]
"Notification: OpenIPSL requested package Complex of version 4.0.0. Complex 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002833/0.002833, allocations: 80 kB / 0.5558 GB, free: 4.102 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3712/0.374, allocations: 80.13 MB / 0.6341 GB, free: 5.223 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1): time 0.008805/0.3828, allocations: 8.117 MB / 0.642 GB, free: 15.74 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.008139/0.391, allocations: 3.317 MB / 0.6453 GB, free: 13.47 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002062/0.393, allocations: 25.5 kB / 0.6453 GB, free: 13.46 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.001393/0.3944, allocations: 428.3 kB / 0.6457 GB, free: 13.24 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.004582/0.399, allocations: 1.307 MB / 0.647 GB, free: 12.41 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003287/0.4023, allocations: 1.003 MB / 0.6479 GB, free: 11.88 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.005571/0.4079, allocations: 2.683 MB / 0.6506 GB, free: 10.56 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008695/0.4088, allocations: 344.5 kB / 0.6509 GB, free: 10.36 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003461/0.4122, allocations: 1.549 MB / 0.6524 GB, free: 9.59 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002022/0.4142, allocations: 1.198 MB / 0.6536 GB, free: 8.789 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005202/0.4148, allocations: 219.6 kB / 0.6538 GB, free: 8.789 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001194/0.4159, allocations: 436.9 kB / 0.6542 GB, free: 8.695 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0006483/0.4166, allocations: 0.5392 MB / 0.6547 GB, free: 8.23 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.001165/0.4178, allocations: 0.8643 MB / 0.6556 GB, free: 7.594 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.003757/0.4215, allocations: 2.807 MB / 0.6583 GB, free: 5.488 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 3.938e-06/0.4215, allocations: 0 / 0.6583 GB, free: 5.488 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.774e-06/0.4215, allocations: 0 / 0.6583 GB, free: 5.488 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 4.893e-05/0.4216, allocations: 0 / 0.6583 GB, free: 5.488 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 285
 * Number of variables: 285
Notification: Performance of Generate backend data structure: time 0.015/0.4366, allocations: 3.945 MB / 0.6622 GB, free: 3.125 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.21e-05/0.4366, allocations: 9.156 kB / 0.6622 GB, free: 3.125 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005172/0.4418, allocations: 0.8021 MB / 0.663 GB, free: 2.777 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002875/0.4447, allocations: 1.252 MB / 0.6642 GB, free: 1.816 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008697/0.4455, allocations: 0.8246 MB / 0.665 GB, free: 0.9258 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003055/0.4458, allocations: 136.6 kB / 0.6651 GB, free: 0.875 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003884/0.4497, allocations: 1.693 MB / 0.6668 GB, free: 15.83 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.902e-05/0.4498, allocations: 0 / 0.6668 GB, free: 15.83 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001605/0.4499, allocations: 46.66 kB / 0.6668 GB, free: 15.79 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.676e-05/0.4499, allocations: 26.72 kB / 0.6668 GB, free: 15.79 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003919/0.4539, allocations: 1.566 MB / 0.6684 GB, free: 15.01 MB / 0.5137 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.01135/0.4652, allocations: 4.445 MB / 0.6727 GB, free: 11.88 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009711/0.4749, allocations: 2.622 MB / 0.6753 GB, free: 10.65 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002578/0.4775, allocations: 0.9625 MB / 0.6762 GB, free: 10.14 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001451/0.4776, allocations: 20.91 kB / 0.6762 GB, free: 10.13 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002956/0.4806, allocations: 1.301 MB / 0.6775 GB, free: 9.293 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=146): time 4.669e-06/0.4806, allocations: 0 / 0.6775 GB, free: 9.293 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=146): time 0.04922/0.5298, allocations: 10.37 MB / 0.6876 GB, free: 0.5469 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001895/0.53, allocations: 252.6 kB / 0.6879 GB, free: 252 kB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003081/0.5331, allocations: 1.662 MB / 0.6895 GB, free: 14.57 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003737/0.5335, allocations: 57.7 kB / 0.6895 GB, free: 14.51 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008286/0.5343, allocations: 0.7548 MB / 0.6903 GB, free: 13.75 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007989/0.5351, allocations: 0.5462 MB / 0.6908 GB, free: 13.2 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008459/0.5359, allocations: 367.7 kB / 0.6912 GB, free: 12.83 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001415/0.5361, allocations: 301.1 kB / 0.6915 GB, free: 12.54 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002994/0.5391, allocations: 1.139 MB / 0.6926 GB, free: 11.38 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003222/0.5423, allocations: 1.416 MB / 0.6939 GB, free: 9.867 MB / 0.5294 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: wSIEG1.Pp_max = wSIEG1.P_MAX (wSIEG1.Gov_Integrator.outMax = wSIEG1.P_MAX)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: wSIEG1.Pp_min = wSIEG1.P_MIN (wSIEG1.Gov_Integrator.outMin = wSIEG1.P_MIN)
Notification: Performance of analyzeInitialSystem (initialization): time 0.03144/0.5737, allocations: 7.327 MB / 0.7011 GB, free: 2.438 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.437e-06/0.5738, allocations: 0 / 0.7011 GB, free: 2.438 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=264) (initialization): time 0.009239/0.583, allocations: 3.512 MB / 0.7045 GB, free: 14.9 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 4.974e-05/0.583, allocations: 33.7 kB / 0.7046 GB, free: 14.86 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.671e-05/0.5831, allocations: 4 kB / 0.7046 GB, free: 14.86 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01081/0.5939, allocations: 2.715 MB / 0.7072 GB, free: 12.14 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001951/0.5958, allocations: 375.5 kB / 0.7076 GB, free: 11.77 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007411/0.6032, allocations: 2.721 MB / 0.7102 GB, free: 9.027 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002887/0.6061, allocations: 286.7 kB / 0.7105 GB, free: 8.746 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002192/0.6063, allocations: 75.92 kB / 0.7106 GB, free: 8.672 MB / 0.545 GB
Warning: Assuming fixed start value for the following 1 variables:
         wSIEG1.deadband2.y:DISCRETE(flow=false start = wSIEG1.GV0 fixed = true )  \"Connector of Real output signal\" type: Real
Warning: The initial conditions are over specified. The following 2 initial equations are redundant, so they are removed from the initialization system:
         wSIEG1.Pp_max = wSIEG1.P_MAX
         wSIEG1.Pp_min = wSIEG1.P_MIN.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003497/0.6098, allocations: 1.387 MB / 0.7119 GB, free: 7.273 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003136/0.613, allocations: 1.397 MB / 0.7133 GB, free: 5.781 MB / 0.545 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: wSIEG1.Pp_max = wSIEG1.P_MAX (wSIEG1.Gov_Integrator.outMax = wSIEG1.P_MAX)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: wSIEG1.Pp_min = wSIEG1.P_MIN (wSIEG1.Gov_Integrator.outMin = wSIEG1.P_MIN)
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03092/0.6439, allocations: 7.305 MB / 0.7204 GB, free: 14.37 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.075e-06/0.6439, allocations: 0 / 0.7204 GB, free: 14.37 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=264) (initialization_lambda0): time 0.009054/0.653, allocations: 3.477 MB / 0.7238 GB, free: 10.87 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 4.383e-05/0.653, allocations: 41.73 kB / 0.7239 GB, free: 10.82 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.622e-05/0.653, allocations: 7.984 kB / 0.7239 GB, free: 10.82 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0106/0.6636, allocations: 2.708 MB / 0.7265 GB, free: 8.105 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001941/0.6656, allocations: 371.5 kB / 0.7269 GB, free: 7.742 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007329/0.6729, allocations: 2.721 MB / 0.7295 GB, free: 4.992 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002912/0.6758, allocations: 274.8 kB / 0.7298 GB, free: 4.723 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002099/0.676, allocations: 75.92 kB / 0.7299 GB, free: 4.648 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 1 variables:
         wSIEG1.deadband2.y:DISCRETE(flow=false start = wSIEG1.GV0 fixed = true )  \"Connector of Real output signal\" type: Real
Warning: The initial conditions are over specified. The following 2 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         wSIEG1.Pp_max = wSIEG1.P_MAX
         wSIEG1.Pp_min = wSIEG1.P_MIN.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($PRE.wSIEG1.deadband2.y,wSIEG1.deadband2.y,$whenCondition3,$whenCondition2,$whenCondition1,wSIEG1.Gov_Integrator.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (207):
 * Single equations (assignments): 205
 * Array equations: 0
 * Algorithm blocks: 1
 * 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.0005844/0.6766, allocations: 305.6 kB / 0.7302 GB, free: 4.336 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004521/0.6771, allocations: 210.6 kB / 0.7304 GB, free: 4.129 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01952/0.6966, allocations: 5.165 MB / 0.7354 GB, free: 14.93 MB / 0.5762 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.442e-05/0.6966, allocations: 3.984 kB / 0.7354 GB, free: 14.93 MB / 0.5762 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.505e-06/0.6966, allocations: 4 kB / 0.7354 GB, free: 14.92 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009755/0.6976, allocations: 230.3 kB / 0.7356 GB, free: 14.7 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005076/0.6981, allocations: 28 kB / 0.7357 GB, free: 14.67 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001936/0.7001, allocations: 159.8 kB / 0.7358 GB, free: 14.51 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001692/0.7018, allocations: 0.5822 MB / 0.7364 GB, free: 13.93 MB / 0.5762 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01049/0.7122, allocations: 3.199 MB / 0.7395 GB, free: 10.71 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008199/0.7204, allocations: 3.447 MB / 0.7429 GB, free: 7.219 MB / 0.5762 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002372/0.7228, allocations: 0.8863 MB / 0.7437 GB, free: 6.328 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.002034/0.7248, allocations: 1.114 MB / 0.7448 GB, free: 5.223 MB / 0.5762 GB
Notification: Performance of Backend: time 9.22e-07/0.7248, allocations: 0 / 0.7448 GB, free: 5.223 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.01984/0.7447, allocations: 4.863 MB / 0.7496 GB, free: 0.5039 MB / 0.5762 GB
Notification: Performance of SimCode: time 0.006746/0.7514, allocations: 4.239 MB / 0.7537 GB, free: 12.2 MB / 0.5919 GB
Notification: Performance of Templates: time 0.07587/0.8273, allocations: 45.45 MB / 0.7981 GB, free: 14.87 MB / 0.6387 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.sim & ./OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Controls.PSSE.TG.WSIEG1.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 7.693909815046936]
Failed to read output from testmodel.py, exit status != 0:
0.8375268029049039 0.854005805 0.428921361
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f1c8d7a6fe0>