Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.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.001478/0.001478, allocations: 111.2 kB / 18.7 MB, free: 3.105 MB / 14.72 MB
"
[Timeout remaining time 180]
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.004531/0.004531, allocations: 189.6 kB / 21.96 MB, free: 5.137 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.331/1.331, allocations: 225.6 MB / 250.7 MB, free: 13.36 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 1.039/1.039, allocations: 175.7 MB / 481.6 MB, free: 7.828 MB / 382.1 MB
"
[Timeout remaining time 179]
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/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 Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(IDEAS.Fluid.Movers.Validation.Pump_y_stratos,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos")
translateModel(IDEAS.Fluid.Movers.Validation.Pump_y_stratos,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001904/0.001904, allocations: 72 kB / 0.6701 GB, free: 14.13 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4466/0.4485, allocations: 94.53 MB / 0.7624 GB, free: 9.902 MB / 0.5762 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Movers.Validation.Pump_y_stratos): time 0.2267/0.6752, allocations: 215.9 MB / 0.9733 GB, free: 14.38 MB / 0.7637 GB
Notification: Performance of NFInst.instExpressions: time 0.1015/0.7767, allocations: 70.47 MB / 1.042 GB, free: 7.75 MB / 0.8262 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01109/0.7878, allocations: 374.1 kB / 1.042 GB, free: 7.383 MB / 0.8262 GB
Notification: Performance of NFTyping.typeComponents: time 0.01438/0.8021, allocations: 5.483 MB / 1.048 GB, free: 1.871 MB / 0.8262 GB
Notification: Performance of NFTyping.typeBindings: time 0.02951/0.8317, allocations: 11.76 MB / 1.059 GB, free: 6.059 MB / 0.8419 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02/0.8516, allocations: 8.912 MB / 1.068 GB, free: 13.2 MB / 0.8575 GB
Notification: Performance of NFFlatten.flatten: time 0.463/1.315, allocations: 49.75 MB / 1.117 GB, free: 30.8 MB / 0.8731 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01283/1.327, allocations: 6.822 MB / 1.123 GB, free: 30.3 MB / 0.8731 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02096/1.348, allocations: 9.96 MB / 1.133 GB, free: 30.27 MB / 0.8731 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01229/1.361, allocations: 7.764 MB / 1.141 GB, free: 28.97 MB / 0.8731 GB
Notification: Performance of NFPackage.collectConstants: time 0.003899/1.365, allocations: 1.073 MB / 1.142 GB, free: 28.97 MB / 0.8731 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01133/1.376, allocations: 4.053 MB / 1.146 GB, free: 28.97 MB / 0.8731 GB
Notification: Performance of NFScalarize.scalarize: time 0.006002/1.382, allocations: 5.42 MB / 1.151 GB, free: 28.29 MB / 0.8731 GB
Notification: Performance of NFVerifyModel.verify: time 0.01366/1.396, allocations: 7.837 MB / 1.158 GB, free: 25.97 MB / 0.8731 GB
Notification: Performance of NFConvertDAE.convert: time 0.02891/1.424, allocations: 21.86 MB / 1.18 GB, free: 22.68 MB / 0.8731 GB
Notification: Performance of FrontEnd - DAE generated: time 5.11e-06/1.424, allocations: 0 / 1.18 GB, free: 22.68 MB / 0.8731 GB
Notification: Performance of FrontEnd: time 1.433e-06/1.424, allocations: 0 / 1.18 GB, free: 22.68 MB / 0.8731 GB
Notification: Performance of Transformations before backend: time 0.000657/1.425, allocations: 0 / 1.18 GB, free: 22.68 MB / 0.8731 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1224
 * Number of variables: 1224
Notification: Performance of Generate backend data structure: time 0.03827/1.463, allocations: 16.38 MB / 1.196 GB, free: 13.86 MB / 0.8731 GB
Notification: Performance of prepare preOptimizeDAE: time 4.509e-05/1.463, allocations: 11.06 kB / 1.196 GB, free: 13.86 MB / 0.8731 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02593/1.489, allocations: 4.832 MB / 1.201 GB, free: 11.65 MB / 0.8731 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03874/1.528, allocations: 22.29 MB / 1.222 GB, free: 3.043 MB / 0.8731 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004152/1.529, allocations: 417.5 kB / 1.223 GB, free: 2.809 MB / 0.8731 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00185/1.53, allocations: 0.5463 MB / 1.223 GB, free: 2.438 MB / 0.8731 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0176/1.548, allocations: 7.688 MB / 1.231 GB, free: 12.8 MB / 0.8887 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001235/1.548, allocations: 10.66 kB / 1.231 GB, free: 12.8 MB / 0.8887 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0009921/1.549, allocations: 201 kB / 1.231 GB, free: 12.75 MB / 0.8887 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001172/1.549, allocations: 120 kB / 1.231 GB, free: 12.64 MB / 0.8887 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01733/1.567, allocations: 6.143 MB / 1.237 GB, free: 8.293 MB / 0.8887 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.06089/1.627, allocations: 34.05 MB / 1.27 GB, free: 11.7 MB / 0.92 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01512/1.643, allocations: 4.741 MB / 1.275 GB, free: 6.98 MB / 0.92 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007024/1.65, allocations: 2.352 MB / 1.277 GB, free: 4.629 MB / 0.92 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02444/1.674, allocations: 11.39 MB / 1.288 GB, free: 8.688 MB / 0.9356 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.834e-05/1.674, allocations: 59.52 kB / 1.288 GB, free: 8.625 MB / 0.9356 GB
Notification: Performance of pre-optimization done (n=237): time 4.128e-06/1.674, allocations: 0 / 1.288 GB, free: 8.625 MB / 0.9356 GB
Notification: Performance of matching and sorting (n=237): time 0.02784/1.702, allocations: 8.02 MB / 1.296 GB, free: 0.5938 MB / 0.9356 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.644e-05/1.702, allocations: 73.41 kB / 1.296 GB, free: 0.5117 MB / 0.9356 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03839/1.74, allocations: 16.43 MB / 1.312 GB, free: 176 kB / 0.9512 GB
Notification: Performance of collectPreVariables (initialization): time 0.001288/1.742, allocations: 105.7 kB / 1.312 GB, free: 64 kB / 0.9512 GB
Notification: Performance of collectInitialEqns (initialization): time 0.009225/1.751, allocations: 6.25 MB / 1.319 GB, free: 9.871 MB / 0.9669 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001849/1.753, allocations: 0.9248 MB / 1.32 GB, free: 8.957 MB / 0.9669 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.006486/1.759, allocations: 2.167 MB / 1.322 GB, free: 6.785 MB / 0.9669 GB
Notification: Performance of setup shared object (initialization): time 0.0001367/1.759, allocations: 308.8 kB / 1.322 GB, free: 6.484 MB / 0.9669 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01299/1.772, allocations: 6.073 MB / 1.328 GB, free: 416 kB / 0.9669 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01595/1.788, allocations: 8.616 MB / 1.336 GB, free: 6.832 MB / 0.9825 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02548/1.814, allocations: 13.48 MB / 1.349 GB, free: 8.703 MB / 0.9981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.098e-05/1.814, allocations: 24 kB / 1.349 GB, free: 8.68 MB / 0.9981 GB
Notification: Performance of matching and sorting (n=1247) (initialization): time 0.03593/1.85, allocations: 15.99 MB / 1.365 GB, free: 9.004 MB / 1.014 GB
Notification: Performance of prepare postOptimizeDAE: time 7.836e-05/1.85, allocations: 32 kB / 1.365 GB, free: 8.973 MB / 1.014 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001074/1.85, allocations: 64 kB / 1.365 GB, free: 8.91 MB / 1.014 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000134/1.85, allocations: 96 kB / 1.365 GB, free: 8.816 MB / 1.014 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008522/1.859, allocations: 1.879 MB / 1.367 GB, free: 7.043 MB / 1.014 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001793/1.859, allocations: 139.5 kB / 1.367 GB, free: 6.93 MB / 1.014 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01193/1.871, allocations: 1.269 MB / 1.368 GB, free: 5.664 MB / 1.014 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001546/1.872, allocations: 447.8 kB / 1.369 GB, free: 5.227 MB / 1.014 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01866/1.891, allocations: 8.434 MB / 1.377 GB, free: 12.78 MB / 1.029 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01453/1.905, allocations: 8.023 MB / 1.385 GB, free: 3.797 MB / 1.029 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.023/1.928, allocations: 12.34 MB / 1.397 GB, free: 6.824 MB / 1.045 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.228e-05/1.929, allocations: 16 kB / 1.397 GB, free: 6.809 MB / 1.045 GB
Notification: Performance of matching and sorting (n=1247) (initialization_lambda0): time 0.03332/1.962, allocations: 14.81 MB / 1.411 GB, free: 8.316 MB / 1.061 GB
Notification: Performance of prepare postOptimizeDAE: time 6.868e-05/1.962, allocations: 28 kB / 1.412 GB, free: 8.289 MB / 1.061 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001166/1.962, allocations: 64 kB / 1.412 GB, free: 8.227 MB / 1.061 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001119/1.962, allocations: 96 kB / 1.412 GB, free: 8.133 MB / 1.061 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008024/1.97, allocations: 1.818 MB / 1.413 GB, free: 6.418 MB / 1.061 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001733/1.97, allocations: 135.3 kB / 1.414 GB, free: 6.312 MB / 1.061 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01188/1.982, allocations: 1.016 MB / 1.415 GB, free: 5.293 MB / 1.061 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001617/1.984, allocations: 423.2 kB / 1.415 GB, free: 4.883 MB / 1.061 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 281
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (872):
 * Single equations (assignments): 852
 * Array equations: 10
 * Algorithm blocks: 0
 * Record equations: 10
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.002401/1.986, allocations: 0.5691 MB / 1.416 GB, free: 4.41 MB / 1.061 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004228/1.99, allocations: 1.311 MB / 1.417 GB, free: 3.09 MB / 1.061 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02161/2.012, allocations: 7.033 MB / 1.424 GB, free: 12.07 MB / 1.076 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.681e-05/2.012, allocations: 15.94 kB / 1.424 GB, free: 12.06 MB / 1.076 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.536e-06/2.012, allocations: 0 / 1.424 GB, free: 12.06 MB / 1.076 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.007719/2.02, allocations: 2.17 MB / 1.426 GB, free: 9.859 MB / 1.076 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004257/2.024, allocations: 44 kB / 1.426 GB, free: 9.816 MB / 1.076 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00906/2.033, allocations: 495.4 kB / 1.426 GB, free: 9.332 MB / 1.076 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004468/2.034, allocations: 182 kB / 1.426 GB, free: 9.152 MB / 1.076 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02099/2.055, allocations: 6.065 MB / 1.432 GB, free: 3.094 MB / 1.076 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02719/2.082, allocations: 9.69 MB / 1.442 GB, free: 9.32 MB / 1.092 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.007285/2.089, allocations: 2.434 MB / 1.444 GB, free: 6.871 MB / 1.092 GB
Notification: Performance of sorting global known variables: time 0.02381/2.113, allocations: 11.11 MB / 1.455 GB, free: 11.84 MB / 1.107 GB
Notification: Performance of Backend: time 5.61e-07/2.113, allocations: 0 / 1.455 GB, free: 11.84 MB / 1.107 GB
Notification: Performance of simCode: created initialization part: time 0.0391/2.152, allocations: 15.08 MB / 1.47 GB, free: 13.31 MB / 1.123 GB
Notification: Performance of SimCode: time 0.06256/2.215, allocations: 29.17 MB / 1.498 GB, free: 288 kB / 1.139 GB
Notification: Performance of Templates: time 0.9936/3.208, allocations: 296.2 MB / 1.788 GB, free: 286.2 MB / 1.139 GB
"
[Timeout remaining time 657]
make -j1 -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.sim & ./IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Movers.Validation.Pump_y_stratos.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 15.87842484912835]
Failed to read output from testmodel.py, exit status != 0:
3.216456046095118 3.224859401 1.798081199
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f26733c6050>