Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate.conf.json
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package IBPSA with version 3.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.004026/0.004031, allocations: 356.6 kB / 15.94 MB, free: 6.207 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 1.525/1.525, allocations: 165.8 MB / 182.5 MB, free: 5.465 MB / 138.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 3.882/3.882, allocations: 410.8 MB / 0.5995 GB, free: 15.19 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001693/0.001693, allocations: 186.4 kB / 0.6662 GB, free: 6.969 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0007745/0.0007747, allocations: 19.98 kB / 0.7423 GB, free: 9 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.5087/0.5095, allocations: 67.44 MB / 0.8081 GB, free: 28.83 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeTraceSubstanceReverseFlowPrescribedHeatFlowRate): time 0.6376/1.147, allocations: 171.1 MB / 0.9753 GB, free: 1.035 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01025/1.157, allocations: 5.845 MB / 0.981 GB, free: 0.6133 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001471/1.159, allocations: 32.94 kB / 0.981 GB, free: 0.5977 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001822/1.161, allocations: 0.5 MB / 0.9815 GB, free: 468 kB / 474.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:788:36-789:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:786:36-787:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:788:36-789:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Fluid/Sources.mo:786:36-787:54:writable] Warning: 'each' used when modifying non-array element m_flow.
Notification: Performance of NFTyping.typeBindings: time 0.00622/1.167, allocations: 1.783 MB / 0.9832 GB, free: 60 kB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004743/1.172, allocations: 1.199 MB / 0.9844 GB, free: 15.84 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.004488/1.176, allocations: 2.966 MB / 0.9873 GB, free: 14.84 MB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001467/1.178, allocations: 0.7389 MB / 0.988 GB, free: 14.54 MB / 490.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002024/1.18, allocations: 0.8057 MB / 0.9888 GB, free: 14.23 MB / 490.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001496/1.181, allocations: 0.7976 MB / 0.9896 GB, free: 13.95 MB / 490.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001995/1.182, allocations: 43.34 kB / 0.9896 GB, free: 13.95 MB / 490.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002602/1.184, allocations: 0.8363 MB / 0.9905 GB, free: 13.69 MB / 490.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006279/1.185, allocations: 434.7 kB / 0.9909 GB, free: 13.5 MB / 490.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004474/1.185, allocations: 253.8 kB / 0.9911 GB, free: 13.43 MB / 490.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002525/1.188, allocations: 1.483 MB / 0.9926 GB, free: 12.71 MB / 490.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.327e-06/1.188, allocations: 0 / 0.9926 GB, free: 12.71 MB / 490.7 MB
Notification: Performance of FrontEnd: time 2.167e-06/1.188, allocations: 0 / 0.9926 GB, free: 12.71 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 2.274e-05/1.188, allocations: 0 / 0.9926 GB, free: 12.71 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 216
 * Number of variables: 216
Notification: Performance of Generate backend data structure: time 0.002815/1.191, allocations: 1.298 MB / 0.9938 GB, free: 11.8 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.486e-05/1.191, allocations: 10.56 kB / 0.9938 GB, free: 11.8 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003763/1.195, allocations: 0.4896 MB / 0.9943 GB, free: 11.61 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002051/1.197, allocations: 0.7801 MB / 0.9951 GB, free: 11.17 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004026/1.197, allocations: 128.6 kB / 0.9952 GB, free: 11.15 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001798/1.197, allocations: 92.72 kB / 0.9953 GB, free: 11.14 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002933/1.2, allocations: 1.196 MB / 0.9965 GB, free: 10.79 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.61e-05/1.2, allocations: 5.984 kB / 0.9965 GB, free: 10.79 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001331/1.201, allocations: 36.75 kB / 0.9965 GB, free: 10.79 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.611e-05/1.201, allocations: 31.28 kB / 0.9965 GB, free: 10.79 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003085/1.204, allocations: 1.103 MB / 0.9976 GB, free: 10.57 MB / 490.7 MB
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.00927/1.213, allocations: 4.368 MB / 1.002 GB, free: 7.965 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002425/1.216, allocations: 0.7923 MB / 1.003 GB, free: 7.777 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001194/1.217, allocations: 423 kB / 1.003 GB, free: 7.688 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002346/1.217, allocations: 100.8 kB / 1.003 GB, free: 7.59 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.955e-05/1.217, allocations: 46.28 kB / 1.003 GB, free: 7.547 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=43): time 3.507e-06/1.217, allocations: 4 kB / 1.003 GB, free: 7.543 MB / 490.7 MB
Notification: Performance of matching and sorting (n=45): time 0.007481/1.225, allocations: 2.281 MB / 1.005 GB, free: 6.727 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.46e-05/1.225, allocations: 53.97 kB / 1.005 GB, free: 6.664 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001378/1.226, allocations: 0.853 MB / 1.006 GB, free: 6.395 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001135/1.226, allocations: 35.14 kB / 1.006 GB, free: 6.363 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004414/1.227, allocations: 495.9 kB / 1.007 GB, free: 6.109 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002112/1.227, allocations: 162.9 kB / 1.007 GB, free: 5.992 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.171e-05/1.227, allocations: 11.44 kB / 1.007 GB, free: 5.992 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001811/1.227, allocations: 333.4 kB / 1.007 GB, free: 5.676 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00121/1.228, allocations: 388.9 kB / 1.008 GB, free: 5.598 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001331/1.23, allocations: 0.5332 MB / 1.008 GB, free: 5.34 MB / 490.7 MB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: volDyn.dynBal.medium.p = volDyn.dynBal.p_start (bou.p = volDyn.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.00556/1.235, allocations: 1.556 MB / 1.01 GB, free: 4.855 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.276e-06/1.235, allocations: 0 / 1.01 GB, free: 4.855 MB / 490.7 MB
Notification: Performance of matching and sorting (n=73) (initialization): time 0.002996/1.238, allocations: 1.028 MB / 1.011 GB, free: 4.516 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.352e-05/1.238, allocations: 4 kB / 1.011 GB, free: 4.512 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.449e-05/1.238, allocations: 7.984 kB / 1.011 GB, free: 4.504 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 2.354e-05/1.238, allocations: 8 kB / 1.011 GB, free: 4.496 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00126/1.24, allocations: 210.2 kB / 1.011 GB, free: 4.297 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.113e-05/1.24, allocations: 11.98 kB / 1.011 GB, free: 4.285 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001423/1.241, allocations: 101.3 kB / 1.011 GB, free: 4.195 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.844e-05/1.241, allocations: 34.94 kB / 1.011 GB, free: 4.164 MB / 490.7 MB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization sytem:
         volDyn.dynBal.medium.p = volDyn.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (73):
 * Single equations (assignments): 73
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0006969/1.242, allocations: 314.8 kB / 1.011 GB, free: 3.926 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002583/1.242, allocations: 86.97 kB / 1.011 GB, free: 3.84 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002577/1.243, allocations: 101.9 kB / 1.012 GB, free: 3.781 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.189e-06/1.243, allocations: 8 kB / 1.012 GB, free: 3.773 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.278e-06/1.243, allocations: 0 / 1.012 GB, free: 3.773 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003555/1.246, allocations: 1.07 MB / 1.013 GB, free: 2.789 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01097/1.257, allocations: 2.208 MB / 1.015 GB, free: 0.9883 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.653e-05/1.257, allocations: 4 kB / 1.015 GB, free: 0.9844 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001887/1.259, allocations: 191.4 kB / 1.015 GB, free: 0.8008 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 2.16e-05/1.259, allocations: 4 kB / 1.015 GB, free: 0.7969 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001146/1.259, allocations: 20.23 kB / 1.015 GB, free: 0.7852 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.726e-05/1.259, allocations: 7.938 kB / 1.015 GB, free: 0.7773 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.342e-05/1.259, allocations: 7.938 kB / 1.015 GB, free: 0.7695 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006274/1.266, allocations: 1.503 MB / 1.016 GB, free: 15.54 MB / 0.4949 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007234/1.266, allocations: 257.4 kB / 1.017 GB, free: 15.31 MB / 0.4949 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001708/1.267, allocations: 17.22 kB / 1.017 GB, free: 15.3 MB / 0.4949 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001266/1.268, allocations: 105.2 kB / 1.017 GB, free: 15.21 MB / 0.4949 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001114/1.268, allocations: 37.09 kB / 1.017 GB, free: 15.18 MB / 0.4949 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000101/1.268, allocations: 27.23 kB / 1.017 GB, free: 15.15 MB / 0.4949 GB
Notification: Performance of sorting global known variables: time 0.001064/1.269, allocations: 0.591 MB / 1.017 GB, free: 14.71 MB / 0.4949 GB
Notification: Performance of sort global known variables: time 8.91e-07/1.269, allocations: 0 / 1.017 GB, free: 14.71 MB / 0.4949 GB
Notification: Performance of remove unused functions: time 0.001247/1.271, allocations: 266.8 kB / 1.018 GB, free: 14.47 MB / 0.4949 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 3 (volDyn.dynBal.U,volDyn.dynBal.mXi[1],volDyn.dynBal.mC[1])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (45):
 * Single equations (assignments): 45
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0005531/1.271, allocations: 215.7 kB / 1.018 GB, free: 14.28 MB / 0.4949 GB
Notification: Performance of simCode: created initialization part: time 0.002775/1.274, allocations: 0.8445 MB / 1.019 GB, free: 13.62 MB / 0.4949 GB
Notification: Performance of simCode: created event and clocks part: time 6.41e-06/1.274, allocations: 0 / 1.019 GB, free: 13.62 MB / 0.4949 GB
Notification: Performance of simCode: created simulation system equations: time 0.001326/1.275, allocations: 423.7 kB / 1.019 GB, free: 13.3 MB / 0.4949 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003486/1.279, allocations: 0.5602 MB / 1.02 GB, free: 12.83 MB / 0.4949 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006373/1.285, allocations: 2.525 MB / 1.022 GB, free: 10.43 MB / 0.4949 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001058/1.286, allocations: 1.128 MB / 1.023 GB, free: 9.312 MB / 0.4949 GB
Notification: Performance of simCode: alias equations: time 0.001576/1.288, allocations: 263.8 kB / 1.024 GB, free: 9.051 MB / 0.4949 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003842/1.288, allocations: 74.73 kB / 1.024 GB, free: 8.98 MB / 0.4949 GB
Notification: Performance of SimCode: time 2.006e-06/1.288, allocations: 0 / 1.024 GB, free: 8.98 MB / 0.4949 GB
Notification: Performance of Templates: time 0.04758/1.336, allocations: 19.54 MB / 1.043 GB, free: 5.742 MB / 0.5105 GB