Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_8_Buildings.Examples.Tutorial.CDL.System5.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.System5,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System5")
translateModel(Buildings.Examples.Tutorial.CDL.System5,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.Examples.Tutorial.CDL.System5")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001469/0.001469, allocations: 109.9 kB / 16.38 MB, free: 5.547 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.001681/0.001681, allocations: 190.7 kB / 17.31 MB, free: 5.188 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.438/1.438, allocations: 205.1 MB / 223.2 MB, free: 12.25 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.785/1.785, allocations: 292.3 MB / 0.5496 GB, free: 17.68 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.001409/0.001409, allocations: 67.91 kB / 0.6729 GB, free: 5.859 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.23/0.2315, allocations: 114.6 MB / 0.7848 GB, free: 3.191 MB / 0.6544 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.Tutorial.CDL.System5): time 1.334/1.565, allocations: 0.5126 GB / 1.297 GB, free: 47.35 MB / 0.92 GB
Notification: Performance of NFInst.instExpressions: time 0.09191/1.657, allocations: 73.45 MB / 1.369 GB, free: 7.637 MB / 0.92 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0184/1.676, allocations: 421.1 kB / 1.37 GB, free: 7.383 MB / 0.92 GB
Notification: Performance of NFTyping.typeComponents: time 0.01587/1.692, allocations: 5.158 MB / 1.375 GB, free: 3.863 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.03687/1.729, allocations: 13.46 MB / 1.388 GB, free: 6.684 MB / 0.9356 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02783/1.757, allocations: 11.97 MB / 1.399 GB, free: 10.89 MB / 0.9512 GB
Notification: Performance of NFFlatten.flatten: time 0.04251/1.799, allocations: 32.14 MB / 1.431 GB, free: 10.67 MB / 0.9825 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02233/1.821, allocations: 12.2 MB / 1.443 GB, free: 14.39 MB / 0.9981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02726/1.849, allocations: 12.03 MB / 1.454 GB, free: 2.367 MB / 0.9981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01747/1.866, allocations: 9.135 MB / 1.463 GB, free: 9.191 MB / 1.014 GB
Notification: Performance of NFPackage.collectConstants: time 0.004686/1.871, allocations: 1.203 MB / 1.465 GB, free: 7.988 MB / 1.014 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01893/1.89, allocations: 6.451 MB / 1.471 GB, free: 1.531 MB / 1.014 GB
Notification: Performance of NFScalarize.scalarize: time 0.007106/1.897, allocations: 3.36 MB / 1.474 GB, free: 14.16 MB / 1.029 GB
Notification: Performance of NFVerifyModel.verify: time 0.01553/1.913, allocations: 6.87 MB / 1.481 GB, free: 7.254 MB / 1.029 GB
Notification: Performance of NFConvertDAE.convert: time 0.03362/1.946, allocations: 19.85 MB / 1.5 GB, free: 3.344 MB / 1.045 GB
Notification: Performance of FrontEnd - DAE generated: time 5.851e-06/1.946, allocations: 7.938 kB / 1.5 GB, free: 3.336 MB / 1.045 GB
Notification: Performance of FrontEnd: time 4.168e-06/1.946, allocations: 4 kB / 1.5 GB, free: 3.332 MB / 1.045 GB
Notification: Performance of Transformations before backend: time 0.0006131/1.947, allocations: 0 / 1.5 GB, free: 3.332 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: 1870
 * Number of variables: 1870
Notification: Performance of Generate backend data structure: time 0.03364/1.981, allocations: 13.46 MB / 1.513 GB, free: 5.734 MB / 1.061 GB
Notification: Performance of prepare preOptimizeDAE: time 5.115e-05/1.981, allocations: 8.031 kB / 1.513 GB, free: 5.727 MB / 1.061 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01157/1.992, allocations: 2.038 MB / 1.515 GB, free: 3.672 MB / 1.061 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.023/2.015, allocations: 10.23 MB / 1.525 GB, free: 9.348 MB / 1.076 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006466/2.016, allocations: 0.565 MB / 1.526 GB, free: 8.781 MB / 1.076 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002603/2.019, allocations: 0.7771 MB / 1.527 GB, free: 8.004 MB / 1.076 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0229/2.041, allocations: 8.68 MB / 1.535 GB, free: 15 MB / 1.092 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000374/2.042, allocations: 15.94 kB / 1.535 GB, free: 14.98 MB / 1.092 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002294/2.044, allocations: 312 kB / 1.535 GB, free: 14.68 MB / 1.092 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003198/2.045, allocations: 297.8 kB / 1.536 GB, free: 14.39 MB / 1.092 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01943/2.064, allocations: 7.03 MB / 1.543 GB, free: 7.344 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.6993/2.763, allocations: 41.27 MB / 1.583 GB, free: 427.4 MB / 1.092 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00852/2.772, allocations: 4.645 MB / 1.587 GB, free: 425.1 MB / 1.092 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004444/2.776, allocations: 2.39 MB / 1.59 GB, free: 424.2 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.03012/2.807, allocations: 10.39 MB / 1.6 GB, free: 418.6 MB / 1.092 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.005837/2.812, allocations: 2.519 MB / 1.602 GB, free: 417.6 MB / 1.092 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003622/2.816, allocations: 373 kB / 1.603 GB, free: 417.6 MB / 1.092 GB
Notification: Performance of pre-optimization done (n=410): time 1.538e-05/2.816, allocations: 0 / 1.603 GB, free: 417.6 MB / 1.092 GB
Notification: Performance of matching and sorting (n=419): time 0.0432/2.859, allocations: 14.81 MB / 1.617 GB, free: 411.1 MB / 1.092 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001457/2.859, allocations: 0.526 MB / 1.618 GB, free: 410.5 MB / 1.092 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01822/2.878, allocations: 8.366 MB / 1.626 GB, free: 407.6 MB / 1.092 GB
Notification: Performance of collectPreVariables (initialization): time 0.00122/2.879, allocations: 140.9 kB / 1.626 GB, free: 407.5 MB / 1.092 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005576/2.885, allocations: 5.048 MB / 1.631 GB, free: 403.3 MB / 1.092 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002158/2.887, allocations: 1.447 MB / 1.632 GB, free: 402.1 MB / 1.092 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004183/2.891, allocations: 1.183 MB / 1.634 GB, free: 401.4 MB / 1.092 GB
Notification: Performance of setup shared object (initialization): time 4.546e-05/2.891, allocations: 302.2 kB / 1.634 GB, free: 401.1 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005835/2.897, allocations: 2.264 MB / 1.636 GB, free: 400 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007123/2.904, allocations: 4.018 MB / 1.64 GB, free: 396.7 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01094/2.915, allocations: 5.889 MB / 1.646 GB, free: 392.8 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.684e-05/2.915, allocations: 7.938 kB / 1.646 GB, free: 392.8 MB / 1.092 GB
Notification: Performance of matching and sorting (n=736) (initialization): time 0.02302/2.938, allocations: 8.041 MB / 1.654 GB, free: 388.9 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 5.822e-05/2.938, allocations: 24.47 kB / 1.654 GB, free: 388.9 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001066/2.938, allocations: 43.25 kB / 1.654 GB, free: 388.9 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008886/2.947, allocations: 1.768 MB / 1.655 GB, free: 388.2 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005363/2.953, allocations: 1.212 MB / 1.657 GB, free: 387.9 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01002/2.963, allocations: 8.355 MB / 1.665 GB, free: 380.4 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008588/2.971, allocations: 393.7 kB / 1.665 GB, free: 380.2 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00128/2.973, allocations: 277 kB / 1.665 GB, free: 380.2 MB / 1.092 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.009041/2.982, allocations: 4.058 MB / 1.669 GB, free: 377.9 MB / 1.092 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005497/2.987, allocations: 3.904 MB / 1.673 GB, free: 374.6 MB / 1.092 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.008335/2.996, allocations: 5.636 MB / 1.679 GB, free: 370.7 MB / 1.092 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.586e-05/2.996, allocations: 20.94 kB / 1.679 GB, free: 370.7 MB / 1.092 GB
Notification: Performance of matching and sorting (n=736) (initialization_lambda0): time 0.01911/3.015, allocations: 7.941 MB / 1.686 GB, free: 365.3 MB / 1.092 GB
Notification: Performance of prepare postOptimizeDAE: time 3.391e-05/3.015, allocations: 20 kB / 1.686 GB, free: 365.3 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.735e-05/3.015, allocations: 47.98 kB / 1.686 GB, free: 365.2 MB / 1.092 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007785/3.023, allocations: 1.573 MB / 1.688 GB, free: 363.6 MB / 1.092 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004343/3.027, allocations: 1.178 MB / 1.689 GB, free: 362.5 MB / 1.092 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01182/3.039, allocations: 10.42 MB / 1.699 GB, free: 351.5 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009177/3.048, allocations: 414.6 kB / 1.7 GB, free: 351.1 MB / 1.092 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001337/3.049, allocations: 271.6 kB / 1.7 GB, free: 350.8 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: 22 ($PRE.timTab.tab.nextTimeEvent,$whenCondition1,conBoiRet.conPID.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,boiSigCon.u,radPumCon.u,conEquSta.hys.y,$PRE.conEquSta.hys.y,conSysSta.onTRoo.y,$PRE.conSysSta.onTRoo.y,conSysSta.onTOut.y,$PRE.conSysSta.onTOut.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 (671):
 * Single equations (assignments): 655
 * 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
   {(5,6), (13,22), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.002364/3.052, allocations: 0.5427 MB / 1.701 GB, free: 350.2 MB / 1.092 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003684/3.056, allocations: 0.9144 MB / 1.701 GB, free: 349.3 MB / 1.092 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01316/3.069, allocations: 6.05 MB / 1.707 GB, free: 343.3 MB / 1.092 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007451/3.076, allocations: 4.524 MB / 1.712 GB, free: 338.7 MB / 1.092 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.29e-05/3.076, allocations: 4.5 kB / 1.712 GB, free: 338.7 MB / 1.092 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004115/3.08, allocations: 1.551 MB / 1.713 GB, free: 337.1 MB / 1.092 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002319/3.083, allocations: 95.98 kB / 1.713 GB, free: 337.1 MB / 1.092 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005758/3.088, allocations: 295.2 kB / 1.714 GB, free: 336.8 MB / 1.092 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000598/3.089, allocations: 339.1 kB / 1.714 GB, free: 336.4 MB / 1.092 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01145/3.101, allocations: 5.663 MB / 1.72 GB, free: 330.8 MB / 1.092 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01215/3.113, allocations: 7.812 MB / 1.727 GB, free: 323 MB / 1.092 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002812/3.116, allocations: 2.122 MB / 1.729 GB, free: 320.8 MB / 1.092 GB
Notification: Performance of sorting global known variables: time 0.01043/3.126, allocations: 5.546 MB / 1.735 GB, free: 315.3 MB / 1.092 GB
Notification: Performance of Backend: time 1.462e-06/3.126, allocations: 0 / 1.735 GB, free: 315.3 MB / 1.092 GB
Notification: Performance of simCode: created initialization part: time 0.03126/3.157, allocations: 11.41 MB / 1.746 GB, free: 304.6 MB / 1.092 GB
Notification: Performance of SimCode: time 0.04218/3.2, allocations: 22.25 MB / 1.767 GB, free: 282.6 MB / 1.092 GB
Notification: Performance of Templates: time 0.2503/3.45, allocations: 203.8 MB / 1.967 GB, free: 80.78 MB / 1.092 GB
make -j1 -f Buildings_8_Buildings.Examples.Tutorial.CDL.System5.makefile
(rm -f Buildings_8_Buildings.Examples.Tutorial.CDL.System5.pipe ; mkfifo Buildings_8_Buildings.Examples.Tutorial.CDL.System5.pipe ; head -c 1048576 < Buildings_8_Buildings.Examples.Tutorial.CDL.System5.pipe >> ../files/Buildings_8_Buildings.Examples.Tutorial.CDL.System5.sim & ./Buildings_8_Buildings.Examples.Tutorial.CDL.System5  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8_Buildings.Examples.Tutorial.CDL.System5.pipe 2>&1)