Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.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.001338/0.001338, allocations: 109.1 kB / 19.71 MB, free: 2.145 MB / 14.72 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.001445/0.001445, allocations: 213.3 kB / 23 MB, free: 4.965 MB / 14.72 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 1.45/1.45, allocations: 230.6 MB / 256.8 MB, free: 7.82 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.128/3.128, allocations: 0.49 GB / 0.7959 GB, free: 35.47 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/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(Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|conWin.T|weaBus.TDryBul|conWin.H|conWin.y",fileNamePrefix="Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow")
translateModel(Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|conWin.T|weaBus.TDryBul|conWin.H|conWin.y",fileNamePrefix="Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow") [Timeout 300]
"Notification: Buildings 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.002028/0.002028, allocations: 68.27 kB / 1.155 GB, free: 25.29 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2979/0.2999, allocations: 176.1 MB / 1.327 GB, free: 1.004 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow): time 0.2527/0.5526, allocations: 205.9 MB / 1.528 GB, free: 2.332 MB / 1.217 GB
Notification: Performance of NFInst.instExpressions: time 0.06826/0.6209, allocations: 39.02 MB / 1.566 GB, free: 11.2 MB / 1.264 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0171/0.638, allocations: 472.2 kB / 1.567 GB, free: 10.74 MB / 1.264 GB
Notification: Performance of NFTyping.typeComponents: time 0.02075/0.6587, allocations: 7.497 MB / 1.574 GB, free: 3.207 MB / 1.264 GB
Notification: Performance of NFTyping.typeBindings: time 0.01904/0.6778, allocations: 6.395 MB / 1.58 GB, free: 12.79 MB / 1.279 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01509/0.6929, allocations: 5.56 MB / 1.586 GB, free: 7.234 MB / 1.279 GB
Notification: Performance of NFFlatten.flatten: time 0.7587/1.452, allocations: 49.51 MB / 1.634 GB, free: 21.39 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02619/1.478, allocations: 14.48 MB / 1.648 GB, free: 20.71 MB / 1.295 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.03526/1.513, allocations: 21.16 MB / 1.669 GB, free: 18.7 MB / 1.295 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02536/1.538, allocations: 15.65 MB / 1.684 GB, free: 16.05 MB / 1.295 GB
Notification: Performance of NFPackage.collectConstants: time 0.01066/1.549, allocations: 2.693 MB / 1.687 GB, free: 16.05 MB / 1.295 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02107/1.57, allocations: 7.145 MB / 1.694 GB, free: 16.05 MB / 1.295 GB
Notification: Performance of NFScalarize.scalarize: time 0.01605/1.586, allocations: 11.76 MB / 1.705 GB, free: 14.92 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.02665/1.613, allocations: 11.24 MB / 1.716 GB, free: 11.07 MB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.06808/1.681, allocations: 52.02 MB / 1.767 GB, free: 15.05 MB / 1.326 GB
Notification: Performance of FrontEnd - DAE generated: time 5.44e-06/1.681, allocations: 0 / 1.767 GB, free: 15.05 MB / 1.326 GB
Notification: Performance of FrontEnd: time 1.753e-06/1.681, allocations: 0 / 1.767 GB, free: 15.05 MB / 1.326 GB
Notification: Performance of Transformations before backend: time 0.001341/1.682, allocations: 0.9062 kB / 1.767 GB, free: 15.05 MB / 1.326 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1678
 * Number of variables: 1678
Notification: Performance of Generate backend data structure: time 0.0997/1.782, allocations: 35.74 MB / 1.802 GB, free: 12.29 MB / 1.357 GB
Notification: Performance of prepare preOptimizeDAE: time 4.756e-05/1.782, allocations: 8.031 kB / 1.802 GB, free: 12.29 MB / 1.357 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0418/1.824, allocations: 7.609 MB / 1.809 GB, free: 4.738 MB / 1.357 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.08772/1.912, allocations: 44.14 MB / 1.852 GB, free: 9.32 MB / 1.404 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001843/1.913, allocations: 2.015 MB / 1.854 GB, free: 6.996 MB / 1.404 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004562/1.918, allocations: 0.9303 MB / 1.855 GB, free: 6.066 MB / 1.404 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03468/1.953, allocations: 14.07 MB / 1.869 GB, free: 7.48 MB / 1.42 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001586/1.953, allocations: 13.47 kB / 1.869 GB, free: 7.477 MB / 1.42 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001137/1.954, allocations: 273.9 kB / 1.869 GB, free: 7.211 MB / 1.42 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002421/1.954, allocations: 244 kB / 1.87 GB, free: 6.969 MB / 1.42 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03102/1.985, allocations: 13.15 MB / 1.882 GB, free: 9.859 MB / 1.436 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.1593/2.144, allocations: 71.56 MB / 1.952 GB, free: 2.098 MB / 1.498 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02161/2.166, allocations: 9.546 MB / 1.962 GB, free: 8.555 MB / 1.514 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01442/2.18, allocations: 6.651 MB / 1.968 GB, free: 1.898 MB / 1.514 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00115/2.182, allocations: 230.3 kB / 1.968 GB, free: 1.68 MB / 1.514 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01417/2.196, allocations: 7.025 MB / 1.975 GB, free: 10.49 MB / 1.529 GB
Notification: Performance of pre-optimization done (n=486): time 1.04e-05/2.196, allocations: 3.938 kB / 1.975 GB, free: 10.49 MB / 1.529 GB
Notification: Performance of matching and sorting (n=486): time 0.06743/2.263, allocations: 24.85 MB / 1.999 GB, free: 1.582 MB / 1.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003254/2.264, allocations: 367.4 kB / 2 GB, free: 1.18 MB / 1.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.129/2.392, allocations: 63.26 MB / 2.062 GB, free: 2.102 MB / 1.607 GB
Notification: Performance of collectPreVariables (initialization): time 0.002835/2.395, allocations: 238.3 kB / 2.062 GB, free: 1.863 MB / 1.607 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02141/2.417, allocations: 11.64 MB / 2.073 GB, free: 6.266 MB / 1.623 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003598/2.42, allocations: 1.784 MB / 2.075 GB, free: 4.508 MB / 1.623 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01309/2.433, allocations: 7.691 MB / 2.082 GB, free: 12.82 MB / 1.639 GB
Notification: Performance of setup shared object (initialization): time 0.0001232/2.434, allocations: 305.1 kB / 2.083 GB, free: 12.52 MB / 1.639 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.06521/2.499, allocations: 52.39 MB / 2.134 GB, free: 8.094 MB / 1.686 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.07121/2.57, allocations: 57.95 MB / 2.19 GB, free: 12.37 MB / 1.748 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.08913/2.659, allocations: 69.62 MB / 2.258 GB, free: 5.43 MB / 1.811 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001428/2.659, allocations: 32 kB / 2.259 GB, free: 5.398 MB / 1.811 GB
Notification: Performance of matching and sorting (n=2912) (initialization): time 0.8151/3.474, allocations: 125.8 MB / 2.381 GB, free: 0.6593 GB / 1.826 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003049/3.475, allocations: 271.4 kB / 2.382 GB, free: 0.659 GB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0874/3.562, allocations: 86.92 MB / 2.467 GB, free: 0.5797 GB / 1.826 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1201/3.682, allocations: 37.08 MB / 2.503 GB, free: 0.547 GB / 1.826 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01666/3.699, allocations: 3.264 MB / 2.506 GB, free: 0.544 GB / 1.826 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02377/3.723, allocations: 30.02 MB / 2.535 GB, free: 0.5133 GB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02517/3.748, allocations: 2.966 MB / 2.538 GB, free: 0.5112 GB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003455/3.751, allocations: 0.5575 MB / 2.539 GB, free: 0.5106 GB / 1.826 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.06358/3.815, allocations: 65.61 MB / 2.603 GB, free: 457.3 MB / 1.826 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05236/3.867, allocations: 57.88 MB / 2.659 GB, free: 397.6 MB / 1.826 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.06632/3.933, allocations: 69.49 MB / 2.727 GB, free: 326.4 MB / 1.826 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.000144/3.934, allocations: 35.94 kB / 2.727 GB, free: 326.4 MB / 1.826 GB
Notification: Performance of matching and sorting (n=2912) (initialization_lambda0): time 0.1315/4.065, allocations: 125.2 MB / 2.849 GB, free: 201.3 MB / 1.826 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002971/4.065, allocations: 267.3 kB / 2.85 GB, free: 201 MB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.08808/4.153, allocations: 87.13 MB / 2.935 GB, free: 113.5 MB / 1.826 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008411/4.162, allocations: 3.095 MB / 2.938 GB, free: 110.4 MB / 1.826 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01553/4.177, allocations: 3.252 MB / 2.941 GB, free: 107.1 MB / 1.826 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02793/4.205, allocations: 32.49 MB / 2.973 GB, free: 73.49 MB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02511/4.23, allocations: 2.641 MB / 2.975 GB, free: 71.04 MB / 1.826 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003513/4.234, allocations: 0.5529 MB / 2.976 GB, free: 70.49 MB / 1.826 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 526
 * Number of states: 0 ()
 * Number of discrete variables: 40 (weaDat1.conTim.canRepeatWeatherFile,weaDat1.conTimMin.canRepeatWeatherFile,roo.solRadExc.is_flo[12],roo.solRadExc.is_flo[11],roo.solRadExc.is_flo[10],roo.solRadExc.is_flo[9],roo.solRadExc.is_flo[8],roo.solRadExc.is_flo[7],roo.solRadExc.is_flo[6],roo.solRadExc.is_flo[5],roo.solRadExc.is_flo[4],roo.solRadExc.is_flo[3],roo.solRadExc.is_flo[2],roo.solRadExc.is_flo[1],nPer.count,$PRE.nPer.count,nPer.T_start,$PRE.nPer.T_start,weaDat1.decAng.tNext,$PRE.weaDat1.decAng.tNext,weaDat1.eqnTim.tNext,$PRE.weaDat1.eqnTim.tNext,weaDat1.conTim.tNext,$PRE.weaDat1.conTim.tNext,weaDat1.conTimMin.tNext,$PRE.weaDat1.conTimMin.tNext,weaDat1.locTim.tNext,$PRE.weaDat1.locTim.tNext,roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,$PRE.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$PRE.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$whenCondition1,$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 (1379):
 * Single equations (assignments): 1321
 * Array equations: 39
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 5
 * Torn equation systems: 12
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 5 systems
   {1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 10 systems
   {(1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(14,53), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.004411/4.238, allocations: 1.068 MB / 2.977 GB, free: 69.48 MB / 1.826 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.008502/4.247, allocations: 2.481 MB / 2.979 GB, free: 66.99 MB / 1.826 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03768/4.285, allocations: 17.69 MB / 2.997 GB, free: 49.18 MB / 1.826 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02542/4.31, allocations: 14.11 MB / 3.01 GB, free: 34.91 MB / 1.826 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.02552/4.335, allocations: 13.32 MB / 3.023 GB, free: 21.45 MB / 1.826 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02118/4.357, allocations: 6.588 MB / 3.03 GB, free: 14.89 MB / 1.826 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.009762/4.366, allocations: 151.9 kB / 3.03 GB, free: 14.74 MB / 1.826 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01761/4.384, allocations: 1.319 MB / 3.031 GB, free: 13.43 MB / 1.826 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001577/4.386, allocations: 331.1 kB / 3.032 GB, free: 13.1 MB / 1.826 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.7919/5.178, allocations: 18.64 MB / 3.05 GB, free: 0.6935 GB / 1.826 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0508/5.228, allocations: 22.83 MB / 3.072 GB, free: 0.6889 GB / 1.826 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01128/5.24, allocations: 6.428 MB / 3.078 GB, free: 0.6869 GB / 1.826 GB
Notification: Performance of sorting global known variables: time 0.06649/5.306, allocations: 42.9 MB / 3.12 GB, free: 0.6737 GB / 1.826 GB
Notification: Performance of Backend: time 5.51e-07/5.306, allocations: 7.375 kB / 3.12 GB, free: 0.6737 GB / 1.826 GB
Notification: Performance of simCode: created initialization part: time 0.1329/5.439, allocations: 115.6 MB / 3.233 GB, free: 0.5752 GB / 1.826 GB
Notification: Performance of SimCode: time 0.1242/5.563, allocations: 80.22 MB / 3.311 GB, free: 0.4983 GB / 1.826 GB
Notification: Performance of Templates: time 1.971/7.534, allocations: 0.6094 GB / 3.921 GB, free: 0.617 GB / 1.826 GB
"
[Timeout remaining time 292]
make -j1 -f Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.pipe ; mkfifo Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.pipe ; head -c 1048576 < Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.pipe >> ../files/Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.sim & ./Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.ThermalZones.Detailed.Examples.ElectroChromicWindow.pipe 2>&1) [Timeout 400]
[Calling os._exit(0), Time elapsed: 29.329377073001524]
Failed to read output from testmodel.py, exit status != 0:
7.54302022200136 7.553361108 5.869660236
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f30a1412f80>