Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.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.001218/0.001218, 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.004556/0.004556, allocations: 188.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.326/1.326, 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.029/1.029, allocations: 175.8 MB / 481.6 MB, free: 7.816 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.SolarCollectors.Validation.FlatPlate,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate")
translateModel(IDEAS.Fluid.SolarCollectors.Validation.FlatPlate,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001798/0.001798, allocations: 63.88 kB / 0.6701 GB, free: 14.18 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4439/0.4457, allocations: 94.54 MB / 0.7624 GB, free: 9.957 MB / 0.5762 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.SolarCollectors.Validation.FlatPlate): time 0.0336/0.4793, allocations: 33.11 MB / 0.7947 GB, free: 2.992 MB / 0.5919 GB
Notification: Performance of NFInst.instExpressions: time 0.1938/0.6731, allocations: 161 MB / 0.9519 GB, free: 4.27 MB / 0.7325 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002929/0.676, allocations: 55.69 kB / 0.952 GB, free: 4.215 MB / 0.7325 GB
Notification: Performance of NFTyping.typeComponents: time 0.003114/0.6791, allocations: 0.8671 MB / 0.9528 GB, free: 3.348 MB / 0.7325 GB
Notification: Performance of NFTyping.typeBindings: time 0.006066/0.6852, allocations: 1.906 MB / 0.9547 GB, free: 1.434 MB / 0.7325 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005742/0.691, allocations: 2.174 MB / 0.9568 GB, free: 15.28 MB / 0.7481 GB
Notification: Performance of NFFlatten.flatten: time 0.03701/0.728, allocations: 35.77 MB / 0.9917 GB, free: 11.42 MB / 0.7794 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01745/0.7454, allocations: 11.69 MB / 1.003 GB, free: 15.59 MB / 0.795 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02256/0.768, allocations: 11.92 MB / 1.015 GB, free: 3.73 MB / 0.795 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01874/0.7867, allocations: 10.52 MB / 1.025 GB, free: 9.168 MB / 0.8106 GB
Notification: Performance of NFPackage.collectConstants: time 0.004858/0.7916, allocations: 1.566 MB / 1.027 GB, free: 7.602 MB / 0.8106 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009868/0.8014, allocations: 3.045 MB / 1.03 GB, free: 4.555 MB / 0.8106 GB
Notification: Performance of NFScalarize.scalarize: time 0.0064/0.8078, allocations: 4.119 MB / 1.034 GB, free: 432 kB / 0.8106 GB
Notification: Performance of NFVerifyModel.verify: time 0.01418/0.822, allocations: 7.966 MB / 1.041 GB, free: 8.418 MB / 0.8262 GB
Notification: Performance of NFConvertDAE.convert: time 0.0295/0.8515, allocations: 22.89 MB / 1.064 GB, free: 1.453 MB / 0.8419 GB
Notification: Performance of FrontEnd - DAE generated: time 4.879e-06/0.8515, allocations: 0 / 1.064 GB, free: 1.453 MB / 0.8419 GB
Notification: Performance of FrontEnd: time 1.693e-06/0.8515, allocations: 4 kB / 1.064 GB, free: 1.449 MB / 0.8419 GB
Notification: Performance of Transformations before backend: time 0.0006358/0.8522, allocations: 0 / 1.064 GB, free: 1.449 MB / 0.8419 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2428
 * Number of variables: 2428
Notification: Performance of Generate backend data structure: time 0.03909/0.8913, allocations: 15.53 MB / 1.079 GB, free: 1.777 MB / 0.8575 GB
Notification: Performance of prepare preOptimizeDAE: time 4.681e-05/0.8913, allocations: 8.031 kB / 1.079 GB, free: 1.77 MB / 0.8575 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01269/0.904, allocations: 2.334 MB / 1.081 GB, free: 15.42 MB / 0.8731 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02825/0.9322, allocations: 13.19 MB / 1.094 GB, free: 2.164 MB / 0.8731 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007112/0.933, allocations: 0.6702 MB / 1.095 GB, free: 1.492 MB / 0.8731 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003036/0.936, allocations: 0.91 MB / 1.096 GB, free: 0.582 MB / 0.8731 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.601/1.537, allocations: 14.77 MB / 1.11 GB, free: 276 MB / 0.8732 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002129/1.537, allocations: 29.84 kB / 1.11 GB, free: 276 MB / 0.8732 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00111/1.538, allocations: 398.2 kB / 1.11 GB, free: 275.7 MB / 0.8732 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003425/1.539, allocations: 482 kB / 1.111 GB, free: 275.7 MB / 0.8732 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02892/1.568, allocations: 13.61 MB / 1.124 GB, free: 271.5 MB / 0.8732 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.07817/1.646, allocations: 55.37 MB / 1.178 GB, free: 230.9 MB / 0.8732 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01598/1.662, allocations: 9.681 MB / 1.188 GB, free: 222.5 MB / 0.8732 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006896/1.669, allocations: 4.659 MB / 1.192 GB, free: 217.8 MB / 0.8732 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0009989/1.67, allocations: 1.018 MB / 1.193 GB, free: 216.7 MB / 0.8732 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007786/1.677, allocations: 5.042 MB / 1.198 GB, free: 211.6 MB / 0.8732 GB
Notification: Performance of pre-optimization done (n=629): time 8.115e-06/1.677, allocations: 0 / 1.198 GB, free: 211.6 MB / 0.8732 GB
Notification: Performance of matching and sorting (n=629): time 0.0214/1.699, allocations: 12.76 MB / 1.211 GB, free: 198.7 MB / 0.8732 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.146e-05/1.699, allocations: 304.2 kB / 1.211 GB, free: 198.4 MB / 0.8732 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01346/1.712, allocations: 10.3 MB / 1.221 GB, free: 188.1 MB / 0.8732 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007553/1.713, allocations: 153.7 kB / 1.221 GB, free: 188 MB / 0.8732 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003846/1.717, allocations: 5.314 MB / 1.226 GB, free: 183.1 MB / 0.8732 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001857/1.719, allocations: 2.264 MB / 1.229 GB, free: 181.2 MB / 0.8732 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002198/1.721, allocations: 1.509 MB / 1.23 GB, free: 179.7 MB / 0.8732 GB
Notification: Performance of setup shared object (initialization): time 3.405e-05/1.721, allocations: 309 kB / 1.23 GB, free: 179.3 MB / 0.8732 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006788/1.728, allocations: 4.403 MB / 1.235 GB, free: 174.9 MB / 0.8732 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0075/1.735, allocations: 6.281 MB / 1.241 GB, free: 167.9 MB / 0.8732 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01491/1.75, allocations: 11.01 MB / 1.251 GB, free: 156.1 MB / 0.8732 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.573e-05/1.75, allocations: 16 kB / 1.252 GB, free: 156.1 MB / 0.8732 GB
Notification: Performance of matching and sorting (n=966) (initialization): time 0.02309/1.773, allocations: 12.04 MB / 1.263 GB, free: 143.9 MB / 0.8732 GB
Notification: Performance of prepare postOptimizeDAE: time 6.845e-05/1.773, allocations: 61.69 kB / 1.263 GB, free: 143.9 MB / 0.8732 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.56e-05/1.773, allocations: 56 kB / 1.263 GB, free: 143.8 MB / 0.8732 GB
Notification: Performance of postOpt tearingSystem (initialization): time 7.728e-05/1.774, allocations: 92 kB / 1.263 GB, free: 143.7 MB / 0.8732 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007751/1.781, allocations: 1.826 MB / 1.265 GB, free: 141.9 MB / 0.8732 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.000112/1.781, allocations: 127.6 kB / 1.265 GB, free: 141.8 MB / 0.8732 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009118/1.791, allocations: 1.128 MB / 1.266 GB, free: 140.9 MB / 0.8732 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008131/1.791, allocations: 359.8 kB / 1.267 GB, free: 140.5 MB / 0.8732 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01195/1.803, allocations: 8.173 MB / 1.275 GB, free: 132.3 MB / 0.8732 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.007794/1.811, allocations: 6.281 MB / 1.281 GB, free: 125.3 MB / 0.8732 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01486/1.826, allocations: 11.01 MB / 1.292 GB, free: 113.5 MB / 0.8732 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.299e-05/1.826, allocations: 16 kB / 1.292 GB, free: 113.5 MB / 0.8732 GB
Notification: Performance of matching and sorting (n=966) (initialization_lambda0): time 0.01994/1.846, allocations: 12.02 MB / 1.303 GB, free: 101.4 MB / 0.8732 GB
Notification: Performance of prepare postOptimizeDAE: time 6.315e-05/1.846, allocations: 69.69 kB / 1.304 GB, free: 101.3 MB / 0.8732 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.486e-05/1.846, allocations: 52 kB / 1.304 GB, free: 101.2 MB / 0.8732 GB
Notification: Performance of postOpt tearingSystem (initialization): time 6.439e-05/1.846, allocations: 92 kB / 1.304 GB, free: 101.1 MB / 0.8732 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007277/1.853, allocations: 1.833 MB / 1.305 GB, free: 99.31 MB / 0.8732 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001112/1.854, allocations: 127.5 kB / 1.306 GB, free: 99.18 MB / 0.8732 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008805/1.862, allocations: 0.9151 MB / 1.306 GB, free: 98.4 MB / 0.8732 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006575/1.863, allocations: 363.8 kB / 1.307 GB, free: 98.04 MB / 0.8732 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 244
 * Number of states: 0 ()
 * Number of discrete variables: 28 ($PRE.datRea.nextTimeEvent,$whenCondition1,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,datRea.nextTimeEventScaled,$PRE.datRea.nextTimeEventScaled,datRea.nextTimeEvent,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,solCol.HDirTil.incAng.decAng.tNext,$PRE.solCol.HDirTil.incAng.decAng.tNext,solCol.HDifTilIso.incAng.decAng.tNext,$PRE.solCol.HDifTilIso.incAng.decAng.tNext,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (957):
 * Single equations (assignments): 956
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * 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.001615/1.865, allocations: 0.6577 MB / 1.307 GB, free: 97.41 MB / 0.8732 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002696/1.867, allocations: 1.021 MB / 1.308 GB, free: 96.38 MB / 0.8732 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01716/1.884, allocations: 10.28 MB / 1.318 GB, free: 86.04 MB / 0.8732 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01293/1.897, allocations: 8.222 MB / 1.327 GB, free: 77.74 MB / 0.8732 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.335e-05/1.897, allocations: 7.844 kB / 1.327 GB, free: 77.73 MB / 0.8732 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006396/1.904, allocations: 2.463 MB / 1.329 GB, free: 75.3 MB / 0.8732 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002532/1.906, allocations: 143.9 kB / 1.329 GB, free: 75.16 MB / 0.8732 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.007899/1.914, allocations: 0.5206 MB / 1.33 GB, free: 74.65 MB / 0.8732 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006156/1.915, allocations: 384.5 kB / 1.33 GB, free: 74.27 MB / 0.8732 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01861/1.933, allocations: 9.967 MB / 1.34 GB, free: 64.29 MB / 0.8732 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02251/1.956, allocations: 16.06 MB / 1.355 GB, free: 48.1 MB / 0.8732 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.005872/1.962, allocations: 4.264 MB / 1.36 GB, free: 43.81 MB / 0.8732 GB
Notification: Performance of sorting global known variables: time 0.009366/1.971, allocations: 7.259 MB / 1.367 GB, free: 36.57 MB / 0.8732 GB
Notification: Performance of Backend: time 3.11e-07/1.971, allocations: 11.94 kB / 1.367 GB, free: 36.56 MB / 0.8732 GB
Notification: Performance of simCode: created initialization part: time 0.02492/1.996, allocations: 13.02 MB / 1.379 GB, free: 23.96 MB / 0.8732 GB
Notification: Performance of SimCode: time 0.04565/2.042, allocations: 27.56 MB / 1.406 GB, free: 12.5 MB / 0.8888 GB
Notification: Performance of Templates: time 0.635/2.677, allocations: 270 MB / 1.67 GB, free: 128.1 MB / 0.92 GB
"
[Timeout remaining time 657]
make -j1 -f IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.sim & ./IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.FlatPlate.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 14.290007906965911]
Failed to read output from testmodel.py, exit status != 0:
2.684955342905596 2.692900529 1.839082048
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8381aba050>