Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.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.0007847/0.0007847, allocations: 84.44 kB / 19.91 MB, free: 4 kB / 13.93 MB
"
[Timeout remaining time 180]
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.000819/0.000819, allocations: 169.2 kB / 23.19 MB, free: 1.375 MB / 18.57 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 0.8599/0.8599, allocations: 177.2 MB / 203.6 MB, free: 5.77 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:686:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:694:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/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 4.0.0/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 4.0.0/package.mo): time 0.7046/0.7046, allocations: 154.7 MB / 414.7 MB, free: 0.75 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea")
translateModel(IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea") [Timeout 660]
"Notification: IDEAS 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.0009231/0.0009231, allocations: 71.89 kB / 0.6195 GB, free: 13.16 MB / 0.5886 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4452/0.4462, allocations: 101.9 MB / 0.7191 GB, free: 23.66 MB / 0.6355 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea): time 0.04354/0.4897, allocations: 59.39 MB / 0.7771 GB, free: 15.49 MB / 0.6667 GB
Notification: Performance of NFInst.instExpressions: time 0.1412/0.6309, allocations: 150 MB / 0.9236 GB, free: 108 kB / 0.7917 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004561/0.6355, allocations: 131 kB / 0.9237 GB, free: 15.97 MB / 0.8073 GB
Notification: Performance of NFTyping.typeComponents: time 0.003358/0.6388, allocations: 1.311 MB / 0.925 GB, free: 14.66 MB / 0.8073 GB
Notification: Performance of NFTyping.typeBindings: time 0.008245/0.6471, allocations: 3.312 MB / 0.9282 GB, free: 11.33 MB / 0.8073 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006922/0.654, allocations: 3.536 MB / 0.9317 GB, free: 7.816 MB / 0.8073 GB
Notification: Performance of NFFlatten.flatten: time 0.03007/0.684, allocations: 45.77 MB / 0.9764 GB, free: 9.941 MB / 0.8542 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01744/0.7015, allocations: 14.88 MB / 0.9909 GB, free: 10.91 MB / 0.8698 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.03738/0.7389, allocations: 21.99 MB / 1.012 GB, free: 4.977 MB / 0.8855 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.4274/1.166, allocations: 13.98 MB / 1.026 GB, free: 39.3 MB / 0.8855 GB
Notification: Performance of NFPackage.collectConstants: time 0.007806/1.174, allocations: 2.032 MB / 1.028 GB, free: 39.3 MB / 0.8855 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01557/1.19, allocations: 3.766 MB / 1.032 GB, free: 39.3 MB / 0.8855 GB
Notification: Performance of NFScalarize.scalarize: time 0.01009/1.2, allocations: 5.263 MB / 1.037 GB, free: 39.3 MB / 0.8855 GB
Notification: Performance of NFVerifyModel.verify: time 0.02064/1.22, allocations: 8.298 MB / 1.045 GB, free: 38.51 MB / 0.8855 GB
Notification: Performance of NFConvertDAE.convert: time 0.03655/1.257, allocations: 31.09 MB / 1.075 GB, free: 35.16 MB / 0.8855 GB
Notification: Performance of FrontEnd - DAE generated: time 4.679e-06/1.257, allocations: 0 / 1.075 GB, free: 35.16 MB / 0.8855 GB
Notification: Performance of FrontEnd: time 2.024e-06/1.257, allocations: 0.875 kB / 1.075 GB, free: 35.16 MB / 0.8855 GB
Notification: Performance of Transformations before backend: time 0.001022/1.258, allocations: 3.938 kB / 1.075 GB, free: 35.16 MB / 0.8855 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3132
 * Number of variables: 3132
Notification: Performance of Generate backend data structure: time 0.04327/1.301, allocations: 19.75 MB / 1.095 GB, free: 28.07 MB / 0.8855 GB
Notification: Performance of prepare preOptimizeDAE: time 4.34e-05/1.301, allocations: 9.125 kB / 1.095 GB, free: 28.07 MB / 0.8855 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01412/1.315, allocations: 3.714 MB / 1.098 GB, free: 27.09 MB / 0.8855 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02752/1.343, allocations: 16.6 MB / 1.114 GB, free: 23.98 MB / 0.8855 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006276/1.343, allocations: 0.8755 MB / 1.115 GB, free: 23.96 MB / 0.8855 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004143/1.348, allocations: 1.17 MB / 1.116 GB, free: 23.95 MB / 0.8855 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03224/1.38, allocations: 21.84 MB / 1.138 GB, free: 13.27 MB / 0.8855 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002644/1.38, allocations: 40.39 kB / 1.138 GB, free: 13.26 MB / 0.8855 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002037/1.382, allocations: 0.5013 MB / 1.138 GB, free: 12.88 MB / 0.8855 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003044/1.382, allocations: 0.5834 MB / 1.139 GB, free: 12.79 MB / 0.8855 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02745/1.41, allocations: 17.25 MB / 1.156 GB, free: 4.262 MB / 0.8855 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.0875/1.497, allocations: 54.65 MB / 1.209 GB, free: 3.207 MB / 0.9323 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0177/1.515, allocations: 12.19 MB / 1.221 GB, free: 7.086 MB / 0.948 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008202/1.523, allocations: 5.936 MB / 1.227 GB, free: 1.129 MB / 0.948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0009798/1.524, allocations: 377.9 kB / 1.227 GB, free: 0.7578 MB / 0.948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01018/1.534, allocations: 9.631 MB / 1.237 GB, free: 6.387 MB / 0.9636 GB
Notification: Performance of pre-optimization done (n=801): time 1.206e-05/1.535, allocations: 7.656 kB / 1.237 GB, free: 6.383 MB / 0.9636 GB
Notification: Performance of matching and sorting (n=801): time 0.02488/1.559, allocations: 16.4 MB / 1.253 GB, free: 5.945 MB / 0.9792 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001253/1.56, allocations: 321.6 kB / 1.253 GB, free: 5.574 MB / 0.9792 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01509/1.575, allocations: 11.65 MB / 1.264 GB, free: 9.969 MB / 0.9948 GB
Notification: Performance of collectPreVariables (initialization): time 0.00194/1.577, allocations: 189.7 kB / 1.264 GB, free: 9.777 MB / 0.9948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005448/1.582, allocations: 6.9 MB / 1.271 GB, free: 2.867 MB / 0.9948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003758/1.586, allocations: 2.891 MB / 1.274 GB, free: 16 kB / 0.9948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002836/1.589, allocations: 1.91 MB / 1.276 GB, free: 14.09 MB / 1.01 GB
Notification: Performance of setup shared object (initialization): time 6.848e-05/1.589, allocations: 305.1 kB / 1.276 GB, free: 13.79 MB / 1.01 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00742/1.596, allocations: 5.825 MB / 1.282 GB, free: 7.938 MB / 1.01 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00888/1.605, allocations: 8.557 MB / 1.29 GB, free: 14.36 MB / 1.026 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01594/1.621, allocations: 14.06 MB / 1.304 GB, free: 15.62 MB / 1.042 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.577e-05/1.621, allocations: 20 kB / 1.304 GB, free: 15.61 MB / 1.042 GB
Notification: Performance of matching and sorting (n=1228) (initialization): time 0.02212/1.643, allocations: 15.59 MB / 1.319 GB, free: 356 kB / 1.042 GB
Notification: Performance of prepare postOptimizeDAE: time 9.152e-05/1.643, allocations: 111.4 kB / 1.319 GB, free: 232 kB / 1.042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.535e-05/1.643, allocations: 64.88 kB / 1.319 GB, free: 168 kB / 1.042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004226/1.644, allocations: 192.8 kB / 1.319 GB, free: 15.97 MB / 1.057 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005866/1.65, allocations: 2.344 MB / 1.322 GB, free: 13.77 MB / 1.057 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001514/1.651, allocations: 1.661 MB / 1.323 GB, free: 12.04 MB / 1.057 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00925/1.66, allocations: 1.317 MB / 1.325 GB, free: 10.71 MB / 1.057 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009321/1.661, allocations: 451.8 kB / 1.325 GB, free: 10.27 MB / 1.057 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01461/1.676, allocations: 10.44 MB / 1.335 GB, free: 15.8 MB / 1.073 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.008433/1.684, allocations: 8.553 MB / 1.344 GB, free: 6.227 MB / 1.073 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01614/1.7, allocations: 14.06 MB / 1.357 GB, free: 7.5 MB / 1.089 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.997e-05/1.7, allocations: 20 kB / 1.357 GB, free: 7.48 MB / 1.089 GB
Notification: Performance of matching and sorting (n=1228) (initialization_lambda0): time 0.02242/1.723, allocations: 15.58 MB / 1.373 GB, free: 8.227 MB / 1.104 GB
Notification: Performance of prepare postOptimizeDAE: time 7.511e-05/1.723, allocations: 111.4 kB / 1.373 GB, free: 8.105 MB / 1.104 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.684e-05/1.723, allocations: 67.94 kB / 1.373 GB, free: 8.039 MB / 1.104 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003393/1.723, allocations: 193.5 kB / 1.373 GB, free: 7.848 MB / 1.104 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005455/1.729, allocations: 2.339 MB / 1.375 GB, free: 5.645 MB / 1.104 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001305/1.73, allocations: 1.667 MB / 1.377 GB, free: 3.906 MB / 1.104 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008218/1.738, allocations: 1.173 MB / 1.378 GB, free: 2.73 MB / 1.104 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007411/1.739, allocations: 451.8 kB / 1.378 GB, free: 2.289 MB / 1.104 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 294
 * Number of states: 0 ()
 * Number of discrete variables: 29 (weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,solCol1.HDirTil.incAng.decAng.tNext,$PRE.solCol1.HDirTil.incAng.decAng.tNext,solCol1.HDifTilIso.incAng.decAng.tNext,$PRE.solCol1.HDifTilIso.incAng.decAng.tNext,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,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1208):
 * Single equations (assignments): 1205
 * Array equations: 0
 * Algorithm blocks: 2
 * 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
   {(1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001955/1.741, allocations: 0.7795 MB / 1.379 GB, free: 1.645 MB / 1.104 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003128/1.744, allocations: 1.18 MB / 1.38 GB, free: 468 kB / 1.104 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01821/1.762, allocations: 14.01 MB / 1.394 GB, free: 2.426 MB / 1.12 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01294/1.775, allocations: 10.92 MB / 1.405 GB, free: 7.402 MB / 1.135 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.829e-05/1.775, allocations: 7.172 kB / 1.405 GB, free: 7.398 MB / 1.135 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006653/1.782, allocations: 2.979 MB / 1.408 GB, free: 4.41 MB / 1.135 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00222/1.784, allocations: 179.9 kB / 1.408 GB, free: 4.234 MB / 1.135 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005324/1.789, allocations: 0.6608 MB / 1.408 GB, free: 3.582 MB / 1.135 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006756/1.79, allocations: 0.4976 MB / 1.409 GB, free: 3.082 MB / 1.135 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0177/1.808, allocations: 12.78 MB / 1.421 GB, free: 6.391 MB / 1.151 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.3828/2.191, allocations: 20.41 MB / 1.441 GB, free: 471.4 MB / 1.151 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.008134/2.199, allocations: 5.504 MB / 1.447 GB, free: 469.5 MB / 1.151 GB
Notification: Performance of sorting global known variables: time 0.01304/2.212, allocations: 8.045 MB / 1.455 GB, free: 466.9 MB / 1.151 GB
Notification: Performance of Backend: time 5.31e-07/2.212, allocations: 0 / 1.455 GB, free: 466.9 MB / 1.151 GB
Notification: Performance of simCode: created initialization part: time 0.03355/2.245, allocations: 16.88 MB / 1.471 GB, free: 461.4 MB / 1.151 GB
Notification: Performance of SimCode: time 0.05634/2.302, allocations: 33.43 MB / 1.504 GB, free: 447.7 MB / 1.151 GB
Notification: Performance of Templates: time 0.2706/2.572, allocations: 326.2 MB / 1.822 GB, free: 140.5 MB / 1.151 GB
"
[Timeout remaining time 657]
make -j1 -f IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.pipe ; mkfifo IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.pipe >> ../files/IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.sim & ./IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.SolarCollectors.Examples.FlatPlateTotalArea.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 11.01118041202426]
Failed to read output from testmodel.py, exit status != 0:
2.5790317349601537 2.585674615 1.327131139
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7b6f2cbcd7c0>