Running: ./testmodel.py --libraries=/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ --ompython_omhome=/usr IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.conf.json
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package IBPSA with version 3.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo)
Using package Complex with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse,tolerance=1e-06,outputFormat="empty",numberOfIntervals=60000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse")
translateModel(IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse,tolerance=1e-06,outputFormat="empty",numberOfIntervals=60000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse")
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0009963/0.0009963, allocations: 176.1 kB / 16.08 MB, free: 5.602 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 0.4347/0.4347, allocations: 89.08 MB / 105.9 MB, free: 12.45 MB / 90.72 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.265/1.265, allocations: 225.7 MB / 353.3 MB, free: 1.102 MB / 282.7 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0007459/0.0007458, allocations: 90.84 kB / 425.4 MB, free: 6.648 MB / 314.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.000293/0.0002931, allocations: 8 kB / 0.4955 GB, free: 4.535 MB / 394.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3031/0.3034, allocations: 71.33 MB / 0.5652 GB, free: 42.36 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse): time 0.4669/0.7703, allocations: 187.7 MB / 0.7485 GB, free: 10.58 MB / 0.5105 GB
Notification: Performance of NFInst.instExpressions: time 0.01509/0.7855, allocations: 12.99 MB / 0.7612 GB, free: 5.816 MB / 0.5105 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002809/0.7883, allocations: 37.5 kB / 0.7612 GB, free: 5.816 MB / 0.5105 GB
Notification: Performance of NFTyping.typeComponents: time 0.002282/0.7906, allocations: 0.597 MB / 0.7618 GB, free: 5.805 MB / 0.5105 GB
Notification: Performance of NFTyping.typeBindings: time 0.00667/0.7973, allocations: 2.179 MB / 0.764 GB, free: 5.688 MB / 0.5105 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004036/0.8014, allocations: 1.394 MB / 0.7653 GB, free: 5.625 MB / 0.5105 GB
Notification: Performance of NFFlatten.flatten: time 0.0056/0.807, allocations: 4.93 MB / 0.7701 GB, free: 5.594 MB / 0.5105 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002665/0.8097, allocations: 1.733 MB / 0.7718 GB, free: 5.312 MB / 0.5105 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002662/0.8124, allocations: 1.134 MB / 0.7729 GB, free: 5.305 MB / 0.5105 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003003/0.8154, allocations: 1.248 MB / 0.7741 GB, free: 5.203 MB / 0.5105 GB
Notification: Performance of NFPackage.collectConstants: time 0.0004033/0.8158, allocations: 83.98 kB / 0.7742 GB, free: 5.203 MB / 0.5105 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005121/0.821, allocations: 2.238 MB / 0.7764 GB, free: 4.746 MB / 0.5105 GB
Notification: Performance of NFScalarize.scalarize: time 0.0009513/0.8219, allocations: 0.6235 MB / 0.777 GB, free: 4.57 MB / 0.5105 GB
Notification: Performance of NFVerifyModel.verify: time 0.001592/0.8236, allocations: 1.063 MB / 0.7781 GB, free: 4.152 MB / 0.5105 GB
Notification: Performance of NFConvertDAE.convert: time 0.006391/0.83, allocations: 4.187 MB / 0.7821 GB, free: 2.023 MB / 0.5105 GB
Notification: Performance of FrontEnd - DAE generated: time 7.314e-06/0.83, allocations: 0 / 0.7821 GB, free: 2.023 MB / 0.5105 GB
Notification: Performance of FrontEnd: time 1.954e-06/0.83, allocations: 0 / 0.7821 GB, free: 2.023 MB / 0.5105 GB
Notification: Performance of Transformations before backend: time 8.133e-05/0.8301, allocations: 4 kB / 0.7822 GB, free: 2.02 MB / 0.5105 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 487
 * Number of variables: 487
Notification: Performance of Generate backend data structure: time 0.005961/0.8361, allocations: 2.387 MB / 0.7845 GB, free: 0.5117 MB / 0.5105 GB
Notification: Performance of prepare preOptimizeDAE: time 4.19e-05/0.8361, allocations: 8.25 kB / 0.7845 GB, free: 0.5117 MB / 0.5105 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002376/0.8385, allocations: 458.3 kB / 0.7849 GB, free: 316 kB / 0.5105 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002493/0.841, allocations: 1.415 MB / 0.7863 GB, free: 15.65 MB / 0.5261 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001042/0.8411, allocations: 142 kB / 0.7864 GB, free: 15.64 MB / 0.5261 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002483/0.8414, allocations: 189.4 kB / 0.7866 GB, free: 15.64 MB / 0.5261 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003871/0.8453, allocations: 2.276 MB / 0.7888 GB, free: 15.03 MB / 0.5261 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.111e-05/0.8453, allocations: 5.109 kB / 0.7889 GB, free: 15.03 MB / 0.5261 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001782/0.8455, allocations: 81.94 kB / 0.7889 GB, free: 15.02 MB / 0.5261 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001015/0.8456, allocations: 78.38 kB / 0.789 GB, free: 15.02 MB / 0.5261 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004478/0.8501, allocations: 2.815 MB / 0.7918 GB, free: 13.25 MB / 0.5261 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.01041/0.8606, allocations: 6.862 MB / 0.7985 GB, free: 8.016 MB / 0.5261 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003212/0.8638, allocations: 2.267 MB / 0.8007 GB, free: 6.668 MB / 0.5261 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001663/0.8655, allocations: 1.124 MB / 0.8018 GB, free: 5.961 MB / 0.5261 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001265/0.8668, allocations: 1.025 MB / 0.8028 GB, free: 4.754 MB / 0.5261 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001875/0.8687, allocations: 1.215 MB / 0.804 GB, free: 3.898 MB / 0.5261 GB
Notification: Performance of pre-optimization done (n=207): time 3.767e-06/0.8687, allocations: 0 / 0.804 GB, free: 3.898 MB / 0.5261 GB
Notification: Performance of matching and sorting (n=207): time 0.006468/0.8752, allocations: 3.256 MB / 0.8071 GB, free: 1.555 MB / 0.5261 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001139/0.8753, allocations: 205.9 kB / 0.8073 GB, free: 1.312 MB / 0.5261 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001606/0.8769, allocations: 1.161 MB / 0.8085 GB, free: 464 kB / 0.5261 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001392/0.8771, allocations: 69.58 kB / 0.8085 GB, free: 416 kB / 0.5261 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005654/0.8777, allocations: 0.7021 MB / 0.8092 GB, free: 15.96 MB / 0.5417 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005755/0.8783, allocations: 0.6878 MB / 0.8099 GB, free: 15.37 MB / 0.5417 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001229/0.8784, allocations: 36.62 kB / 0.8099 GB, free: 15.36 MB / 0.5417 GB
Notification: Performance of setup shared object (initialization): time 0.0001495/0.8785, allocations: 380.8 kB / 0.8103 GB, free: 15.02 MB / 0.5417 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001252/0.8798, allocations: 0.8086 MB / 0.8111 GB, free: 14.5 MB / 0.5417 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001412/0.8812, allocations: 1.006 MB / 0.8121 GB, free: 13.8 MB / 0.5417 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003113/0.8843, allocations: 1.931 MB / 0.8139 GB, free: 12.62 MB / 0.5417 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.052e-06/0.8844, allocations: 4 kB / 0.8139 GB, free: 12.62 MB / 0.5417 GB
Notification: Performance of matching and sorting (n=231) (initialization): time 0.004782/0.8892, allocations: 2.271 MB / 0.8162 GB, free: 11.07 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 2.906e-05/0.8892, allocations: 4 kB / 0.8162 GB, free: 11.06 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.543e-05/0.8892, allocations: 4 kB / 0.8162 GB, free: 11.06 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007024/0.8899, allocations: 88.86 kB / 0.8163 GB, free: 10.98 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00169/0.8916, allocations: 384.3 kB / 0.8166 GB, free: 10.64 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002021/0.8937, allocations: 1.528 MB / 0.8181 GB, free: 9.105 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002116/0.8958, allocations: 231 kB / 0.8183 GB, free: 8.91 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001691/0.896, allocations: 74.72 kB / 0.8184 GB, free: 8.852 MB / 0.5417 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 8
 * Number of states: 0 ()
 * Number of discrete variables: 8 ($whenCondition2,$whenCondition1,$PRE.weaDat.conTimMin.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTim.tNext,weaDat.conTimMin.canRepeatWeatherFile,weaDat.conTim.canRepeatWeatherFile)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (229):
 * Single equations (assignments): 228
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001268/0.8973, allocations: 0.5058 MB / 0.8189 GB, free: 8.547 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003115/0.8976, allocations: 169.9 kB / 0.8191 GB, free: 8.379 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004754/0.9023, allocations: 2.607 MB / 0.8216 GB, free: 6.645 MB / 0.5417 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.594e-05/0.9024, allocations: 20 kB / 0.8216 GB, free: 6.625 MB / 0.5417 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.222e-06/0.9024, allocations: 4 kB / 0.8216 GB, free: 6.621 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003531/0.9059, allocations: 2.384 MB / 0.824 GB, free: 4.844 MB / 0.5417 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00817/0.9141, allocations: 5.378 MB / 0.8292 GB, free: 0.7578 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.039e-05/0.9141, allocations: 6.531 kB / 0.8292 GB, free: 0.7539 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001517/0.9157, allocations: 376.8 kB / 0.8296 GB, free: 416 kB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0006725/0.9163, allocations: 137.6 kB / 0.8297 GB, free: 272 kB / 0.5417 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001347/0.9165, allocations: 54.61 kB / 0.8298 GB, free: 220 kB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002787/0.9193, allocations: 3.006 MB / 0.8327 GB, free: 12.96 MB / 0.5573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.408e-06/0.9193, allocations: 4 kB / 0.8327 GB, free: 12.95 MB / 0.5573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006066/0.9254, allocations: 3.874 MB / 0.8365 GB, free: 9.012 MB / 0.5573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001305/0.9267, allocations: 0.5372 MB / 0.837 GB, free: 8.453 MB / 0.5573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003209/0.9271, allocations: 47.98 kB / 0.8371 GB, free: 8.406 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001354/0.9284, allocations: 136 kB / 0.8372 GB, free: 8.273 MB / 0.5573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003183/0.9287, allocations: 152.3 kB / 0.8373 GB, free: 8.125 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001439/0.9289, allocations: 75.97 kB / 0.8374 GB, free: 8.051 MB / 0.5573 GB
Notification: Performance of sorting global known variables: time 0.00116/0.9301, allocations: 0.8047 MB / 0.8382 GB, free: 7.242 MB / 0.5573 GB
Notification: Performance of sort global known variables: time 1.6e-07/0.9301, allocations: 0 / 0.8382 GB, free: 7.242 MB / 0.5573 GB
Notification: Performance of remove unused functions: time 0.002013/0.9321, allocations: 0.7018 MB / 0.8389 GB, free: 6.539 MB / 0.5573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 20
 * Number of states: 5 (simpleZone.volRoom.dynBal.U,simpleZone.volRoom.dynBal.m,simpleZone.volRoom.dynBal.mXi[1],simpleZone.door.mExcAB,simpleZone.door.mExcBA)
 * Number of discrete variables: 4 ($whenCondition2,$whenCondition1,weaDat.conTimMin.tNext,weaDat.conTim.tNext)
 * Number of discrete states: 2 (weaDat.conTim.tNext,weaDat.conTimMin.tNext)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (216):
 * Single equations (assignments): 212
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001582/0.9337, allocations: 0.8353 MB / 0.8397 GB, free: 5.918 MB / 0.5573 GB
Notification: Performance of simCode: created initialization part: time 0.003577/0.9373, allocations: 2.174 MB / 0.8418 GB, free: 3.762 MB / 0.5573 GB
Notification: Performance of simCode: created event and clocks part: time 6.462e-06/0.9373, allocations: 0 / 0.8418 GB, free: 3.762 MB / 0.5573 GB
Notification: Performance of simCode: created simulation system equations: time 0.002025/0.9393, allocations: 1.396 MB / 0.8432 GB, free: 2.395 MB / 0.5573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003763/0.9431, allocations: 0.7307 MB / 0.8439 GB, free: 1.672 MB / 0.5573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.007861/0.951, allocations: 5.4 MB / 0.8492 GB, free: 12.2 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001039/0.952, allocations: 1.522 MB / 0.8506 GB, free: 10.64 MB / 0.573 GB
Notification: Performance of simCode: alias equations: time 0.00212/0.9542, allocations: 0.6765 MB / 0.8513 GB, free: 9.965 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005305/0.9547, allocations: 183.4 kB / 0.8515 GB, free: 9.785 MB / 0.573 GB
Notification: Performance of SimCode: time 4.71e-07/0.9547, allocations: 0 / 0.8515 GB, free: 9.785 MB / 0.573 GB
Notification: Performance of Templates: time 0.04976/1.004, allocations: 32.58 MB / 0.8833 GB, free: 9.555 MB / 0.6042 GB
make -j1 -f IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.makefile
(rm -f IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe ; mkfifo IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe >> ../files/IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.sim & ./IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Experimental.Benchmarks.AirFlow.Examples.ZoneStepResponse.pipe 2>&1)