Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|pipeFriction1.port_b.m_flow|pipeFriction1.port_b.p|pipeFriction1.port_b.h_outflow|pipeFriction2.port_b.m_flow|pipeFriction2.port_b.p|pipeFriction2.port_b.h_outflow|pipeFriction3.port_b.m_flow|pipeFriction3.port_b.p|pipeFriction3.port_b.h_outflow|boundary1.ports.1..m_flow|boundary1.ports.1..p|boundary1.ports.1..h_outflow|boundary1.ports.1..Xi_outflow.1.|boundary1.ports.1..Xi_outflow.2.|boundary1.ports.1..Xi_outflow.3.|boundary1.ports.1..Xi_outflow.4.|boundary1.ports.1..Xi_outflow.5.|boundary1.ports.1..Xi_outflow.6.|boundary2.ports.1..m_flow|boundary2.ports.1..p|boundary2.ports.1..h_outflow|boundary2.ports.1..Xi_outflow.1.|boundary2.ports.1..Xi_outflow.2.|boundary2.ports.1..Xi_outflow.3.|boundary2.ports.1..Xi_outflow.4.|boundary2.ports.1..Xi_outflow.5.|boundary2.ports.1..Xi_outflow.6.|boundary3.ports.1..m_flow|boundary3.ports.1..p|boundary3.ports.1..h_outflow|boundary3.ports.1..Xi_outflow.1.|boundary3.ports.1..Xi_outflow.2.|boundary3.ports.1..Xi_outflow.3.|boundary3.ports.1..Xi_outflow.4.|boundary3.ports.1..Xi_outflow.5.|boundary3.ports.1..Xi_outflow.6.|sine1.y|sine2.y|sine3.y",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1")
translateModel(ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|pipeFriction1.port_b.m_flow|pipeFriction1.port_b.p|pipeFriction1.port_b.h_outflow|pipeFriction2.port_b.m_flow|pipeFriction2.port_b.p|pipeFriction2.port_b.h_outflow|pipeFriction3.port_b.m_flow|pipeFriction3.port_b.p|pipeFriction3.port_b.h_outflow|boundary1.ports.1..m_flow|boundary1.ports.1..p|boundary1.ports.1..h_outflow|boundary1.ports.1..Xi_outflow.1.|boundary1.ports.1..Xi_outflow.2.|boundary1.ports.1..Xi_outflow.3.|boundary1.ports.1..Xi_outflow.4.|boundary1.ports.1..Xi_outflow.5.|boundary1.ports.1..Xi_outflow.6.|boundary2.ports.1..m_flow|boundary2.ports.1..p|boundary2.ports.1..h_outflow|boundary2.ports.1..Xi_outflow.1.|boundary2.ports.1..Xi_outflow.2.|boundary2.ports.1..Xi_outflow.3.|boundary2.ports.1..Xi_outflow.4.|boundary2.ports.1..Xi_outflow.5.|boundary2.ports.1..Xi_outflow.6.|boundary3.ports.1..m_flow|boundary3.ports.1..p|boundary3.ports.1..h_outflow|boundary3.ports.1..Xi_outflow.1.|boundary3.ports.1..Xi_outflow.2.|boundary3.ports.1..Xi_outflow.3.|boundary3.ports.1..Xi_outflow.4.|boundary3.ports.1..Xi_outflow.5.|boundary3.ports.1..Xi_outflow.6.|sine1.y|sine2.y|sine3.y",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001322/0.001321, allocations: 110.5 kB / 18.46 MB, free: 4.598 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001299/0.001299, allocations: 208 kB / 19.41 MB, free: 3.652 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.337/1.337, allocations: 227.4 MB / 247.6 MB, free: 10.5 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2188/0.2188, allocations: 46.11 MB / 345 MB, free: 11.48 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001641/0.001641, allocations: 71.89 kB / 482 MB, free: 4.055 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.357/0.3586, allocations: 62.44 MB / 0.5317 GB, free: 13.59 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1): time 0.5833/0.9419, allocations: 200.7 MB / 0.7276 GB, free: 1.812 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.01767/0.9596, allocations: 14.62 MB / 0.7419 GB, free: 1.746 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001313/0.961, allocations: 36.34 kB / 0.7419 GB, free: 1.746 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.00171/0.9627, allocations: 0.5504 MB / 0.7425 GB, free: 1.734 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.007499/0.9703, allocations: 3.316 MB / 0.7457 GB, free: 1.605 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.008403/0.9787, allocations: 3.793 MB / 0.7494 GB, free: 1.504 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.003786/0.9825, allocations: 3.286 MB / 0.7526 GB, free: 1.496 MB / 0.5606 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001884/0.9844, allocations: 1.671 MB / 0.7543 GB, free: 1.332 MB / 0.5606 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00309/0.9875, allocations: 1.939 MB / 0.7561 GB, free: 1.316 MB / 0.5606 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005355/0.9929, allocations: 3.657 MB / 0.7597 GB, free: 1.297 MB / 0.5606 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003424/0.9932, allocations: 186.2 kB / 0.7599 GB, free: 1.297 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009065/1.002, allocations: 5.083 MB / 0.7649 GB, free: 1.262 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.0008493/1.003, allocations: 0.9428 MB / 0.7658 GB, free: 1.258 MB / 0.5606 GB
Notification: Performance of NFVerifyModel.verify: time 0.00117/1.004, allocations: 1.084 MB / 0.7668 GB, free: 1.258 MB / 0.5606 GB
Notification: Performance of NFConvertDAE.convert: time 0.009119/1.013, allocations: 5.895 MB / 0.7726 GB, free: 0.8086 MB / 0.5606 GB
Notification: Performance of FrontEnd - DAE generated: time 4.408e-06/1.013, allocations: 3.5 kB / 0.7726 GB, free: 0.8086 MB / 0.5606 GB
Notification: Performance of FrontEnd: time 1.963e-06/1.014, allocations: 0 / 0.7726 GB, free: 0.8086 MB / 0.5606 GB
Notification: Performance of Transformations before backend: time 7.542e-05/1.014, allocations: 0 / 0.7726 GB, free: 0.8086 MB / 0.5606 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 334
 * Number of variables: 334
Notification: Performance of Generate backend data structure: time 0.01198/1.026, allocations: 2.847 MB / 0.7754 GB, free: 15.61 MB / 0.5762 GB
Notification: Performance of prepare preOptimizeDAE: time 5.508e-05/1.026, allocations: 9.344 kB / 0.7754 GB, free: 15.61 MB / 0.5762 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003898/1.03, allocations: 488.1 kB / 0.7759 GB, free: 15.52 MB / 0.5762 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001996/1.032, allocations: 1.173 MB / 0.777 GB, free: 14.96 MB / 0.5762 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.929e-05/1.032, allocations: 91.48 kB / 0.7771 GB, free: 14.96 MB / 0.5762 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002664/1.032, allocations: 110 kB / 0.7772 GB, free: 14.96 MB / 0.5762 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004135/1.036, allocations: 2.866 MB / 0.78 GB, free: 14.12 MB / 0.5762 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.328e-05/1.036, allocations: 3.938 kB / 0.78 GB, free: 14.12 MB / 0.5762 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002299/1.036, allocations: 54 kB / 0.78 GB, free: 14.12 MB / 0.5762 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001533/1.036, allocations: 144.4 kB / 0.7802 GB, free: 14.09 MB / 0.5762 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00495/1.041, allocations: 3.532 MB / 0.7836 GB, free: 13.45 MB / 0.5762 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.01379/1.055, allocations: 8.502 MB / 0.7919 GB, free: 9.328 MB / 0.5762 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003204/1.058, allocations: 1.566 MB / 0.7935 GB, free: 8.375 MB / 0.5762 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001088/1.06, allocations: 0.8138 MB / 0.7943 GB, free: 7.863 MB / 0.5762 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001155/1.061, allocations: 0.9075 MB / 0.7951 GB, free: 6.816 MB / 0.5762 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.831e-05/1.061, allocations: 54.41 kB / 0.7952 GB, free: 6.762 MB / 0.5762 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001637/1.062, allocations: 467.3 kB / 0.7956 GB, free: 6.395 MB / 0.5762 GB
Notification: Performance of pre-optimization done (n=74): time 7.103e-06/1.062, allocations: 0 / 0.7956 GB, free: 6.395 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=74): time 0.01345/1.076, allocations: 4.295 MB / 0.7998 GB, free: 3.07 MB / 0.5762 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.031e-05/1.076, allocations: 141.2 kB / 0.8 GB, free: 2.902 MB / 0.5762 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002143/1.078, allocations: 1.682 MB / 0.8016 GB, free: 1.52 MB / 0.5762 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001528/1.078, allocations: 45.7 kB / 0.8017 GB, free: 1.469 MB / 0.5762 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005161/1.079, allocations: 0.656 MB / 0.8023 GB, free: 0.8125 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002706/1.079, allocations: 267 kB / 0.8026 GB, free: 0.5508 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005341/1.08, allocations: 270.3 kB / 0.8028 GB, free: 284 kB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.0001301/1.08, allocations: 353 kB / 0.8031 GB, free: 15.93 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001377/1.081, allocations: 0.9065 MB / 0.804 GB, free: 15.02 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001525/1.083, allocations: 1.104 MB / 0.8051 GB, free: 13.84 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002853/1.086, allocations: 1.95 MB / 0.807 GB, free: 11.85 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.552e-06/1.086, allocations: 4 kB / 0.807 GB, free: 11.85 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=124) (initialization): time 0.0045/1.09, allocations: 2.758 MB / 0.8097 GB, free: 9.102 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 2.137e-05/1.09, allocations: 6.344 kB / 0.8097 GB, free: 9.098 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.267e-05/1.09, allocations: 8 kB / 0.8097 GB, free: 9.09 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01992/1.11, allocations: 3.543 MB / 0.8132 GB, free: 5.551 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001315/1.111, allocations: 200.4 kB / 0.8134 GB, free: 5.367 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003472/1.115, allocations: 2.262 MB / 0.8156 GB, free: 3.078 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002323/1.117, allocations: 59.92 kB / 0.8156 GB, free: 3.02 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003799/1.118, allocations: 87.92 kB / 0.8157 GB, free: 2.934 MB / 0.5919 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.002398/1.12, allocations: 1.228 MB / 0.8169 GB, free: 1.703 MB / 0.5919 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001558/1.122, allocations: 1.075 MB / 0.818 GB, free: 0.5469 MB / 0.5919 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002762/1.124, allocations: 1.902 MB / 0.8198 GB, free: 14.6 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.352e-06/1.124, allocations: 4 kB / 0.8198 GB, free: 14.59 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=124) (initialization_lambda0): time 0.004403/1.129, allocations: 2.368 MB / 0.8221 GB, free: 12.25 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 1.508e-05/1.129, allocations: 8 kB / 0.8222 GB, free: 12.24 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.186e-05/1.129, allocations: 4 kB / 0.8222 GB, free: 12.23 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006679/1.13, allocations: 176.9 kB / 0.8223 GB, free: 12.06 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001424/1.131, allocations: 272.3 kB / 0.8226 GB, free: 11.8 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002582/1.134, allocations: 2.353 MB / 0.8249 GB, free: 9.328 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001552/1.135, allocations: 64 kB / 0.8249 GB, free: 9.266 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001687/1.135, allocations: 87.92 kB / 0.825 GB, free: 9.18 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 21
 * 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 (88):
 * Single equations (assignments): 87
 * 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
   {(3,34)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004648/1.136, allocations: 233.7 kB / 0.8253 GB, free: 8.953 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003419/1.136, allocations: 158.2 kB / 0.8254 GB, free: 8.797 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005335/1.142, allocations: 3.376 MB / 0.8287 GB, free: 5.422 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.012e-05/1.142, allocations: 3.984 kB / 0.8287 GB, free: 5.418 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.615e-06/1.142, allocations: 0 / 0.8287 GB, free: 5.418 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003935/1.142, allocations: 235.3 kB / 0.8289 GB, free: 5.168 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003125/1.142, allocations: 19.98 kB / 0.8289 GB, free: 5.148 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001191/1.143, allocations: 63.88 kB / 0.829 GB, free: 5.086 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001703/1.144, allocations: 48.53 kB / 0.8291 GB, free: 5.039 MB / 0.6075 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004809/1.148, allocations: 2.017 MB / 0.831 GB, free: 3.035 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.003367/1.152, allocations: 2.308 MB / 0.8333 GB, free: 0.7031 MB / 0.6075 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0009025/1.153, allocations: 0.6023 MB / 0.8339 GB, free: 104 kB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.001716/1.154, allocations: 1.2 MB / 0.835 GB, free: 14.9 MB / 0.6231 GB
Notification: Performance of Backend: time 4e-07/1.154, allocations: 0 / 0.835 GB, free: 14.9 MB / 0.6231 GB
Notification: Performance of simCode: created initialization part: time 0.01035/1.165, allocations: 4.151 MB / 0.8391 GB, free: 10.86 MB / 0.6231 GB
Notification: Performance of SimCode: time 0.006373/1.171, allocations: 3.876 MB / 0.8429 GB, free: 6.965 MB / 0.6231 GB
Notification: Performance of Templates: time 0.09808/1.269, allocations: 74.13 MB / 0.9153 GB, free: 13.16 MB / 0.7012 GB
make -j1 -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.sim & ./ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.Fluid.TestComponents.Pipes.IdealMixing1.pipe 2>&1)