Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.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.00153/0.00153, allocations: 113.9 kB / 18.71 MB, free: 3.137 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.006276/0.006276, allocations: 188.5 kB / 21.96 MB, free: 5.652 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.336/1.336, allocations: 225.6 MB / 250.7 MB, free: 13.38 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.021/1.021, allocations: 175.7 MB / 481.6 MB, free: 7.895 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.EN12975_Series,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series")
translateModel(IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001894/0.001894, allocations: 68.17 kB / 0.67 GB, free: 14.25 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4683/0.4702, allocations: 94.52 MB / 0.7623 GB, free: 9.961 MB / 0.5763 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series): time 0.08565/0.5558, allocations: 85.32 MB / 0.8456 GB, free: 12 MB / 0.6388 GB
Notification: Performance of NFInst.instExpressions: time 0.2107/0.7665, allocations: 177.4 MB / 1.019 GB, free: 15.46 MB / 0.8106 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005656/0.7721, allocations: 127.3 kB / 1.019 GB, free: 15.34 MB / 0.8106 GB
Notification: Performance of NFTyping.typeComponents: time 0.005525/0.7777, allocations: 1.813 MB / 1.021 GB, free: 13.52 MB / 0.8106 GB
Notification: Performance of NFTyping.typeBindings: time 0.01356/0.7912, allocations: 4.781 MB / 1.025 GB, free: 8.719 MB / 0.8106 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009804/0.801, allocations: 3.952 MB / 1.029 GB, free: 4.793 MB / 0.8106 GB
Notification: Performance of NFFlatten.flatten: time 0.02606/0.8271, allocations: 20.85 MB / 1.05 GB, free: 15.87 MB / 0.8419 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01161/0.8387, allocations: 7.698 MB / 1.057 GB, free: 8.051 MB / 0.8419 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01577/0.8545, allocations: 7.741 MB / 1.065 GB, free: 332 kB / 0.8419 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01139/0.8659, allocations: 6.626 MB / 1.071 GB, free: 9.672 MB / 0.8575 GB
Notification: Performance of NFPackage.collectConstants: time 0.002866/0.8687, allocations: 1.152 MB / 1.072 GB, free: 8.52 MB / 0.8575 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.00898/0.8777, allocations: 3.387 MB / 1.076 GB, free: 5.129 MB / 0.8575 GB
Notification: Performance of NFScalarize.scalarize: time 0.004428/0.8821, allocations: 2.877 MB / 1.078 GB, free: 2.242 MB / 0.8575 GB
Notification: Performance of NFVerifyModel.verify: time 0.01059/0.8927, allocations: 5.546 MB / 1.084 GB, free: 12.67 MB / 0.8731 GB
Notification: Performance of NFConvertDAE.convert: time 0.4025/1.295, allocations: 16.84 MB / 1.1 GB, free: 47.65 MB / 0.8731 GB
Notification: Performance of FrontEnd - DAE generated: time 4.619e-06/1.295, allocations: 0 / 1.1 GB, free: 47.65 MB / 0.8731 GB
Notification: Performance of FrontEnd: time 1.643e-06/1.295, allocations: 0 / 1.1 GB, free: 47.65 MB / 0.8731 GB
Notification: Performance of Transformations before backend: time 0.0004135/1.296, allocations: 0.9688 kB / 1.1 GB, free: 47.65 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: 1783
 * Number of variables: 1783
Notification: Performance of Generate backend data structure: time 0.02519/1.321, allocations: 10.92 MB / 1.111 GB, free: 43.39 MB / 0.8731 GB
Notification: Performance of prepare preOptimizeDAE: time 3.995e-05/1.321, allocations: 8.625 kB / 1.111 GB, free: 43.39 MB / 0.8731 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01764/1.338, allocations: 2.918 MB / 1.114 GB, free: 42.85 MB / 0.8731 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01636/1.355, allocations: 8.204 MB / 1.122 GB, free: 41.52 MB / 0.8731 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004516/1.355, allocations: 0.5047 MB / 1.122 GB, free: 41.5 MB / 0.8731 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002474/1.358, allocations: 0.669 MB / 1.123 GB, free: 41.5 MB / 0.8731 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02412/1.382, allocations: 9.908 MB / 1.133 GB, free: 40.68 MB / 0.8731 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001761/1.382, allocations: 20.39 kB / 1.133 GB, free: 40.68 MB / 0.8731 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001108/1.383, allocations: 288.5 kB / 1.133 GB, free: 40.68 MB / 0.8731 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003284/1.384, allocations: 301.3 kB / 1.133 GB, free: 40.66 MB / 0.8731 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02362/1.407, allocations: 9.696 MB / 1.143 GB, free: 40.59 MB / 0.8731 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.0527/1.46, allocations: 35.76 MB / 1.178 GB, free: 27.54 MB / 0.8731 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01463/1.474, allocations: 6.698 MB / 1.184 GB, free: 27.16 MB / 0.8731 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007446/1.482, allocations: 3.198 MB / 1.187 GB, free: 26.86 MB / 0.8731 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001486/1.483, allocations: 1.893 MB / 1.189 GB, free: 24.81 MB / 0.8731 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008284/1.492, allocations: 3.704 MB / 1.193 GB, free: 23.73 MB / 0.8731 GB
Notification: Performance of pre-optimization done (n=478): time 7.023e-06/1.492, allocations: 4 kB / 1.193 GB, free: 23.73 MB / 0.8731 GB
Notification: Performance of matching and sorting (n=478): time 0.02199/1.514, allocations: 9.344 MB / 1.202 GB, free: 21.82 MB / 0.8731 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.972e-05/1.514, allocations: 239.5 kB / 1.202 GB, free: 21.59 MB / 0.8731 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009388/1.523, allocations: 6.13 MB / 1.208 GB, free: 20.09 MB / 0.8731 GB
Notification: Performance of collectPreVariables (initialization): time 0.002265/1.525, allocations: 122 kB / 1.208 GB, free: 20.06 MB / 0.8731 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004035/1.529, allocations: 3.618 MB / 1.212 GB, free: 17.27 MB / 0.8731 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002456/1.532, allocations: 1.671 MB / 1.213 GB, free: 15.62 MB / 0.8731 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001836/1.534, allocations: 1.052 MB / 1.214 GB, free: 14.56 MB / 0.8731 GB
Notification: Performance of setup shared object (initialization): time 4.195e-05/1.534, allocations: 301.1 kB / 1.215 GB, free: 14.26 MB / 0.8731 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006399/1.54, allocations: 3.038 MB / 1.218 GB, free: 11.21 MB / 0.8731 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007491/1.548, allocations: 4.076 MB / 1.222 GB, free: 6.75 MB / 0.8731 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0142/1.562, allocations: 7.227 MB / 1.229 GB, free: 15.2 MB / 0.8888 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.234e-05/1.562, allocations: 5.641 kB / 1.229 GB, free: 15.19 MB / 0.8888 GB
Notification: Performance of matching and sorting (n=678) (initialization): time 0.02336/1.585, allocations: 8.214 MB / 1.237 GB, free: 7.02 MB / 0.8888 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001492/1.585, allocations: 133.1 kB / 1.237 GB, free: 6.871 MB / 0.8888 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.249e-05/1.585, allocations: 24 kB / 1.237 GB, free: 6.848 MB / 0.8888 GB
Notification: Performance of postOpt tearingSystem (initialization): time 6.58e-05/1.586, allocations: 52 kB / 1.237 GB, free: 6.797 MB / 0.8888 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007489/1.593, allocations: 1.342 MB / 1.238 GB, free: 5.523 MB / 0.8888 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001102/1.593, allocations: 73.28 kB / 1.238 GB, free: 5.465 MB / 0.8888 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007879/1.601, allocations: 0.8158 MB / 1.239 GB, free: 4.645 MB / 0.8888 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001348/1.602, allocations: 267.8 kB / 1.239 GB, free: 4.383 MB / 0.8888 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01292/1.615, allocations: 5.073 MB / 1.244 GB, free: 15.3 MB / 0.9044 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009845/1.625, allocations: 4.073 MB / 1.248 GB, free: 10.84 MB / 0.9044 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01742/1.643, allocations: 7.217 MB / 1.255 GB, free: 3.289 MB / 0.9044 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.173e-05/1.643, allocations: 11.75 kB / 1.255 GB, free: 3.281 MB / 0.9044 GB
Notification: Performance of matching and sorting (n=678) (initialization_lambda0): time 0.02235/1.665, allocations: 8.204 MB / 1.263 GB, free: 11.12 MB / 0.92 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000138/1.665, allocations: 137.1 kB / 1.264 GB, free: 10.97 MB / 0.92 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.982e-05/1.665, allocations: 29.42 kB / 1.264 GB, free: 10.94 MB / 0.92 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.915e-05/1.665, allocations: 48 kB / 1.264 GB, free: 10.89 MB / 0.92 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007076/1.672, allocations: 1.332 MB / 1.265 GB, free: 9.633 MB / 0.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.18e-05/1.672, allocations: 74.14 kB / 1.265 GB, free: 9.57 MB / 0.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007315/1.68, allocations: 0.6935 MB / 1.266 GB, free: 8.875 MB / 0.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001032/1.681, allocations: 275.8 kB / 1.266 GB, free: 8.605 MB / 0.92 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 116
 * Number of states: 0 ()
 * Number of discrete variables: 35 (weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,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,solCol2.HDirTil.incAng.decAng.tNext,$PRE.solCol2.HDirTil.incAng.decAng.tNext,solCol2.HDifTilIso.incAng.decAng.tNext,$PRE.solCol2.HDifTilIso.incAng.decAng.tNext,solCol1.HDirTil.incAng.decAng.tNext,$PRE.solCol1.HDirTil.incAng.decAng.tNext,solCol1.HDifTilIso.incAng.decAng.tNext,$PRE.solCol1.HDifTilIso.incAng.decAng.tNext,solCol.HDirTil.incAng.decAng.tNext,$PRE.solCol.HDirTil.incAng.decAng.tNext,solCol.HDifTilIso.incAng.decAng.tNext,$PRE.solCol.HDifTilIso.incAng.decAng.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (651):
 * Single equations (assignments): 648
 * Array equations: 0
 * Algorithm blocks: 3
 * 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.001535/1.682, allocations: 0.4919 MB / 1.266 GB, free: 8.176 MB / 0.92 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002178/1.684, allocations: 0.6948 MB / 1.267 GB, free: 7.477 MB / 0.92 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02091/1.705, allocations: 8.228 MB / 1.275 GB, free: 15.16 MB / 0.9356 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01658/1.722, allocations: 6.264 MB / 1.281 GB, free: 8.797 MB / 0.9356 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.42e-05/1.722, allocations: 3.984 kB / 1.281 GB, free: 8.793 MB / 0.9356 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004864/1.727, allocations: 1.659 MB / 1.283 GB, free: 7.109 MB / 0.9356 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001943/1.729, allocations: 99.98 kB / 1.283 GB, free: 7.012 MB / 0.9356 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006352/1.735, allocations: 442.5 kB / 1.283 GB, free: 6.582 MB / 0.9356 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007331/1.736, allocations: 311.2 kB / 1.284 GB, free: 6.281 MB / 0.9356 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02122/1.757, allocations: 7.377 MB / 1.291 GB, free: 14.91 MB / 0.9513 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02909/1.786, allocations: 11.67 MB / 1.302 GB, free: 3.117 MB / 0.9513 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.008257/1.794, allocations: 3.162 MB / 1.305 GB, free: 15.95 MB / 0.9669 GB
Notification: Performance of sorting global known variables: time 0.008053/1.802, allocations: 4.226 MB / 1.309 GB, free: 11.74 MB / 0.9669 GB
Notification: Performance of Backend: time 6.01e-07/1.802, allocations: 4 kB / 1.309 GB, free: 11.74 MB / 0.9669 GB
Notification: Performance of simCode: created initialization part: time 0.03021/1.833, allocations: 10.1 MB / 1.319 GB, free: 2.145 MB / 0.9669 GB
Notification: Performance of SimCode: time 0.03528/1.868, allocations: 16.75 MB / 1.336 GB, free: 1.484 MB / 0.9825 GB
Notification: Performance of Templates: time 0.5855/2.453, allocations: 178.5 MB / 1.51 GB, free: 0.5188 GB / 1.139 GB
"
[Timeout remaining time 658]
make -j1 -f IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.sim & ./IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.SolarCollectors.Validation.EN12975_Series.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 12.332978282007389]
Failed to read output from testmodel.py, exit status != 0:
2.462215809035115 2.472585239 1.174792109
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3ec07f2050>