Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_8_Buildings.Examples.Tutorial.CDL.System3.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo", uses=false)
Using package Buildings with version 8.1.4 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Buildings.Examples.Tutorial.CDL.System3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System3")
translateModel(Buildings.Examples.Tutorial.CDL.System3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System3")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001109/0.001109, allocations: 109.6 kB / 16.38 MB, free: 6.484 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001102/0.001102, allocations: 190.7 kB / 17.32 MB, free: 5.816 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.452/1.452, allocations: 205.1 MB / 223.2 MB, free: 12.3 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo): time 1.816/1.816, allocations: 292.3 MB / 0.5496 GB, free: 17.61 MB / 462.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.00138/0.00138, allocations: 67.89 kB / 0.6729 GB, free: 5.805 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2122/0.2136, allocations: 114.6 MB / 0.7848 GB, free: 3.137 MB / 0.6544 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.Tutorial.CDL.System3): time 1.406/1.62, allocations: 0.5125 GB / 1.297 GB, free: 47.32 MB / 0.92 GB
Notification: Performance of NFInst.instExpressions: time 0.09034/1.71, allocations: 73.38 MB / 1.369 GB, free: 7.762 MB / 0.92 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01719/1.728, allocations: 421.8 kB / 1.369 GB, free: 7.508 MB / 0.92 GB
Notification: Performance of NFTyping.typeComponents: time 0.01823/1.746, allocations: 5.162 MB / 1.374 GB, free: 3.98 MB / 0.92 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Utilities/Strings.mo:139:3-175:14:writable] Warning: Pure function 'Modelica.Utilities.Strings.isEmpty' contains a call to impure function 'Modelica.Utilities.Strings.Advanced.skipWhiteSpace'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Utilities/Strings.mo:216:3-259:11:writable] Warning: Pure function 'Modelica.Utilities.Strings.find' contains a call to impure function 'Modelica.Utilities.Strings.length'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getHeaderElementTMY3.mo:2:1-105:25:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3' contains a call to impure function 'Modelica.Utilities.Streams.readLine'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getLongitudeTMY3.mo:2:1-48:21:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getLongitudeTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getLatitudeTMY3.mo:2:1-48:20:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getLatitudeTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getTimeZoneTMY3.mo:2:1-47:20:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getTimeZoneTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
Notification: Performance of NFTyping.typeBindings: time 0.0568/1.803, allocations: 13.43 MB / 1.388 GB, free: 6.879 MB / 0.9356 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05357/1.856, allocations: 11.94 MB / 1.399 GB, free: 11.12 MB / 0.9512 GB
Notification: Performance of NFFlatten.flatten: time 0.05655/1.913, allocations: 32.06 MB / 1.43 GB, free: 10.98 MB / 0.9825 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01978/1.933, allocations: 12.13 MB / 1.442 GB, free: 14.77 MB / 0.9981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02907/1.962, allocations: 12.01 MB / 1.454 GB, free: 2.773 MB / 0.9981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01711/1.979, allocations: 9.095 MB / 1.463 GB, free: 9.645 MB / 1.014 GB
Notification: Performance of NFPackage.collectConstants: time 0.005198/1.984, allocations: 1.195 MB / 1.464 GB, free: 8.449 MB / 1.014 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02091/2.005, allocations: 6.436 MB / 1.47 GB, free: 2.004 MB / 1.014 GB
Notification: Performance of NFScalarize.scalarize: time 0.007585/2.013, allocations: 3.341 MB / 1.474 GB, free: 14.65 MB / 1.029 GB
Notification: Performance of NFVerifyModel.verify: time 0.01896/2.032, allocations: 6.835 MB / 1.48 GB, free: 7.785 MB / 1.029 GB
Notification: Performance of NFConvertDAE.convert: time 0.03542/2.067, allocations: 19.74 MB / 1.5 GB, free: 3.988 MB / 1.045 GB
Notification: Performance of FrontEnd - DAE generated: time 6.001e-06/2.067, allocations: 0 / 1.5 GB, free: 3.988 MB / 1.045 GB
Notification: Performance of FrontEnd: time 1.443e-06/2.067, allocations: 0 / 1.5 GB, free: 3.988 MB / 1.045 GB
Notification: Performance of Transformations before backend: time 0.0006223/2.068, allocations: 0 / 1.5 GB, free: 3.988 MB / 1.045 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1854
 * Number of variables: 1854
Notification: Performance of Generate backend data structure: time 0.03567/2.104, allocations: 13.35 MB / 1.513 GB, free: 6.508 MB / 1.061 GB
Notification: Performance of prepare preOptimizeDAE: time 4.894e-05/2.104, allocations: 8.031 kB / 1.513 GB, free: 6.5 MB / 1.061 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01209/2.116, allocations: 2.026 MB / 1.515 GB, free: 4.457 MB / 1.061 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02449/2.14, allocations: 10.2 MB / 1.525 GB, free: 10.16 MB / 1.076 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006194/2.141, allocations: 0.5688 MB / 1.525 GB, free: 9.59 MB / 1.076 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00342/2.145, allocations: 0.7653 MB / 1.526 GB, free: 8.824 MB / 1.076 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02341/2.168, allocations: 8.616 MB / 1.534 GB, free: 15.88 MB / 1.092 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002227/2.168, allocations: 23.81 kB / 1.534 GB, free: 15.86 MB / 1.092 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001226/2.169, allocations: 315.9 kB / 1.535 GB, free: 15.55 MB / 1.092 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002656/2.17, allocations: 289.7 kB / 1.535 GB, free: 15.26 MB / 1.092 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01612/2.186, allocations: 6.978 MB / 1.542 GB, free: 8.27 MB / 1.092 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.7154/2.901, allocations: 41.16 MB / 1.582 GB, free: 427.5 MB / 1.092 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009029/2.91, allocations: 4.541 MB / 1.586 GB, free: 425.2 MB / 1.092 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004609/2.915, allocations: 2.363 MB / 1.589 GB, free: 424.3 MB / 1.092 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 evalFunc (simulation): time 0.02667/2.942, allocations: 10.32 MB / 1.599 GB, free: 418.7 MB / 1.092 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.006392/2.948, allocations: 2.466 MB / 1.601 GB, free: 417.8 MB / 1.092 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.004841/2.953, allocations: 365.4 kB / 1.601 GB, free: 417.7 MB / 1.092 GB
Notification: Performance of pre-optimization done (n=400): time 1.802e-05/2.953, allocations: 1.125 kB / 1.601 GB, free: 417.7 MB / 1.092 GB
Notification: Performance of matching and sorting (n=409): time 0.04186/2.995, allocations: 14.62 MB / 1.616 GB, free: 411.3 MB / 1.092 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001506/2.995, allocations: 0.5287 MB / 1.616 GB, free: 410.7 MB / 1.092 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01559/3.011, allocations: 8.368 MB / 1.624 GB, free: 407.8 MB / 1.092 GB
Notification: Performance of collectPreVariables (initialization): time 0.001242/3.012, allocations: 134.7 kB / 1.625 GB, free: 407.7 MB / 1.092 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004618/3.017, allocations: 5.05 MB / 1.629 GB, free: 403.7 MB / 1.092 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001964/3.019, allocations: 1.426 MB / 1.631 GB, free: 402.5 MB / 1.092 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001979/3.021, allocations: 1.165 MB / 1.632 GB, free: 401.8 MB / 1.092 GB
Notification: Performance of setup shared object (initialization): time 4.42e-05/3.021, allocations: 308.2 kB / 1.632 GB, free: 401.5 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004626/3.025, allocations: 2.239 MB / 1.634 GB, free: 400.4 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00533/3.031, allocations: 3.987 MB / 1.638 GB, free: 397.1 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008519/3.039, allocations: 5.85 MB / 1.644 GB, free: 393.2 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.508e-05/3.039, allocations: 15.72 kB / 1.644 GB, free: 393.2 MB / 1.092 GB
Notification: Performance of matching and sorting (n=728) (initialization): time 0.02118/3.06, allocations: 8.301 MB / 1.652 GB, free: 389.3 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 4.391e-05/3.061, allocations: 19.78 kB / 1.652 GB, free: 389.3 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.122e-05/3.061, allocations: 47.39 kB / 1.652 GB, free: 389.3 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008824/3.069, allocations: 1.77 MB / 1.654 GB, free: 388.6 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005292/3.075, allocations: 1.213 MB / 1.655 GB, free: 388.3 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008631/3.083, allocations: 8.22 MB / 1.663 GB, free: 380.9 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008424/3.092, allocations: 359.6 kB / 1.664 GB, free: 380.7 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001171/3.093, allocations: 277.4 kB / 1.664 GB, free: 380.6 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008882/3.102, allocations: 4.034 MB / 1.668 GB, free: 378.4 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005544/3.108, allocations: 3.866 MB / 1.672 GB, free: 375.1 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.008344/3.116, allocations: 5.614 MB / 1.677 GB, free: 371.2 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.818e-05/3.116, allocations: 14.75 kB / 1.677 GB, free: 371.2 MB / 1.092 GB
Notification: Performance of matching and sorting (n=728) (initialization_lambda0): time 0.01741/3.133, allocations: 7.585 MB / 1.684 GB, free: 367.1 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 5.718e-05/3.134, allocations: 24 kB / 1.684 GB, free: 367.1 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.783e-05/3.134, allocations: 48.86 kB / 1.685 GB, free: 367 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008148/3.142, allocations: 1.566 MB / 1.686 GB, free: 365.5 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004747/3.147, allocations: 1.186 MB / 1.687 GB, free: 364.3 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01121/3.158, allocations: 10.42 MB / 1.697 GB, free: 353.3 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008045/3.166, allocations: 390.5 kB / 1.698 GB, free: 352.9 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001023/3.167, allocations: 263.9 kB / 1.698 GB, free: 352.6 MB / 1.092 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 178
 * Number of states: 0 ()
 * Number of discrete variables: 14 ($PRE.timTab.tab.nextTimeEvent,$whenCondition1,conBoiRet.conPID.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,timTab.tab.nextTimeEventScaled,$PRE.timTab.tab.nextTimeEventScaled,timTab.tab.nextTimeEvent,$whenCondition2,$whenCondition3)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (663):
 * Single equations (assignments): 647
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 4
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 5
 * 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): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,5,100.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(3,8), (13,22), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.002109/3.169, allocations: 0.5358 MB / 1.699 GB, free: 352.1 MB / 1.092 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003016/3.172, allocations: 0.9105 MB / 1.699 GB, free: 351.2 MB / 1.092 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01126/3.183, allocations: 5.982 MB / 1.705 GB, free: 345.2 MB / 1.092 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007516/3.191, allocations: 4.468 MB / 1.71 GB, free: 340.7 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.36e-05/3.191, allocations: 0 / 1.71 GB, free: 340.7 MB / 1.092 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00484/3.196, allocations: 1.548 MB / 1.711 GB, free: 339.1 MB / 1.092 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002725/3.199, allocations: 95.98 kB / 1.711 GB, free: 339 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006392/3.205, allocations: 271.2 kB / 1.711 GB, free: 338.8 MB / 1.092 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008728/3.206, allocations: 319 kB / 1.712 GB, free: 338.5 MB / 1.092 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01171/3.218, allocations: 5.575 MB / 1.717 GB, free: 332.9 MB / 1.092 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01212/3.23, allocations: 7.7 MB / 1.725 GB, free: 325.2 MB / 1.092 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002755/3.233, allocations: 2.09 MB / 1.727 GB, free: 323.1 MB / 1.092 GB
Notification: Performance of sorting global known variables: time 0.009024/3.242, allocations: 5.554 MB / 1.732 GB, free: 317.5 MB / 1.092 GB
Notification: Performance of Backend: time 1.132e-06/3.242, allocations: 3.844 kB / 1.732 GB, free: 317.5 MB / 1.092 GB
Notification: Performance of simCode: created initialization part: time 0.02744/3.269, allocations: 11.26 MB / 1.743 GB, free: 307 MB / 1.092 GB
Notification: Performance of SimCode: time 0.04796/3.317, allocations: 22.17 MB / 1.765 GB, free: 285.1 MB / 1.092 GB
Notification: Performance of Templates: time 0.2519/3.569, allocations: 202.8 MB / 1.963 GB, free: 84.21 MB / 1.092 GB
make -j1 -f Buildings_8_Buildings.Examples.Tutorial.CDL.System3.makefile
(rm -f Buildings_8_Buildings.Examples.Tutorial.CDL.System3.pipe ; mkfifo Buildings_8_Buildings.Examples.Tutorial.CDL.System3.pipe ; head -c 1048576 < Buildings_8_Buildings.Examples.Tutorial.CDL.System3.pipe >> ../files/Buildings_8_Buildings.Examples.Tutorial.CDL.System3.sim & ./Buildings_8_Buildings.Examples.Tutorial.CDL.System3  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8_Buildings.Examples.Tutorial.CDL.System3.pipe 2>&1)