Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_Chemical.Examples.HeatingOfAlcohol.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1-master/package.mo", uses=false)
Using package Chemical with version 1.4.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Chemical.Examples.HeatingOfAlcohol,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Chemical_Chemical.Examples.HeatingOfAlcohol")
translateModel(Chemical.Examples.HeatingOfAlcohol,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Chemical_Chemical.Examples.HeatingOfAlcohol")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001322/0.001322, allocations: 105.1 kB / 16.41 MB, free: 6.41 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001306/0.001306, allocations: 189.7 kB / 17.35 MB, free: 5.695 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.356/1.356, allocations: 222.9 MB / 241.1 MB, free: 15.12 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1-master/package.mo): time 0.08443/0.08443, allocations: 15.3 MB / 306.5 MB, free: 15.39 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002457/0.002457, allocations: 60.11 kB / 425.8 MB, free: 11.45 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1251/0.1276, allocations: 53.62 MB / 479.5 MB, free: 5.773 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(Chemical.Examples.HeatingOfAlcohol): time 0.005563/0.1332, allocations: 4.239 MB / 483.7 MB, free: 1.512 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01009/0.1434, allocations: 8.685 MB / 492.4 MB, free: 8.793 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005398/0.144, allocations: 4 kB / 492.4 MB, free: 8.789 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006439/0.1446, allocations: 186.5 kB / 492.6 MB, free: 8.605 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002202/0.1469, allocations: 0.7797 MB / 493.4 MB, free: 7.82 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002658/0.1495, allocations: 1.006 MB / 494.4 MB, free: 6.809 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001335/0.1509, allocations: 0.8811 MB / 495.2 MB, free: 5.922 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006799/0.1516, allocations: 0.5078 MB / 495.7 MB, free: 5.406 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0005266/0.1521, allocations: 442.8 kB / 496.2 MB, free: 4.973 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006287/0.1528, allocations: 478.4 kB / 496.6 MB, free: 4.504 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001211/0.1529, allocations: 76 kB / 496.7 MB, free: 4.43 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002756/0.1557, allocations: 0.8971 MB / 497.6 MB, free: 3.531 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001929/0.1559, allocations: 183.4 kB / 497.8 MB, free: 3.352 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003978/0.1563, allocations: 406.2 kB / 498.2 MB, free: 2.953 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003274/0.1596, allocations: 1.787 MB / 500 MB, free: 1.16 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.799e-06/0.1596, allocations: 4 kB / 500 MB, free: 1.156 MB / 366.1 MB
Notification: Performance of FrontEnd: time 3.236e-06/0.1596, allocations: 0 / 500 MB, free: 1.156 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 1.1e-05/0.1596, allocations: 3.938 kB / 500 MB, free: 1.152 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 168
 * Number of variables: 168
Notification: Performance of Generate backend data structure: time 0.002036/0.1617, allocations: 1.147 MB / 0.4894 GB, free: 15.95 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.927e-05/0.1617, allocations: 8.031 kB / 0.4894 GB, free: 15.95 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0006307/0.1623, allocations: 147.7 kB / 0.4895 GB, free: 15.8 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007199/0.1631, allocations: 463.4 kB / 0.49 GB, free: 15.31 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.314e-05/0.1631, allocations: 50.38 kB / 0.49 GB, free: 15.26 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001159/0.1633, allocations: 72.31 kB / 0.4901 GB, free: 15.19 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001203/0.1645, allocations: 0.7628 MB / 0.4908 GB, free: 14.41 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.435e-05/0.1645, allocations: 0 / 0.4908 GB, free: 14.41 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.879e-05/0.1646, allocations: 28 kB / 0.4909 GB, free: 14.39 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.733e-05/0.1646, allocations: 20 kB / 0.4909 GB, free: 14.37 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001313/0.1659, allocations: 0.8667 MB / 0.4917 GB, free: 13.5 MB / 382.1 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.003659/0.1696, allocations: 2.338 MB / 0.494 GB, free: 11.04 MB / 382.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001682/0.1713, allocations: 1.016 MB / 0.495 GB, free: 9.98 MB / 382.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004432/0.1718, allocations: 299.8 kB / 0.4953 GB, free: 9.68 MB / 382.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.004149/0.1759, allocations: 2.86 MB / 0.4981 GB, free: 6.492 MB / 382.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.61e-05/0.176, allocations: 38.56 kB / 0.4981 GB, free: 6.445 MB / 382.1 MB
Notification: Performance of pre-optimization done (n=42): time 2.034e-06/0.176, allocations: 0 / 0.4981 GB, free: 6.445 MB / 382.1 MB
Notification: Performance of matching and sorting (n=56): time 0.01478/0.1908, allocations: 2.811 MB / 0.5009 GB, free: 3.547 MB / 382.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.443e-05/0.1909, allocations: 59.97 kB / 0.5009 GB, free: 3.477 MB / 382.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.000536/0.1914, allocations: 405.7 kB / 0.5013 GB, free: 3.074 MB / 382.1 MB
Notification: Performance of collectPreVariables (initialization): time 7.729e-05/0.1915, allocations: 33.7 kB / 0.5013 GB, free: 3.035 MB / 382.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002478/0.1917, allocations: 363 kB / 0.5017 GB, free: 2.676 MB / 382.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000209/0.192, allocations: 201.1 kB / 0.5019 GB, free: 2.477 MB / 382.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000222/0.1922, allocations: 138.6 kB / 0.502 GB, free: 2.336 MB / 382.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001646/0.1924, allocations: 370.7 kB / 0.5024 GB, free: 1.965 MB / 382.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003536/0.1927, allocations: 205.9 kB / 0.5026 GB, free: 1.762 MB / 382.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005331/0.1933, allocations: 466.2 kB / 0.503 GB, free: 1.195 MB / 382.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009512/0.1942, allocations: 0.7173 MB / 0.5037 GB, free: 372 kB / 382.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.402e-06/0.1942, allocations: 4 kB / 0.5037 GB, free: 368 kB / 382.1 MB
Notification: Performance of matching and sorting (n=95) (initialization): time 0.2178/0.4121, allocations: 1.138 MB / 0.5048 GB, free: 34.97 MB / 382.1 MB
Notification: Performance of prepare postOptimizeDAE: time 3.161e-05/0.4122, allocations: 8.75 kB / 0.5048 GB, free: 34.97 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.906e-05/0.4122, allocations: 8.438 kB / 0.5048 GB, free: 34.97 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003522/0.4157, allocations: 441.2 kB / 0.5053 GB, free: 34.96 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005338/0.4163, allocations: 159.2 kB / 0.5054 GB, free: 34.96 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006626/0.4229, allocations: 4.641 MB / 0.5099 GB, free: 31.52 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005829/0.4235, allocations: 25.36 kB / 0.51 GB, free: 31.51 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.774e-05/0.4236, allocations: 43.95 kB / 0.51 GB, free: 31.5 MB / 382.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 37
 * 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 (81):
 * Single equations (assignments): 79
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * 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
   {(2,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003096/0.4239, allocations: 206 kB / 0.5102 GB, free: 31.33 MB / 382.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001442/0.4241, allocations: 56.19 kB / 0.5103 GB, free: 31.33 MB / 382.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004996/0.4291, allocations: 1.089 MB / 0.5113 GB, free: 30.97 MB / 382.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.222e-05/0.4291, allocations: 6.531 kB / 0.5113 GB, free: 30.97 MB / 382.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.616e-06/0.4291, allocations: 1.062 kB / 0.5113 GB, free: 30.97 MB / 382.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.091e-05/0.4291, allocations: 9.234 kB / 0.5113 GB, free: 30.97 MB / 382.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00589/0.435, allocations: 1.812 MB / 0.5131 GB, free: 30.1 MB / 382.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.069e-05/0.4351, allocations: 5.312 kB / 0.5131 GB, free: 30.1 MB / 382.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003472/0.4354, allocations: 89.69 kB / 0.5132 GB, free: 30.09 MB / 382.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004423/0.4399, allocations: 0.5122 MB / 0.5137 GB, free: 30 MB / 382.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.348e-05/0.44, allocations: 20.25 kB / 0.5137 GB, free: 29.99 MB / 382.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007267/0.4473, allocations: 5.965 MB / 0.5195 GB, free: 24.81 MB / 382.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.042e-06/0.4473, allocations: 2.031 kB / 0.5195 GB, free: 24.81 MB / 382.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00558/0.4529, allocations: 1.406 MB / 0.5209 GB, free: 24.16 MB / 382.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001913/0.4532, allocations: 128.1 kB / 0.521 GB, free: 24.04 MB / 382.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 8.347e-05/0.4533, allocations: 13.72 kB / 0.5211 GB, free: 24.03 MB / 382.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003361/0.4536, allocations: 13.83 kB / 0.5211 GB, free: 24.02 MB / 382.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002027/0.4538, allocations: 64.5 kB / 0.5211 GB, free: 24 MB / 382.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.103e-05/0.4539, allocations: 23.72 kB / 0.5212 GB, free: 24 MB / 382.1 MB
Notification: Performance of sorting global known variables: time 0.0003457/0.4542, allocations: 269.1 kB / 0.5214 GB, free: 23.86 MB / 382.1 MB
Notification: Performance of sort global known variables: time 3.21e-07/0.4542, allocations: 0 / 0.5214 GB, free: 23.86 MB / 382.1 MB
Notification: Performance of remove unused functions: time 0.001592/0.4558, allocations: 400 kB / 0.5218 GB, free: 23.83 MB / 382.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 8
 * Number of states: 3 (Ethanol.logn,liquidWater.amountOfBonds,liquidWater.logn)
 * 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 (48):
 * Single equations (assignments): 45
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(2,10,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,1), (2,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0009353/0.4568, allocations: 466.8 kB / 0.5222 GB, free: 23.69 MB / 382.1 MB
Notification: Performance of simCode: created initialization part: time 0.001713/0.4585, allocations: 0.8981 MB / 0.5231 GB, free: 23.21 MB / 382.1 MB
Notification: Performance of simCode: created event and clocks part: time 5.992e-06/0.4585, allocations: 1.75 kB / 0.5231 GB, free: 23.21 MB / 382.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.001105/0.4596, allocations: 0.7302 MB / 0.5238 GB, free: 22.73 MB / 382.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008793/0.4605, allocations: 151.8 kB / 0.524 GB, free: 22.66 MB / 382.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002222/0.4628, allocations: 1.346 MB / 0.5253 GB, free: 21.89 MB / 382.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000421/0.4632, allocations: 0.8982 MB / 0.5262 GB, free: 21.18 MB / 382.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000283/0.4635, allocations: 124.6 kB / 0.5263 GB, free: 21.16 MB / 382.1 MB
Notification: Performance of SimCode: time 8.22e-07/0.4635, allocations: 0 / 0.5263 GB, free: 21.16 MB / 382.1 MB
Notification: Performance of Templates: time 0.01865/0.4822, allocations: 17.33 MB / 0.5432 GB, free: 10.06 MB / 382.1 MB
make -j1 -f Chemical_Chemical.Examples.HeatingOfAlcohol.makefile
(rm -f Chemical_Chemical.Examples.HeatingOfAlcohol.pipe ; mkfifo Chemical_Chemical.Examples.HeatingOfAlcohol.pipe ; head -c 1048576 < Chemical_Chemical.Examples.HeatingOfAlcohol.pipe >> ../files/Chemical_Chemical.Examples.HeatingOfAlcohol.sim & ./Chemical_Chemical.Examples.HeatingOfAlcohol  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Chemical_Chemical.Examples.HeatingOfAlcohol.pipe 2>&1)