Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.conf.json
Using package ModelicaTest with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo)
Using package ModelicaServices with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo)
Running command: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volume.medium.T|volume.medium.Xi.1.|volume.medium.p",fileNamePrefix="ModelicaTest_3.2.2_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.004182/0.004187, allocations: 371.5 kB / 15.96 MB, free: 6.199 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 3.943/3.943, allocations: 388.9 MB / 405.6 MB, free: 9.648 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.003553/0.003553, allocations: 227.7 kB / 451 MB, free: 145 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.525/0.525, allocations: 80.03 MB / 0.5627 GB, free: 19.77 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0007094/0.0007096, allocations: 8.719 kB / 0.6265 GB, free: 96.52 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1099/0.1106, allocations: 54.87 MB / 0.6801 GB, free: 41.65 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir): time 0.6593/0.77, allocations: 179.1 MB / 0.8551 GB, free: 8.969 MB / 442.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02491/0.7949, allocations: 9.565 MB / 0.8644 GB, free: 15.38 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001424/0.7964, allocations: 19.81 kB / 0.8644 GB, free: 15.36 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002009/0.7985, allocations: 314 kB / 0.8647 GB, free: 15.05 MB / 458.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:826:36-827:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:824:36-825:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:772:36-773:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:770:36-771:54:writable] Warning: 'each' used when modifying non-array element m_flow.
Notification: Performance of NFTyping.typeBindings: time 0.0131/0.8116, allocations: 2.136 MB / 0.8668 GB, free: 12.91 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01297/0.8246, allocations: 1.961 MB / 0.8687 GB, free: 10.96 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.006535/0.8312, allocations: 2.636 MB / 0.8713 GB, free: 8.32 MB / 458.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001739/0.833, allocations: 494 kB / 0.8718 GB, free: 7.828 MB / 458.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008591/0.8416, allocations: 1.664 MB / 0.8734 GB, free: 6.164 MB / 458.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003035/0.8447, allocations: 0.8567 MB / 0.8742 GB, free: 5.305 MB / 458.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003329/0.845, allocations: 51.94 kB / 0.8743 GB, free: 5.254 MB / 458.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.02045/0.8655, allocations: 4.668 MB / 0.8788 GB, free: 0.5781 MB / 458.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001284/0.8669, allocations: 375 kB / 0.8792 GB, free: 216 kB / 458.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006872/0.8676, allocations: 209.3 kB / 0.8794 GB, free: 0 / 458.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.009865/0.8775, allocations: 2.651 MB / 0.882 GB, free: 13.34 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 9.107e-06/0.8776, allocations: 0 / 0.882 GB, free: 13.34 MB / 474.7 MB
Notification: Performance of FrontEnd: time 3.306e-06/0.8776, allocations: 0 / 0.882 GB, free: 13.34 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 3.287e-05/0.8776, allocations: 0 / 0.882 GB, free: 13.34 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 186
 * Number of variables: 186
Notification: Performance of Generate backend data structure: time 0.008931/0.8866, allocations: 1.614 MB / 0.8836 GB, free: 11.68 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 9.159e-05/0.8867, allocations: 8.828 kB / 0.8836 GB, free: 11.67 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001891/0.8887, allocations: 110.9 kB / 0.8837 GB, free: 11.56 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001699/0.8904, allocations: 0.5862 MB / 0.8842 GB, free: 10.93 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001771/0.8906, allocations: 117.4 kB / 0.8843 GB, free: 10.81 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002188/0.8908, allocations: 75.98 kB / 0.8844 GB, free: 10.73 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003071/0.8939, allocations: 1.158 MB / 0.8856 GB, free: 9.531 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.91e-05/0.894, allocations: 7.906 kB / 0.8856 GB, free: 9.523 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001571/0.8942, allocations: 31.98 kB / 0.8856 GB, free: 9.492 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001329/0.8943, allocations: 61.97 kB / 0.8857 GB, free: 9.434 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00321/0.8975, allocations: 1.167 MB / 0.8868 GB, free: 8.262 MB / 474.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.01082/0.9084, allocations: 4.962 MB / 0.8916 GB, free: 2.957 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002824/0.9113, allocations: 0.783 MB / 0.8924 GB, free: 2.164 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001119/0.9125, allocations: 394.3 kB / 0.8928 GB, free: 1.777 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.3003/1.213, allocations: 1.888 MB / 0.8946 GB, free: 170.6 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.875e-05/1.213, allocations: 43.64 kB / 0.8947 GB, free: 170.5 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=69): time 4.148e-06/1.213, allocations: 0.6562 kB / 0.8947 GB, free: 170.5 MB / 474.7 MB
Notification: Performance of matching and sorting (n=81): time 0.02551/1.238, allocations: 4.032 MB / 0.8986 GB, free: 169.9 MB / 474.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.922e-05/1.239, allocations: 112.3 kB / 0.8987 GB, free: 169.7 MB / 474.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001976/1.241, allocations: 0.6195 MB / 0.8993 GB, free: 169.5 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003829/1.241, allocations: 44.86 kB / 0.8994 GB, free: 169.4 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000676/1.242, allocations: 398.8 kB / 0.8997 GB, free: 169.1 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0006198/1.242, allocations: 288.5 kB / 0.9 GB, free: 168.9 MB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001615/1.243, allocations: 20.81 kB / 0.9 GB, free: 168.9 MB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002442/1.243, allocations: 390.7 kB / 0.9004 GB, free: 168.5 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003122/1.246, allocations: 476.9 kB / 0.9009 GB, free: 168.4 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003229/1.249, allocations: 0.7911 MB / 0.9016 GB, free: 167.8 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002412/1.252, allocations: 0.5863 MB / 0.9022 GB, free: 167.6 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.442e-05/1.252, allocations: 4.109 kB / 0.9022 GB, free: 167.6 MB / 474.7 MB
Notification: Performance of matching and sorting (n=129) (initialization): time 0.00623/1.258, allocations: 1.673 MB / 0.9038 GB, free: 167 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.234e-05/1.258, allocations: 14.12 kB / 0.9039 GB, free: 167 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.857e-05/1.258, allocations: 9.344 kB / 0.9039 GB, free: 167 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01859/1.277, allocations: 1.379 MB / 0.9052 GB, free: 166.8 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001715/1.278, allocations: 172.4 kB / 0.9054 GB, free: 166.8 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005951/1.284, allocations: 1.505 MB / 0.9068 GB, free: 165.9 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002491/1.287, allocations: 84.38 kB / 0.9069 GB, free: 165.9 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003156/1.287, allocations: 62.88 kB / 0.907 GB, free: 165.9 MB / 474.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * Number of states: 0 ()
 * Number of discrete variables: 4 (volume.regularFlow[1],volume.regularFlow[2],volume.inFlow[2],volume.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (95):
 * Single equations (assignments): 94
 * 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: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {3 32}
Notification: Performance of prepare postOptimizeDAE: time 0.001078/1.288, allocations: 305.1 kB / 0.9073 GB, free: 165.7 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004/1.289, allocations: 92.94 kB / 0.9074 GB, free: 165.6 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.007836/1.297, allocations: 1.604 MB / 0.9089 GB, free: 165 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.63e-05/1.297, allocations: 3.984 kB / 0.9089 GB, free: 165 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.203e-06/1.297, allocations: 0.7969 kB / 0.9089 GB, free: 165 MB / 474.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001065/1.298, allocations: 283.2 kB / 0.9092 GB, free: 164.8 MB / 474.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003835/1.298, allocations: 24.14 kB / 0.9092 GB, free: 164.8 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001902/1.3, allocations: 73.17 kB / 0.9093 GB, free: 164.7 MB / 474.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003965/1.301, allocations: 58.34 kB / 0.9094 GB, free: 164.7 MB / 474.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01204/1.313, allocations: 1.677 MB / 0.911 GB, free: 164.2 MB / 474.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.008577/1.321, allocations: 1.887 MB / 0.9128 GB, free: 163.3 MB / 474.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002453/1.324, allocations: 0.5211 MB / 0.9133 GB, free: 163.1 MB / 474.7 MB
Notification: Performance of sorting global known variables: time 0.00136/1.325, allocations: 454 kB / 0.9138 GB, free: 163 MB / 474.7 MB
Notification: Performance of Backend: time 1.162e-06/1.325, allocations: 0 / 0.9138 GB, free: 163 MB / 474.7 MB
Notification: Performance of simCode: created initialization part: time 0.01352/1.339, allocations: 2.133 MB / 0.9159 GB, free: 162.5 MB / 474.7 MB
Notification: Performance of SimCode: time 0.005911/1.345, allocations: 2.649 MB / 0.9184 GB, free: 160.6 MB / 474.7 MB
Notification: Performance of Templates: time 0.1036/1.448, allocations: 39.1 MB / 0.9566 GB, free: 123.5 MB / 474.7 MB