Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_8_Buildings.Examples.Tutorial.CDL.System4.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.System4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System4")
translateModel(Buildings.Examples.Tutorial.CDL.System4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System4")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001257/0.001257, allocations: 104.8 kB / 16.37 MB, free: 6.262 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.001245/0.001245, allocations: 190.4 kB / 17.3 MB, free: 5.848 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.432/1.432, allocations: 205.1 MB / 223.2 MB, free: 12.19 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.75/1.75, allocations: 292.3 MB / 0.5496 GB, free: 17.45 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.001427/0.001427, allocations: 63.91 kB / 0.6729 GB, free: 5.875 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2109/0.2124, allocations: 114.6 MB / 0.7848 GB, free: 3.203 MB / 0.6544 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.Tutorial.CDL.System4): time 1.406/1.619, allocations: 0.5125 GB / 1.297 GB, free: 47.32 MB / 0.92 GB
Notification: Performance of NFInst.instExpressions: time 0.0894/1.708, allocations: 73.4 MB / 1.369 GB, free: 7.77 MB / 0.92 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02075/1.729, allocations: 422.9 kB / 1.369 GB, free: 7.516 MB / 0.92 GB
Notification: Performance of NFTyping.typeComponents: time 0.01593/1.745, allocations: 5.164 MB / 1.374 GB, free: 3.984 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.04056/1.786, allocations: 13.43 MB / 1.388 GB, free: 6.863 MB / 0.9356 GB
Notification: Performance of NFTyping.typeClassSections: time 0.03075/1.816, allocations: 11.96 MB / 1.399 GB, free: 11.08 MB / 0.9512 GB
Notification: Performance of NFFlatten.flatten: time 0.04256/1.859, allocations: 32.08 MB / 1.431 GB, free: 10.92 MB / 0.9825 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0214/1.88, allocations: 12.15 MB / 1.442 GB, free: 14.68 MB / 0.9981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02638/1.907, allocations: 12.02 MB / 1.454 GB, free: 2.672 MB / 0.9981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01583/1.923, allocations: 9.107 MB / 1.463 GB, free: 9.531 MB / 1.014 GB
Notification: Performance of NFPackage.collectConstants: time 0.005326/1.928, allocations: 1.199 MB / 1.464 GB, free: 8.332 MB / 1.014 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02008/1.948, allocations: 6.447 MB / 1.471 GB, free: 1.875 MB / 1.014 GB
Notification: Performance of NFScalarize.scalarize: time 0.006189/1.954, allocations: 3.348 MB / 1.474 GB, free: 14.52 MB / 1.029 GB
Notification: Performance of NFVerifyModel.verify: time 0.01559/1.97, allocations: 6.839 MB / 1.48 GB, free: 7.645 MB / 1.029 GB
Notification: Performance of NFConvertDAE.convert: time 0.04052/2.01, allocations: 19.78 MB / 1.5 GB, free: 3.805 MB / 1.045 GB
Notification: Performance of FrontEnd - DAE generated: time 6.453e-06/2.011, allocations: 4 kB / 1.5 GB, free: 3.801 MB / 1.045 GB
Notification: Performance of FrontEnd: time 2.344e-06/2.011, allocations: 0 / 1.5 GB, free: 3.801 MB / 1.045 GB
Notification: Performance of Transformations before backend: time 0.0007084/2.011, allocations: 0 / 1.5 GB, free: 3.801 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: 1860
 * Number of variables: 1860
Notification: Performance of Generate backend data structure: time 0.03307/2.044, allocations: 13.37 MB / 1.513 GB, free: 6.297 MB / 1.061 GB
Notification: Performance of prepare preOptimizeDAE: time 4.74e-05/2.044, allocations: 8.031 kB / 1.513 GB, free: 6.289 MB / 1.061 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01014/2.055, allocations: 2.034 MB / 1.515 GB, free: 4.238 MB / 1.061 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02161/2.076, allocations: 10.21 MB / 1.525 GB, free: 9.938 MB / 1.076 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000561/2.077, allocations: 0.5649 MB / 1.525 GB, free: 9.371 MB / 1.076 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001972/2.079, allocations: 0.7654 MB / 1.526 GB, free: 8.605 MB / 1.076 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01736/2.096, allocations: 8.647 MB / 1.535 GB, free: 15.63 MB / 1.092 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001564/2.096, allocations: 15.88 kB / 1.535 GB, free: 15.62 MB / 1.092 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007131/2.097, allocations: 308 kB / 1.535 GB, free: 15.32 MB / 1.092 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002416/2.097, allocations: 293.8 kB / 1.535 GB, free: 15.03 MB / 1.092 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0127/2.11, allocations: 6.99 MB / 1.542 GB, free: 8.023 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.6898/2.8, allocations: 41.14 MB / 1.582 GB, free: 427.5 MB / 1.092 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007175/2.807, allocations: 4.562 MB / 1.587 GB, free: 425.2 MB / 1.092 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003696/2.811, allocations: 2.364 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.02789/2.839, allocations: 10.35 MB / 1.599 GB, free: 418.7 MB / 1.092 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.005437/2.844, allocations: 2.483 MB / 1.601 GB, free: 417.8 MB / 1.092 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003722/2.848, allocations: 364.4 kB / 1.602 GB, free: 417.7 MB / 1.092 GB
Notification: Performance of pre-optimization done (n=403): time 1.95e-05/2.848, allocations: 5 kB / 1.602 GB, free: 417.7 MB / 1.092 GB
Notification: Performance of matching and sorting (n=412): time 0.04205/2.89, allocations: 14.64 MB / 1.616 GB, free: 411.3 MB / 1.092 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001816/2.89, allocations: 0.5251 MB / 1.617 GB, free: 410.7 MB / 1.092 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01724/2.908, allocations: 8.372 MB / 1.625 GB, free: 407.8 MB / 1.092 GB
Notification: Performance of collectPreVariables (initialization): time 0.001263/2.909, allocations: 134.2 kB / 1.625 GB, free: 407.7 MB / 1.092 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004831/2.914, allocations: 5.044 MB / 1.63 GB, free: 403.7 MB / 1.092 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001907/2.916, allocations: 1.426 MB / 1.631 GB, free: 402.4 MB / 1.092 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002023/2.918, allocations: 1.173 MB / 1.632 GB, free: 401.8 MB / 1.092 GB
Notification: Performance of setup shared object (initialization): time 5.295e-05/2.918, allocations: 308.4 kB / 1.633 GB, free: 401.5 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004415/2.922, allocations: 2.248 MB / 1.635 GB, free: 400.3 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006105/2.928, allocations: 4 MB / 1.639 GB, free: 397 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009306/2.938, allocations: 5.865 MB / 1.645 GB, free: 393.2 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.586e-05/2.938, allocations: 11.78 kB / 1.645 GB, free: 393.2 MB / 1.092 GB
Notification: Performance of matching and sorting (n=731) (initialization): time 0.01988/2.958, allocations: 8.316 MB / 1.653 GB, free: 389.2 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 4.415e-05/2.958, allocations: 27.38 kB / 1.653 GB, free: 389.2 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.992e-05/2.958, allocations: 51.8 kB / 1.653 GB, free: 389.1 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008802/2.967, allocations: 1.766 MB / 1.654 GB, free: 388.5 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005227/2.972, allocations: 1.214 MB / 1.656 GB, free: 388.2 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008624/2.98, allocations: 8.22 MB / 1.664 GB, free: 380.8 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008458/2.989, allocations: 360.8 kB / 1.664 GB, free: 380.6 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001176/2.99, allocations: 276.8 kB / 1.664 GB, free: 380.5 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.009087/2.999, allocations: 4.052 MB / 1.668 GB, free: 378.3 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00549/3.005, allocations: 3.88 MB / 1.672 GB, free: 375 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.007973/3.013, allocations: 5.624 MB / 1.677 GB, free: 371.1 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.841e-05/3.013, allocations: 14.25 kB / 1.678 GB, free: 371.1 MB / 1.092 GB
Notification: Performance of matching and sorting (n=731) (initialization_lambda0): time 0.01808/3.031, allocations: 7.758 MB / 1.685 GB, free: 366.5 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 3.609e-05/3.031, allocations: 20 kB / 1.685 GB, free: 366.5 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.653e-05/3.031, allocations: 46.77 kB / 1.685 GB, free: 366.5 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008068/3.039, allocations: 1.567 MB / 1.687 GB, free: 364.9 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004813/3.044, allocations: 1.186 MB / 1.688 GB, free: 363.7 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01089/3.055, allocations: 10.41 MB / 1.698 GB, free: 352.7 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0089/3.064, allocations: 399.7 kB / 1.698 GB, free: 352.3 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001168/3.065, allocations: 259.9 kB / 1.699 GB, free: 352.1 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: 17 ($PRE.timTab.tab.nextTimeEvent,$whenCondition1,conBoiRet.conPID.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,boiSigCon.u,conEquSta.hys.y,$PRE.conEquSta.hys.y,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 (666):
 * Single equations (assignments): 650
 * 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.001499/3.067, allocations: 0.5476 MB / 1.699 GB, free: 351.5 MB / 1.092 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003522/3.07, allocations: 0.9105 MB / 1.7 GB, free: 350.6 MB / 1.092 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01138/3.082, allocations: 5.994 MB / 1.706 GB, free: 344.6 MB / 1.092 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00789/3.089, allocations: 4.488 MB / 1.71 GB, free: 340.1 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.492e-05/3.09, allocations: 0 / 1.71 GB, free: 340.1 MB / 1.092 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00549/3.095, allocations: 1.547 MB / 1.712 GB, free: 338.5 MB / 1.092 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002982/3.098, allocations: 103.1 kB / 1.712 GB, free: 338.4 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005779/3.104, allocations: 271.1 kB / 1.712 GB, free: 338.2 MB / 1.092 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005729/3.104, allocations: 327.1 kB / 1.712 GB, free: 337.9 MB / 1.092 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01066/3.115, allocations: 5.588 MB / 1.718 GB, free: 332.3 MB / 1.092 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0115/3.127, allocations: 7.739 MB / 1.725 GB, free: 324.5 MB / 1.092 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002609/3.129, allocations: 2.102 MB / 1.728 GB, free: 322.4 MB / 1.092 GB
Notification: Performance of sorting global known variables: time 0.008017/3.137, allocations: 5.55 MB / 1.733 GB, free: 316.9 MB / 1.092 GB
Notification: Performance of Backend: time 8.71e-07/3.137, allocations: 0 / 1.733 GB, free: 316.9 MB / 1.092 GB
Notification: Performance of simCode: created initialization part: time 0.0261/3.163, allocations: 11.33 MB / 1.744 GB, free: 306.3 MB / 1.092 GB
Notification: Performance of SimCode: time 0.04008/3.204, allocations: 22.2 MB / 1.766 GB, free: 284.3 MB / 1.092 GB
Notification: Performance of Templates: time 0.2509/3.454, allocations: 203.2 MB / 1.964 GB, free: 83.11 MB / 1.092 GB
make -j1 -f Buildings_8_Buildings.Examples.Tutorial.CDL.System4.makefile
(rm -f Buildings_8_Buildings.Examples.Tutorial.CDL.System4.pipe ; mkfifo Buildings_8_Buildings.Examples.Tutorial.CDL.System4.pipe ; head -c 1048576 < Buildings_8_Buildings.Examples.Tutorial.CDL.System4.pipe >> ../files/Buildings_8_Buildings.Examples.Tutorial.CDL.System4.sim & ./Buildings_8_Buildings.Examples.Tutorial.CDL.System4  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8_Buildings.Examples.Tutorial.CDL.System4.pipe 2>&1)