Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.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.001272/0.001272, allocations: 110.4 kB / 18.94 MB, free: 2.949 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.001422/0.001422, allocations: 219 kB / 22.26 MB, free: 5.125 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.428/1.428, allocations: 230.6 MB / 256 MB, free: 7.973 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.246/3.246, allocations: 0.505 GB / 0.8101 GB, free: 22.02 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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: "" <> buildModelFMU(Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning,fileNamePrefix="Buildings_latest_Buildings_Fluid_HeatExchangers_Examples_WetCoilCounterFlowPIDControlAutoTuning",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning,fileNamePrefix="Buildings_latest_Buildings_Fluid_HeatExchangers_Examples_WetCoilCounterFlowPIDControlAutoTuning",fmuType="me",version="2.0",platforms={"static"}) [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.002299/0.002299, allocations: 70.77 kB / 1.177 GB, free: 16.91 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3569/0.3592, allocations: 180.2 MB / 1.353 GB, free: 8.391 MB / 1.045 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning): time 1.225/1.584, allocations: 289.3 MB / 1.635 GB, free: 13.04 MB / 1.295 GB
Notification: Performance of NFInst.instExpressions: time 0.04086/1.625, allocations: 26.34 MB / 1.661 GB, free: 12.88 MB / 1.295 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01023/1.635, allocations: 194.5 kB / 1.661 GB, free: 12.88 MB / 1.295 GB
Notification: Performance of NFTyping.typeComponents: time 0.00774/1.643, allocations: 1.794 MB / 1.663 GB, free: 12.86 MB / 1.295 GB
Notification: Performance of NFTyping.typeBindings: time 0.02074/1.664, allocations: 5.063 MB / 1.668 GB, free: 12.7 MB / 1.295 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0145/1.678, allocations: 4.342 MB / 1.672 GB, free: 12.57 MB / 1.295 GB
Notification: Performance of NFFlatten.flatten: time 0.04122/1.719, allocations: 21.6 MB / 1.693 GB, free: 12.54 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02095/1.74, allocations: 7.877 MB / 1.701 GB, free: 11.98 MB / 1.295 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02345/1.764, allocations: 8.372 MB / 1.709 GB, free: 9.035 MB / 1.295 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01449/1.778, allocations: 6.602 MB / 1.716 GB, free: 6.527 MB / 1.295 GB
Notification: Performance of NFPackage.collectConstants: time 0.005021/1.783, allocations: 1.231 MB / 1.717 GB, free: 6.527 MB / 1.295 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01837/1.802, allocations: 5.572 MB / 1.722 GB, free: 5.555 MB / 1.295 GB
Notification: Performance of NFScalarize.scalarize: time 0.00571/1.807, allocations: 3.048 MB / 1.725 GB, free: 3.77 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.01249/1.82, allocations: 5.552 MB / 1.731 GB, free: 204 kB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.03415/1.854, allocations: 19.14 MB / 1.749 GB, free: 6.273 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 5.871e-06/1.854, allocations: 3.875 kB / 1.749 GB, free: 6.273 MB / 1.311 GB
Notification: Performance of FrontEnd: time 1.884e-06/1.854, allocations: 0 / 1.749 GB, free: 6.273 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.0005737/1.855, allocations: 0.625 kB / 1.749 GB, free: 6.273 MB / 1.311 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1765
 * Number of variables: 1765
Notification: Performance of Generate backend data structure: time 0.03634/1.891, allocations: 11.89 MB / 1.761 GB, free: 14.39 MB / 1.326 GB
Notification: Performance of prepare preOptimizeDAE: time 5.471e-05/1.891, allocations: 11.12 kB / 1.761 GB, free: 14.39 MB / 1.326 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003852/1.895, allocations: 0.9311 MB / 1.762 GB, free: 13.71 MB / 1.326 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01263/1.907, allocations: 1.815 MB / 1.764 GB, free: 12.84 MB / 1.326 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02131/1.929, allocations: 7.809 MB / 1.771 GB, free: 7.867 MB / 1.326 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006665/1.929, allocations: 0.5318 MB / 1.772 GB, free: 7.625 MB / 1.326 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002795/1.932, allocations: 0.7117 MB / 1.773 GB, free: 7.391 MB / 1.326 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02942/1.962, allocations: 9.03 MB / 1.781 GB, free: 14.51 MB / 1.342 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003219/1.962, allocations: 9.922 kB / 1.781 GB, free: 14.5 MB / 1.342 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002243/1.964, allocations: 293.8 kB / 1.782 GB, free: 14.22 MB / 1.342 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003313/1.965, allocations: 293.3 kB / 1.782 GB, free: 13.93 MB / 1.342 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02355/1.988, allocations: 7.701 MB / 1.789 GB, free: 6.234 MB / 1.342 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.08553/2.074, allocations: 33.85 MB / 1.823 GB, free: 3.371 MB / 1.373 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01196/2.086, allocations: 5.235 MB / 1.828 GB, free: 14.17 MB / 1.389 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006849/2.092, allocations: 3.067 MB / 1.831 GB, free: 11.09 MB / 1.389 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001194/2.094, allocations: 201.9 kB / 1.831 GB, free: 10.91 MB / 1.389 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007555/2.101, allocations: 3.16 MB / 1.834 GB, free: 7.727 MB / 1.389 GB
Notification: Performance of pre-optimization done (n=483): time 1.402e-05/2.101, allocations: 3.984 kB / 1.834 GB, free: 7.723 MB / 1.389 GB
Notification: Performance of matching and sorting (n=487): time 0.04733/2.149, allocations: 16.05 MB / 1.85 GB, free: 7.621 MB / 1.404 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002054/2.149, allocations: 196.3 kB / 1.85 GB, free: 7.398 MB / 1.404 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01466/2.163, allocations: 5.725 MB / 1.855 GB, free: 1.699 MB / 1.404 GB
Notification: Performance of collectPreVariables (initialization): time 0.001438/2.165, allocations: 168.7 kB / 1.856 GB, free: 1.531 MB / 1.404 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005724/2.171, allocations: 3.447 MB / 1.859 GB, free: 14.12 MB / 1.42 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00316/2.174, allocations: 1.648 MB / 1.861 GB, free: 12.5 MB / 1.42 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004539/2.178, allocations: 1.002 MB / 1.862 GB, free: 11.5 MB / 1.42 GB
Notification: Performance of setup shared object (initialization): time 0.0001599/2.178, allocations: 301.1 kB / 1.862 GB, free: 11.2 MB / 1.42 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006118/2.185, allocations: 2.481 MB / 1.864 GB, free: 8.703 MB / 1.42 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007318/2.192, allocations: 3.516 MB / 1.868 GB, free: 4.84 MB / 1.42 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0126/2.204, allocations: 5.964 MB / 1.873 GB, free: 14.62 MB / 1.436 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.755e-05/2.204, allocations: 4 kB / 1.873 GB, free: 14.62 MB / 1.436 GB
Notification: Performance of matching and sorting (n=668) (initialization): time 0.01922/2.224, allocations: 7.072 MB / 1.88 GB, free: 7.559 MB / 1.436 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001195/2.224, allocations: 79.38 kB / 1.88 GB, free: 7.469 MB / 1.436 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.371e-05/2.224, allocations: 23.94 kB / 1.88 GB, free: 7.445 MB / 1.436 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001098/2.225, allocations: 289.1 kB / 1.881 GB, free: 7.16 MB / 1.436 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005151/2.23, allocations: 1.015 MB / 1.882 GB, free: 6.211 MB / 1.436 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00411/2.234, allocations: 3.591 MB / 1.885 GB, free: 2.484 MB / 1.436 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006437/2.241, allocations: 391.9 kB / 1.886 GB, free: 2.105 MB / 1.436 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00108/2.242, allocations: 196 kB / 1.886 GB, free: 1.914 MB / 1.436 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01148/2.253, allocations: 4.855 MB / 1.891 GB, free: 13.05 MB / 1.451 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006865/2.26, allocations: 3.485 MB / 1.894 GB, free: 9.219 MB / 1.451 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01198/2.272, allocations: 5.912 MB / 1.9 GB, free: 3.051 MB / 1.451 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.791e-05/2.272, allocations: 7.375 kB / 1.9 GB, free: 3.047 MB / 1.451 GB
Notification: Performance of matching and sorting (n=668) (initialization_lambda0): time 0.01647/2.289, allocations: 6.99 MB / 1.907 GB, free: 12.07 MB / 1.467 GB
Notification: Performance of prepare postOptimizeDAE: time 8.766e-05/2.289, allocations: 75.39 kB / 1.907 GB, free: 11.98 MB / 1.467 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.572e-05/2.289, allocations: 16 kB / 1.907 GB, free: 11.96 MB / 1.467 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007562/2.289, allocations: 246.2 kB / 1.907 GB, free: 11.72 MB / 1.467 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004848/2.294, allocations: 0.9942 MB / 1.908 GB, free: 10.79 MB / 1.467 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00554/2.3, allocations: 4.562 MB / 1.912 GB, free: 6.023 MB / 1.467 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006246/2.306, allocations: 418.8 kB / 1.913 GB, free: 5.617 MB / 1.467 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009232/2.307, allocations: 188 kB / 1.913 GB, free: 5.434 MB / 1.467 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 80
 * Number of states: 0 ()
 * Number of discrete variables: 122 ($PRE.con.resPro.norTimDel.tunEndDel.u,$PRE.con.tunStaDel.u,$whenCondition20,$PRE.con.resPro.norTimDel.tunEndDel.t_next,$whenCondition13,$PRE.con.tunStaDel.t_next,$whenCondition5,$whenCondition2,$whenCondition3,con.resPro.norTimDel.tunEndDel.t_next,con.tunStaDel.t_next,autTunSig.y,$PRE.autTunSig.y,con.falEdg.not_u,$PRE.con.falEdg.not_u,con.falEdg.y,con.greThr.greHys.y,$PRE.con.greThr.greHys.y,con.sam_u_s.y,$PRE.con.sam_u_s.y,con.tunStaDel.y,con.tunStaDel.u,con.edgReq.u,$PRE.con.edgReq.u,con.nand.y,con.nand.u2,con.samTd.y,$PRE.con.samTd.y,con.samTd.trigger,con.samTi.y,$PRE.con.samTi.y,con.samk.y,$PRE.con.samk.y,con.inTunPro.y,$PRE.con.inTunPro.y,con.inTunPro.clr,con.inTunPro.u,con.conProMod.not1.y,con.conProMod.samTau.y,$PRE.con.conProMod.samTau.y,con.conProMod.samT.y,$PRE.con.conProMod.samT.y,con.conProMod.samtOn.y,$PRE.con.conProMod.samtOn.y,con.conProMod.samL.y,$PRE.con.conProMod.samL.y,con.conProMod.samK.y,$PRE.con.conProMod.samK.y,con.conProMod.timConDel.edg.u,$PRE.con.conProMod.timConDel.edg.u,con.conProMod.timConDel.gre1.greHys.y,$PRE.con.conProMod.timConDel.gre1.greHys.y,con.conProMod.tunSta,con.conProMod.triEnd,con.resPro.tunMon.greThr1.y,con.resPro.tunMon.edgTunEnd.u,$PRE.con.resPro.tunMon.edgTunEnd.u,con.resPro.tunMon.edgTunSta.u,$PRE.con.resPro.tunMon.edgTunSta.u,con.resPro.tunMon.tDec.y,con.resPro.tunMon.tInc.y,con.resPro.tunMon.samAddtOntOff.y,$PRE.con.resPro.tunMon.samAddtOntOff.y,con.resPro.norTimDel.tunEndDel.y,con.resPro.norTimDel.tunEndDel.u,con.resPro.norTimDel.or2.y,con.resPro.norTimDel.gre.greHys.y,$PRE.con.resPro.norTimDel.gre.greHys.y,con.resPro.halPerRatio.tOffSam.y,$PRE.con.resPro.halPerRatio.tOffSam.y,con.resPro.halPerRatio.tOnSam.y,$PRE.con.resPro.halPerRatio.tOnSam.y,con.resPro.onOffPer.timOffRec.edgThr.y,con.resPro.onOffPer.timOffRec.edgThr.u,$PRE.con.resPro.onOffPer.timOffRec.edgThr.u,con.resPro.onOffPer.timOffRec.edgRes.u,$PRE.con.resPro.onOffPer.timOffRec.edgRes.u,con.resPro.onOffPer.timOffRec.samTri.y,con.resPro.onOffPer.timOffRec.samTri.u2,con.resPro.onOffPer.timOffRec.yRec.y,$PRE.con.resPro.onOffPer.timOffRec.yRec.y,con.resPro.onOffPer.timOnRec.edgThr.y,con.resPro.onOffPer.timOnRec.edgThr.u,$PRE.con.resPro.onOffPer.timOnRec.edgThr.u,con.resPro.onOffPer.timOnRec.edgRes.u,$PRE.con.resPro.onOffPer.timOnRec.edgRes.u,con.resPro.onOffPer.timOnRec.samTri.y,con.resPro.onOffPer.timOnRec.samTri.u2,con.resPro.onOffPer.timOnRec.yRec.y,$PRE.con.resPro.onOffPer.timOnRec.yRec.y,con.resPro.onOffPer.timOff.y,$PRE.con.resPro.onOffPer.timOff.y,con.resPro.onOffPer.timOn.y,$PRE.con.resPro.onOffPer.timOn.y,con.resPro.triSta,con.rel.hys.y,$PRE.con.rel.hys.y,con.con.greThrkTd.greHys.y,$PRE.con.con.greThrkTd.greHys.y,TSet.nextEventScaled,TSet.nextEvent,$PRE.TSet.nextEvent,TSet.last,TSet.b,TSet.a,$whenCondition1,$whenCondition4,$whenCondition7,$whenCondition6,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition15,$whenCondition14,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition21,con.gre.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (639):
 * Single equations (assignments): 630
 * Array equations: 4
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * 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): 1 system
   {(1,7,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,10)}
Notification: Performance of prepare postOptimizeDAE: time 0.002221/2.309, allocations: 0.5855 MB / 1.913 GB, free: 4.898 MB / 1.467 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00261/2.312, allocations: 0.6405 MB / 1.914 GB, free: 4.254 MB / 1.467 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01455/2.326, allocations: 6.309 MB / 1.92 GB, free: 13.93 MB / 1.482 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.222e-05/2.326, allocations: 51.94 kB / 1.92 GB, free: 13.88 MB / 1.482 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.844e-05/2.326, allocations: 0 / 1.92 GB, free: 13.88 MB / 1.482 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01021/2.337, allocations: 5.31 MB / 1.925 GB, free: 8.543 MB / 1.482 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 postOpt removeSimpleEquations (simulation): time 0.0358/2.372, allocations: 15.38 MB / 1.941 GB, free: 9.145 MB / 1.498 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.066e-05/2.373, allocations: 4 kB / 1.941 GB, free: 9.141 MB / 1.498 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00444/2.377, allocations: 0.8702 MB / 1.941 GB, free: 8.312 MB / 1.498 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0006388/2.378, allocations: 200.9 kB / 1.942 GB, free: 8.113 MB / 1.498 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006309/2.378, allocations: 127 kB / 1.942 GB, free: 7.992 MB / 1.498 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003506/2.382, allocations: 3.491 MB / 1.945 GB, free: 4.359 MB / 1.498 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.906e-06/2.382, allocations: 11.19 kB / 1.945 GB, free: 4.352 MB / 1.498 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01793/2.4, allocations: 9.486 MB / 1.954 GB, free: 10.76 MB / 1.514 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004367/2.404, allocations: 1.34 MB / 1.956 GB, free: 9.41 MB / 1.514 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001679/2.406, allocations: 131.9 kB / 1.956 GB, free: 9.281 MB / 1.514 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00439/2.41, allocations: 283.8 kB / 1.956 GB, free: 9.004 MB / 1.514 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008165/2.411, allocations: 365.2 kB / 1.956 GB, free: 8.652 MB / 1.514 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005637/2.411, allocations: 179.9 kB / 1.957 GB, free: 8.477 MB / 1.514 GB
Notification: Performance of sorting global known variables: time 0.008334/2.42, allocations: 3.947 MB / 1.96 GB, free: 4.559 MB / 1.514 GB
Notification: Performance of sort global known variables: time 7e-08/2.42, allocations: 0 / 1.96 GB, free: 4.559 MB / 1.514 GB
Notification: Performance of remove unused functions: time 0.01335/2.433, allocations: 3.115 MB / 1.963 GB, free: 1.445 MB / 1.514 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 23
 * Number of states: 22 (temSen.T,val.actPos.y,hex.ele[1].vol1.dynBal.U,hex.ele[1].vol2.dynBal.medium.Xi[1],hex.ele[1].vol2.dynBal.U,hex.ele[1].mas.T,hex.ele[2].vol1.dynBal.U,hex.ele[2].vol2.dynBal.medium.Xi[1],hex.ele[2].vol2.dynBal.U,hex.ele[2].mas.T,hex.ele[3].vol1.dynBal.U,hex.ele[3].vol2.dynBal.medium.Xi[1],hex.ele[3].vol2.dynBal.U,hex.ele[3].mas.T,hex.ele[4].vol1.dynBal.U,hex.ele[4].vol2.dynBal.medium.Xi[1],hex.ele[4].vol2.dynBal.U,hex.ele[4].vol2.dynBal.m,hex.ele[4].mas.T,con.con.I.y,con.con.D.x,con.conProMod.gain.Iy.y)
 * Number of discrete variables: 85 (con.samTd.trigger,con.tunStaDel.u,con.resPro.norTimDel.tunEndDel.u,con.inTunPro.clr,con.resPro.triSta,con.conProMod.triEnd,con.resPro.tunMon.greThr1.y,con.resPro.onOffPer.timOnRec.samTri.y,con.resPro.onOffPer.timOffRec.samTri.y,con.inTunPro.u,$whenCondition21,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition4,$whenCondition2,$whenCondition3,$whenCondition1,TSet.a,TSet.b,TSet.last,TSet.nextEvent,TSet.nextEventScaled,con.con.greThrkTd.greHys.y,con.rel.hys.y,con.resPro.onOffPer.timOn.y,con.resPro.onOffPer.timOff.y,con.resPro.onOffPer.timOnRec.yRec.y,con.resPro.onOffPer.timOnRec.samTri.u2,con.resPro.onOffPer.timOnRec.edgRes.u,con.resPro.onOffPer.timOnRec.edgThr.u,con.resPro.onOffPer.timOnRec.edgThr.y,con.resPro.onOffPer.timOffRec.yRec.y,con.resPro.onOffPer.timOffRec.samTri.u2,con.resPro.onOffPer.timOffRec.edgRes.u,con.resPro.onOffPer.timOffRec.edgThr.u,con.resPro.onOffPer.timOffRec.edgThr.y,con.resPro.halPerRatio.tOnSam.y,con.resPro.halPerRatio.tOffSam.y,con.resPro.norTimDel.gre.greHys.y,con.resPro.norTimDel.or2.y,con.resPro.norTimDel.tunEndDel.y,con.resPro.norTimDel.tunEndDel.t_next,con.resPro.tunMon.samAddtOntOff.y,con.resPro.tunMon.tInc.y,con.resPro.tunMon.tDec.y,con.resPro.tunMon.edgTunSta.u,con.resPro.tunMon.edgTunEnd.u,con.conProMod.tunSta,con.conProMod.timConDel.gre1.greHys.y,con.conProMod.timConDel.edg.u,con.conProMod.samK.y,con.conProMod.samL.y,con.conProMod.samtOn.y,con.conProMod.samT.y,con.conProMod.samTau.y,con.conProMod.not1.y,con.inTunPro.y,con.samk.y,con.samTi.y,con.samTd.y,con.nand.u2,con.nand.y,con.edgReq.u,con.tunStaDel.y,con.tunStaDel.t_next,con.sam_u_s.y,con.greThr.greHys.y,con.falEdg.y,con.falEdg.not_u,autTunSig.y,con.gre.y)
 * Number of discrete states: 22 (con.resPro.norTimDel.tunEndDel.t_next,con.tunStaDel.t_next,con.edgReq.u,con.resPro.onOffPer.timOffRec.edgRes.u,con.resPro.onOffPer.timOnRec.edgRes.u,TSet.nextEvent,TSet.last,TSet.nextEventScaled,TSet.b,TSet.a,$whenCondition1,con.rel.hys.y,con.resPro.onOffPer.timOffRec.edgThr.u,con.resPro.onOffPer.timOnRec.edgThr.u,con.resPro.tunMon.edgTunEnd.u,con.conProMod.timConDel.gre1.greHys.y,con.conProMod.timConDel.edg.u,con.resPro.norTimDel.gre.greHys.y,con.resPro.tunMon.edgTunSta.u,con.greThr.greHys.y,con.falEdg.not_u,con.con.greThrkTd.greHys.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (476):
 * Single equations (assignments): 446
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 4
 * When equations: 22
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * 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): 1 system
   {(1,7,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.02028/2.453, allocations: 10.63 MB / 1.974 GB, free: 6.766 MB / 1.529 GB
Notification: Performance of simCode: created initialization part: time 0.01868/2.472, allocations: 9.419 MB / 1.983 GB, free: 13.45 MB / 1.545 GB
Notification: Performance of simCode: created event and clocks part: time 1.262e-05/2.472, allocations: 5 kB / 1.983 GB, free: 13.45 MB / 1.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.006225/2.478, allocations: 3.079 MB / 1.986 GB, free: 10.45 MB / 1.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01742/2.496, allocations: 3.193 MB / 1.989 GB, free: 7.324 MB / 1.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1215/2.617, allocations: 54.8 MB / 2.043 GB, free: 15.71 MB / 1.607 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008569/2.626, allocations: 5.151 MB / 2.048 GB, free: 10.53 MB / 1.607 GB
Notification: Performance of simCode: alias equations: time 0.01018/2.636, allocations: 2.834 MB / 2.051 GB, free: 7.711 MB / 1.607 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.006614/2.643, allocations: 3.354 MB / 2.054 GB, free: 4.355 MB / 1.607 GB
Notification: Performance of SimCode: time 8.11e-07/2.643, allocations: 0 / 2.054 GB, free: 4.355 MB / 1.607 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.31/2.953, allocations: 175.2 MB / 2.225 GB, free: 6.688 MB / 1.779 GB
Notification: Performance of buildModelFMU: Generate platform static: time 28.59/31.55, allocations: 0 / 2.225 GB, free: 6.688 MB / 1.779 GB
"
[Timeout remaining time 268]
(rm -f Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.pipe ; mkfifo Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.pipe >> ../files/Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning_res.mat --tempDir=temp_Buildings_latest_Buildings_Fluid_HeatExchangers_Examples_WetCoilCounterFlowPIDControlAutoTuning_fmu --startTime=0 --stopTime=3600 --stepSize=1.44 --timeout=800 --tolerance=1e-06 Buildings_latest_Buildings_Fluid_HeatExchangers_Examples_WetCoilCounterFlowPIDControlAutoTuning.fmu > Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.pipe 2>&1) [Timeout 840.0]
diffSimulationResults("Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Fluid_HeatExchangers_Examples_WetCoilCounterFlowPIDControlAutoTuning.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Fluid.HeatExchangers.Examples.WetCoilCounterFlowPIDControlAutoTuning.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
""

Variables in the reference:time,hex.m1_flow,hex.m2_flow,con.u_s,con.u_m,hex.Q1_flow,hex.Q2_flow,hex.QLat2_flow,hex.QSen2_flow,autTunSig.y,con.inTunPro.y

Variables in the result:TSet.offset,TSet.shiftTime,TSet.startTime,TSet.table[1,1],TSet.table[1,2],TSet.table[2,1],TSet.table[2,2],TSet.table[3,1],TSet.table[3,2],TSet.table[4,1],TSet.table[4,2],TSet.table[5,1],TSet.table[5,2],TSet.table[6,1],TSet.table[6,2],TSet.table[7,1],TSet.table[7,2],TSet.table[8,1],TSet.table[8,2],TSet.table[9,1],TSet.table[9,2],TSet.timeScale,TSet.y,TWat.duration,TWat.height,TWat.offset,TWat.startTime,TWat.y,T_a1_nominal,T_a2_nominal,T_b1_nominal,T_b2_nominal,autTunSig.period,autTunSig.shift,autTunSig.width,autTunSig.y,con.Nd,con.Ni,con.PIDPar.L,con.PIDPar.T,con.PIDPar.Td,con.PIDPar.Ti,con.PIDPar.k,con.PIDPar.kp,con.Td_start,con.Ti_start,con.con.D.T,con.con.D.k,con.con.D.u,con.con.D.x,con.con.D.y,con.con.D.y_start,con.con.I.k,con.con.I.trigger,con.con.I.u,con.con.I.y,con.con.I.y_reset_in,con.con.I.y_start,con.con.Nd,con.con.Ni,con.con.P.u1,con.con.P.u2,con.con.P.y,con.con.Td,con.con.Ti,con.con.antWinGai2.u1,con.con.antWinGai2.u2,con.con.antWinGai2.y,con.con.controlError.u1,con.con.controlError.u2,con.con.controlError.y,con.con.controllerType,con.con.errD.u1,con.con.errD.u2,con.con.errD.y,con.con.errI1.u1,con.con.errI1.u2,con.con.errI1.y,con.con.errI2.u1,con.con.errI2.u2,con.con.errI2.y,con.con.errIWithGai.u1,con.con.errIWithGai.u2,con.con.errIWithGai.y,con.con.errP.u1,con.con.errP.u2,con.con.errP.y,con.con.gaiI.u1,con.con.gaiI.u2,con.con.gaiI.y,con.con.greThrkTd.h,con.con.greThrkTd.pre_y_start,con.con.greThrkTd.t,con.con.greThrkTd.u,con.con.greThrkTd.y,con.con.k,con.con.lim.u,con.con.lim.uMax,con.con.lim.uMin,con.con.lim.y,con.con.mulkTd.u1,con.con.mulkTd.u2,con.con.mulkTd.y,con.con.r,con.con.reverseActing,con.con.trigger,con.con.u_m,con.con.u_s,con.con.xi_start,con.con.y,con.con.yMax,con.con.yMin,con.con.y_reset,con.con.yd_start,con.conProMod.L,con.conProMod.T,con.conProMod.deaBan,con.conProMod.gain.Iy.y,con.conProMod.k,con.conProMod.tOff,con.conProMod.tOn,con.conProMod.tau,con.conProMod.triEnd,con.conProMod.triSta,con.conProMod.tunSta,con.conProMod.u,con.conProMod.yHig,con.conProMod.yLow,con.controllerType,con.deaBan,con.inTunPro.clr,con.inTunPro.u,con.inTunPro.y,con.k_start,con.r,con.rel.deaBan,con.rel.r,con.rel.reverseActing,con.rel.trigger,con.rel.u_m,con.rel.u_s,con.rel.y,con.rel.yDif,con.rel.yHig,con.rel.yLow,con.rel.yOn,con.resPro.inTun,con.resPro.on,con.resPro.tOff,con.resPro.tOn,con.resPro.tau,con.resPro.tim,con.resPro.triEnd,con.resPro.triSta,con.resPro.trigger,con.resPro.yHig,con.resPro.yLow,con.reverseActing,con.setHys,con.triRes,con.triTun,con.u_m,con.u_s,con.xi_start,con.y,con.yHig,con.yLow,con.yMax,con.yMin,con.yRef,con.y_reset,con.yd_start,const.k,const.y,const1.k,const1.y,der(con.con.D.x),der(con.con.I.y),der(con.conProMod.gain.Iy.y),der(hex.ele[1].mas.T),der(hex.ele[1].vol1.dynBal.U),der(hex.ele[1].vol2.dynBal.U),der(hex.ele[1].vol2.dynBal.medium.Xi[1]),der(hex.ele[2].mas.T),der(hex.ele[2].vol1.dynBal.U),der(hex.ele[2].vol2.dynBal.U),der(hex.ele[2].vol2.dynBal.medium.Xi[1]),der(hex.ele[3].mas.T),der(hex.ele[3].vol1.dynBal.U),der(hex.ele[3].vol2.dynBal.U),der(hex.ele[3].vol2.dynBal.medium.Xi[1]),der(hex.ele[4].mas.T),der(hex.ele[4].vol1.dynBal.U),der(hex.ele[4].vol2.dynBal.U),der(hex.ele[4].vol2.dynBal.m),der(hex.ele[4].vol2.dynBal.medium.Xi[1]),der(temSen.T),der(val.actPos.y),hex.GDif,hex.Q1_flow,hex.Q2_flow,hex.QLat2_flow,hex.QSen2_flow,hex.SHR,hex.T1[1],hex.T1[2],hex.T1[3],hex.T1[4],hex.T2[1],hex.T2[2],hex.T2[3],hex.T2[4],hex.T_m[1],hex.T_m[2],hex.T_m[3],hex.T_m[4],hex.UA_nominal,hex.airSideFlowDependent,hex.airSideTemperatureDependent,hex.allowFlowReversal1,hex.allowFlowReversal2,hex.computeFlowResistance1,hex.computeFlowResistance2,hex.deltaM1,hex.deltaM2,hex.dp1,hex.dp1_nominal,hex.dp2,hex.dp2_nominal,hex.ele[1].mas.T,hex.ele[1].vol1.dynBal.U,hex.ele[1].vol2.dynBal.U,hex.ele[1].vol2.dynBal.medium.Xi[1],hex.ele[2].mas.T,hex.ele[2].vol1.dynBal.U,hex.ele[2].vol2.dynBal.U,hex.ele[2].vol2.dynBal.medium.Xi[1],hex.ele[3].mas.T,hex.ele[3].vol1.dynBal.U,hex.ele[3].vol2.dynBal.U,hex.ele[3].vol2.dynBal.medium.Xi[1],hex.ele[4].mas.T,hex.ele[4].vol1.dynBal.U,hex.ele[4].vol2.dynBal.U,hex.ele[4].vol2.dynBal.m,hex.ele[4].vol2.dynBal.medium.Xi[1],hex.energyDynamics,hex.from_dp1,hex.from_dp2,hex.hA.T0_a,hex.hA.T0_w,hex.hA.T_1,hex.hA.T_2,hex.hA.UA_nominal,hex.hA.airSideFlowDependent,hex.hA.airSideTemperatureDependent,hex.hA.hA_1,hex.hA.hA_2,hex.hA.hA_nominal_a,hex.hA.hA_nominal_w,hex.hA.m1_flow,hex.hA.m2_flow,hex.hA.m_flow_nominal_a,hex.hA.m_flow_nominal_w,hex.hA.n_a,hex.hA.n_w,hex.hA.r_nominal,hex.hA.waterSideFlowDependent,hex.hA.waterSideTemperatureDependent,hex.linearizeFlowResistance1,hex.linearizeFlowResistance2,hex.m1_flow,hex.m1_flow_nominal,hex.m1_flow_small,hex.m2_flow,hex.m2_flow_nominal,hex.m2_flow_small,hex.mWat_flow,hex.nEle,hex.port_a1.h_outflow,hex.port_a1.m_flow,hex.port_a1.p,hex.port_a2.Xi_outflow[1],hex.port_a2.h_outflow,hex.port_a2.m_flow,hex.port_a2.p,hex.port_b1.h_outflow,hex.port_b1.m_flow,hex.port_b1.p,hex.port_b2.Xi_outflow[1],hex.port_b2.h_outflow,hex.port_b2.m_flow,hex.port_b2.p,hex.r_nominal,hex.show_T,hex.sta_a1.T,hex.sta_a1.p,hex.sta_a2.T,hex.sta_a2.X[1],hex.sta_a2.X[2],hex.sta_a2.p,hex.sta_b1.T,hex.sta_b1.p,hex.sta_b2.T,hex.sta_b2.X[1],hex.sta_b2.X[2],hex.sta_b2.p,hex.tau1,hex.tau2,hex.tau_m,hex.waterSideFlowDependent,hex.waterSideTemperatureDependent,m1_flow_nominal,m2_flow_nominal,resSig.k,resSig.y,res_1.allowFlowReversal,res_1.deltaM,res_1.dp,res_1.dp_nominal,res_1.from_dp,res_1.k,res_1.linearized,res_1.m_flow,res_1.m_flow_nominal,res_1.m_flow_small,res_1.m_flow_turbulent,res_1.port_a.h_outflow,res_1.port_a.m_flow,res_1.port_a.p,res_1.port_b.h_outflow,res_1.port_b.m_flow,res_1.port_b.p,res_1.show_T,res_2.allowFlowReversal,res_2.deltaM,res_2.dp,res_2.dp_nominal,res_2.from_dp,res_2.k,res_2.linearized,res_2.m_flow,res_2.m_flow_nominal,res_2.m_flow_small,res_2.m_flow_turbulent,res_2.port_a.Xi_outflow[1],res_2.port_a.h_outflow,res_2.port_a.m_flow,res_2.port_a.p,res_2.port_b.Xi_outflow[1],res_2.port_b.h_outflow,res_2.port_b.m_flow,res_2.port_b.p,res_2.show_T,sin_1.T,sin_1.X[1],sin_1.nPorts,sin_1.p,sin_1.ports[1].h_outflow,sin_1.ports[1].m_flow,sin_1.ports[1].p,sin_1.use_C_in,sin_1.use_T_in,sin_1.use_X_in,sin_1.use_Xi_in,sin_1.use_p_in,sin_1.verifyInputs,sin_2.T,sin_2.X[1],sin_2.X[2],sin_2.nPorts,sin_2.p,sin_2.ports[1].Xi_outflow[1],sin_2.ports[1].h_outflow,sin_2.ports[1].m_flow,sin_2.ports[1].p,sin_2.use_C_in,sin_2.use_T_in,sin_2.use_X_in,sin_2.use_Xi_in,sin_2.use_p_in,sin_2.verifyInputs,sou_1.T,sou_1.T_in,sou_1.X[1],sou_1.nPorts,sou_1.p,sou_1.ports[1].h_outflow,sou_1.ports[1].m_flow,sou_1.ports[1].p,sou_1.use_C_in,sou_1.use_T_in,sou_1.use_X_in,sou_1.use_Xi_in,sou_1.use_p_in,sou_1.verifyInputs,sou_2.T,sou_2.T_in,sou_2.X[1],sou_2.X[2],sou_2.X_in[1],sou_2.X_in[2],sou_2.nPorts,sou_2.p,sou_2.ports[1].Xi_outflow[1],sou_2.ports[1].h_outflow,sou_2.ports[1].m_flow,sou_2.ports[1].p,sou_2.use_C_in,sou_2.use_T_in,sou_2.use_X_in,sou_2.use_Xi_in,sou_2.use_p_in,sou_2.verifyInputs,temSen.T,temSen.TAmb,temSen.T_start,temSen.allowFlowReversal,temSen.initType,temSen.m_flow_nominal,temSen.m_flow_small,temSen.port_a.Xi_outflow[1],temSen.port_a.h_outflow,temSen.port_a.m_flow,temSen.port_a.p,temSen.port_b.Xi_outflow[1],temSen.port_b.h_outflow,temSen.port_b.m_flow,temSen.port_b.p,temSen.tau,temSen.tauHeaTra,temSen.transferHeat,time,val.Av,val.Cv,val.CvData,val.Kv,val.R,val.actPos.y,val.allowFlowReversal,val.delta0,val.deltaM,val.dp,val.dpFixed_nominal,val.dpValve_nominal,val.dp_nominal,val.from_dp,val.init,val.k,val.kFixed,val.kVal,val.l,val.linearized,val.m_flow,val.m_flow_nominal,val.m_flow_small,val.m_flow_turbulent,val.phi,val.port_a.h_outflow,val.port_a.m_flow,val.port_a.p,val.port_b.h_outflow,val.port_b.m_flow,val.port_b.p,val.rhoStd,val.show_T,val.strokeTime,val.use_strokeTime,val.y,val.y_actual,val.y_start,x_pTphi.T,x_pTphi.X[1],x_pTphi.X[2],x_pTphi.p,x_pTphi.phi,x_pTphi.use_p_in
[Calling sys.exit(0), Time elapsed: 39.10793608613312]