Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.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.001541/0.001541, allocations: 108.3 kB / 18.71 MB, free: 3.305 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.432/1.432, allocations: 225.6 MB / 247.4 MB, free: 3.352 MB / 190.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.001417/0.001417, allocations: 192.7 kB / 302.7 MB, free: 3.457 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.3595/0.3595, allocations: 79.74 MB / 437.6 MB, free: 7.293 MB / 366.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.Loads.PSAT.LoadTestThLoad,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad")
translateModel(OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002421/0.002421, allocations: 83.86 kB / 0.5833 GB, free: 8.086 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2983/0.3007, allocations: 72.08 MB / 0.6536 GB, free: 18.61 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad): time 0.006111/0.3068, allocations: 5.941 MB / 0.6594 GB, free: 14.33 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003471/0.3103, allocations: 1.912 MB / 0.6613 GB, free: 12.97 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008841/0.3111, allocations: 13.25 kB / 0.6613 GB, free: 12.97 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008234/0.312, allocations: 203.3 kB / 0.6615 GB, free: 12.86 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002047/0.314, allocations: 0.7149 MB / 0.6622 GB, free: 12.38 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001544/0.3156, allocations: 0.6411 MB / 0.6628 GB, free: 12.03 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00183/0.3174, allocations: 1.294 MB / 0.6641 GB, free: 11.39 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004467/0.3178, allocations: 237.7 kB / 0.6643 GB, free: 11.25 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008836/0.3187, allocations: 0.5336 MB / 0.6649 GB, free: 10.98 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00102/0.3197, allocations: 0.691 MB / 0.6655 GB, free: 10.64 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000298/0.32, allocations: 104.5 kB / 0.6656 GB, free: 10.64 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005701/0.3206, allocations: 233.8 kB / 0.6659 GB, free: 10.6 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002263/0.3208, allocations: 288.1 kB / 0.6661 GB, free: 10.41 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004315/0.3213, allocations: 0.5867 MB / 0.6667 GB, free: 10.01 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001526/0.3228, allocations: 1.479 MB / 0.6681 GB, free: 9.312 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.917e-06/0.3228, allocations: 0 / 0.6681 GB, free: 9.312 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.343e-06/0.3228, allocations: 0 / 0.6681 GB, free: 9.312 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 1.863e-05/0.3228, allocations: 1.656 kB / 0.6681 GB, free: 9.312 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 184
 * Number of variables: 184
Notification: Performance of Generate backend data structure: time 0.006735/0.3296, allocations: 2.378 MB / 0.6705 GB, free: 7.793 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.823e-05/0.3296, allocations: 8.969 kB / 0.6705 GB, free: 7.793 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004106/0.3337, allocations: 0.6765 MB / 0.6711 GB, free: 7.504 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009247/0.3346, allocations: 0.7571 MB / 0.6719 GB, free: 6.855 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001902/0.3348, allocations: 219.3 kB / 0.6721 GB, free: 6.656 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001343/0.3349, allocations: 77.84 kB / 0.6722 GB, free: 6.629 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00206/0.337, allocations: 1.086 MB / 0.6732 GB, free: 6 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.912e-05/0.337, allocations: 3.938 kB / 0.6732 GB, free: 5.996 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.852e-05/0.3371, allocations: 42.12 kB / 0.6733 GB, free: 5.961 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.727e-05/0.3371, allocations: 18.25 kB / 0.6733 GB, free: 5.961 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002211/0.3393, allocations: 1.025 MB / 0.6743 GB, free: 5.484 MB / 462.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.004343/0.3437, allocations: 2.955 MB / 0.6772 GB, free: 3.188 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002007/0.3457, allocations: 1.196 MB / 0.6783 GB, free: 2.363 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001348/0.347, allocations: 0.6382 MB / 0.679 GB, free: 2.047 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000101/0.3471, allocations: 18.23 kB / 0.679 GB, free: 2.039 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 1.932e-05/0.3472, allocations: 39.91 kB / 0.679 GB, free: 1.992 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=89): time 2.624e-06/0.3472, allocations: 0 / 0.679 GB, free: 1.992 MB / 462.1 MB
Notification: Performance of matching and sorting (n=89): time 0.02402/0.3712, allocations: 6.188 MB / 0.6851 GB, free: 14.9 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.952e-05/0.3713, allocations: 86.36 kB / 0.6851 GB, free: 14.8 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001417/0.3727, allocations: 0.8932 MB / 0.686 GB, free: 14.29 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001792/0.3729, allocations: 40.48 kB / 0.6861 GB, free: 14.25 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004276/0.3733, allocations: 477.9 kB / 0.6865 GB, free: 13.84 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003822/0.3737, allocations: 336.7 kB / 0.6868 GB, free: 13.54 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004225/0.3741, allocations: 200.7 kB / 0.687 GB, free: 13.42 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.000173/0.3743, allocations: 359.4 kB / 0.6874 GB, free: 13.07 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001666/0.3759, allocations: 0.6759 MB / 0.688 GB, free: 12.73 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001816/0.3777, allocations: 0.8023 MB / 0.6888 GB, free: 12.02 MB / 478.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003507/0.3812, allocations: 1.459 MB / 0.6902 GB, free: 10.54 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.044e-06/0.3813, allocations: 4 kB / 0.6902 GB, free: 10.53 MB / 478.1 MB
Notification: Performance of matching and sorting (n=140) (initialization): time 0.01304/0.3943, allocations: 3.789 MB / 0.6939 GB, free: 6.727 MB / 478.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.018e-05/0.3943, allocations: 8 kB / 0.6939 GB, free: 6.719 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.028e-05/0.3943, allocations: 8.25 kB / 0.694 GB, free: 6.711 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006114/0.4004, allocations: 1.589 MB / 0.6955 GB, free: 5.117 MB / 478.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001026/0.4015, allocations: 187.8 kB / 0.6957 GB, free: 4.934 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02283/0.4243, allocations: 8.929 MB / 0.7044 GB, free: 11.86 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001526/0.4258, allocations: 175.3 kB / 0.7046 GB, free: 11.69 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001239/0.4259, allocations: 39.98 kB / 0.7046 GB, free: 11.65 MB / 494.1 MB
Warning: Assuming fixed start value for the following 1 variables:
         order3_Inputs_Outputs1.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.002051/0.428, allocations: 0.8343 MB / 0.7054 GB, free: 10.81 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001783/0.4298, allocations: 0.7963 MB / 0.7062 GB, free: 9.977 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003379/0.4332, allocations: 1.439 MB / 0.7076 GB, free: 8.512 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.966e-06/0.4332, allocations: 0 / 0.7076 GB, free: 8.512 MB / 494.1 MB
Notification: Performance of matching and sorting (n=140) (initialization_lambda0): time 0.01276/0.4459, allocations: 3.711 MB / 0.7112 GB, free: 4.785 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 1.848e-05/0.4459, allocations: 8 kB / 0.7112 GB, free: 4.777 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.533e-06/0.4459, allocations: 3.938 kB / 0.7112 GB, free: 4.773 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005914/0.4519, allocations: 1.582 MB / 0.7128 GB, free: 3.188 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009792/0.4528, allocations: 187.8 kB / 0.713 GB, free: 3.004 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02134/0.4742, allocations: 8.705 MB / 0.7215 GB, free: 10.15 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001542/0.4757, allocations: 155.5 kB / 0.7216 GB, free: 9.996 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001149/0.4758, allocations: 39.98 kB / 0.7217 GB, free: 9.957 MB / 0.4981 GB
Warning: Assuming fixed start value for the following 1 variables:
         order3_Inputs_Outputs1.w:VARIABLE(flow=false start = 1.0 fixed = true )  \"Rotor speed [pu]\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * Number of states: 0 ()
 * Number of discrete variables: 1 (thLoad.Limiter.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (99):
 * Single equations (assignments): 98
 * 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
   {(20,22)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003623/0.4762, allocations: 224.1 kB / 0.7219 GB, free: 9.73 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00025/0.4764, allocations: 127.2 kB / 0.722 GB, free: 9.605 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01214/0.4886, allocations: 3.483 MB / 0.7254 GB, free: 6.109 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.729e-05/0.4886, allocations: 15.86 kB / 0.7254 GB, free: 6.094 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.567e-06/0.4886, allocations: 0 / 0.7254 GB, free: 6.094 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003895/0.489, allocations: 200.1 kB / 0.7256 GB, free: 5.879 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001898/0.4892, allocations: 19.98 kB / 0.7256 GB, free: 5.859 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009793/0.4902, allocations: 99.89 kB / 0.7257 GB, free: 5.762 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001182/0.4913, allocations: 420.3 kB / 0.7261 GB, free: 5.352 MB / 0.4981 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.006606/0.498, allocations: 2.079 MB / 0.7281 GB, free: 3.262 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005106/0.5031, allocations: 2.188 MB / 0.7303 GB, free: 1.051 MB / 0.4981 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001508/0.5046, allocations: 0.5543 MB / 0.7308 GB, free: 0.4961 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.001001/0.5056, allocations: 0.6034 MB / 0.7314 GB, free: 15.88 MB / 0.5137 GB
Notification: Performance of Backend: time 2.81e-07/0.5056, allocations: 0 / 0.7314 GB, free: 15.88 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.01072/0.5163, allocations: 3.291 MB / 0.7346 GB, free: 12.57 MB / 0.5137 GB
Notification: Performance of SimCode: time 0.004009/0.5203, allocations: 2.655 MB / 0.7372 GB, free: 9.852 MB / 0.5137 GB
Notification: Performance of Templates: time 0.05375/0.5741, allocations: 33.44 MB / 0.7699 GB, free: 8.449 MB / 0.545 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.sim & ./OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Loads.PSAT.LoadTestThLoad.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.2566144849406555]
Failed to read output from testmodel.py, exit status != 0:
0.5827233430463821 0.596958429 0.27099917300000004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3ca9df2050>