Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001833/0.001833, allocations: 95.92 kB / 19.7 MB, free: 2.129 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.002224/0.002224, allocations: 211.9 kB / 23 MB, free: 4.934 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.448/1.448, allocations: 230.6 MB / 256.8 MB, free: 7.766 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical master/package.mo): time 0.138/0.138, allocations: 23.91 MB / 337.1 MB, free: 10.91 MB / 270.1 MB
"
[Timeout remaining time 180]
Using package Chemical with version 2.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical master/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation")
translateModel(Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation,tolerance=1e-05,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation") [Timeout 660]
"Notification: Chemical requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002072/0.002072, allocations: 75.88 kB / 472.5 MB, free: 6.895 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3215/0.3235, allocations: 59.87 MB / 0.5199 GB, free: 9.789 MB / 382.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical master/Obsolete.mo:14335:7-14335:66:writable] Warning: An inner declaration for outer component system could not be found and was automatically generated.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical master/Obsolete.mo:14335:7-14335:66:writable] Notification: The diagnostics message for the missing inner is: 
Your model is using an outer \"system\" component but
an inner \"system\" component is not defined.
For simulation drag Modelica.Fluid.System into your model
to specify system properties.
Notification: Performance of NFInst.instantiate(Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation): time 0.08521/0.4087, allocations: 39.9 MB / 0.5588 GB, free: 0.7852 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.08564/0.4944, allocations: 64.1 MB / 0.6214 GB, free: 1.527 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004318/0.4987, allocations: 79.38 kB / 0.6215 GB, free: 1.449 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004353/0.5031, allocations: 1.473 MB / 0.6229 GB, free: 15.96 MB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01723/0.5203, allocations: 5.994 MB / 0.6288 GB, free: 9.926 MB / 478.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0258/0.5461, allocations: 10.39 MB / 0.6389 GB, free: 15.48 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02839/0.5745, allocations: 26.67 MB / 0.665 GB, free: 4.719 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02058/0.5951, allocations: 16.27 MB / 0.6809 GB, free: 4.328 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02698/0.622, allocations: 17.81 MB / 0.6983 GB, free: 2.484 MB / 0.5294 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02971/0.6517, allocations: 16.73 MB / 0.7146 GB, free: 1.707 MB / 0.545 GB
Notification: Performance of NFPackage.collectConstants: time 0.009864/0.6616, allocations: 3.133 MB / 0.7177 GB, free: 14.57 MB / 0.5606 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02965/0.6913, allocations: 10.53 MB / 0.728 GB, free: 4.027 MB / 0.5606 GB
Notification: Performance of NFScalarize.scalarize: time 0.4564/1.148, allocations: 7.059 MB / 0.7349 GB, free: 51.24 MB / 0.5607 GB
Notification: Performance of NFVerifyModel.verify: time 0.02817/1.176, allocations: 10.51 MB / 0.7451 GB, free: 51.24 MB / 0.5607 GB
Notification: Performance of NFConvertDAE.convert: time 0.07046/1.246, allocations: 44.91 MB / 0.789 GB, free: 47.35 MB / 0.5607 GB
Notification: Performance of FrontEnd - DAE generated: time 7.645e-06/1.246, allocations: 0 / 0.789 GB, free: 47.35 MB / 0.5607 GB
Notification: Performance of FrontEnd: time 1.764e-06/1.246, allocations: 0 / 0.789 GB, free: 47.35 MB / 0.5607 GB
Notification: Performance of Transformations before backend: time 0.001021/1.247, allocations: 0 / 0.789 GB, free: 47.35 MB / 0.5607 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3808
 * Number of variables: 3808
Notification: Performance of Generate backend data structure: time 0.08398/1.331, allocations: 23.66 MB / 0.8121 GB, free: 34.7 MB / 0.5607 GB
Notification: Performance of prepare preOptimizeDAE: time 4.87e-05/1.331, allocations: 11.56 kB / 0.8121 GB, free: 34.7 MB / 0.5607 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02714/1.359, allocations: 3.971 MB / 0.816 GB, free: 32.93 MB / 0.5607 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03246/1.391, allocations: 12.63 MB / 0.8283 GB, free: 26.34 MB / 0.5607 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001712/1.393, allocations: 3.069 MB / 0.8313 GB, free: 23.51 MB / 0.5607 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01099/1.404, allocations: 1.536 MB / 0.8328 GB, free: 22.95 MB / 0.5607 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08405/1.488, allocations: 35.25 MB / 0.8672 GB, free: 11.96 MB / 0.5763 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0006041/1.488, allocations: 7.375 kB / 0.8672 GB, free: 11.95 MB / 0.5763 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.009715/1.498, allocations: 0.5898 MB / 0.8678 GB, free: 11.36 MB / 0.5763 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003318/1.498, allocations: 361.2 kB / 0.8682 GB, free: 11.01 MB / 0.5763 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1031/1.601, allocations: 47.47 MB / 0.9145 GB, free: 11.55 MB / 0.6232 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.5455/2.147, allocations: 116.5 MB / 1.028 GB, free: 189.8 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.5725/2.719, allocations: 218.4 MB / 1.242 GB, free: 17.35 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.2747/2.994, allocations: 18.49 MB / 1.26 GB, free: 231.3 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1559/3.15, allocations: 76.47 MB / 1.334 GB, free: 202.9 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.653e-05/3.15, allocations: 135.5 kB / 1.334 GB, free: 202.8 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02973/3.18, allocations: 6.286 MB / 1.341 GB, free: 199.8 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=995): time 5.269e-05/3.18, allocations: 0 / 1.341 GB, free: 199.8 MB / 0.67 GB
Notification: Performance of matching and sorting (n=1129): time 0.7357/3.916, allocations: 135.7 MB / 1.473 GB, free: 65.65 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.035e-05/3.916, allocations: 176.2 kB / 1.473 GB, free: 65.46 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0137/3.929, allocations: 9.746 MB / 1.483 GB, free: 55.75 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.003963/3.933, allocations: 209.7 kB / 1.483 GB, free: 55.54 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003637/3.937, allocations: 7.121 MB / 1.49 GB, free: 48.41 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00632/3.943, allocations: 4.111 MB / 1.494 GB, free: 44.36 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.008387/3.952, allocations: 3.269 MB / 1.497 GB, free: 41.07 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 4.363e-05/3.952, allocations: 301.1 kB / 1.497 GB, free: 40.78 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01808/3.97, allocations: 11.84 MB / 1.509 GB, free: 28.93 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.2796/4.249, allocations: 16.57 MB / 1.525 GB, free: 208 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05191/4.301, allocations: 29.76 MB / 1.554 GB, free: 198.1 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002468/4.302, allocations: 42.91 kB / 1.554 GB, free: 198.1 MB / 0.67 GB
Notification: Performance of matching and sorting (n=1889) (initialization): time 0.4555/4.757, allocations: 81.38 MB / 1.634 GB, free: 159.2 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001841/4.757, allocations: 68 kB / 1.634 GB, free: 159.1 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002939/4.758, allocations: 148 kB / 1.634 GB, free: 159 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06042/4.818, allocations: 22.38 MB / 1.656 GB, free: 136.8 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01741/4.835, allocations: 3.73 MB / 1.659 GB, free: 133.1 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1959/5.031, allocations: 92.35 MB / 1.75 GB, free: 40.47 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01958/5.051, allocations: 0.8313 MB / 1.75 GB, free: 39.64 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005983/5.057, allocations: 0.9328 MB / 1.751 GB, free: 38.7 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 677
 * 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 (1841):
 * Single equations (assignments): 1838
 * 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): 2 systems
   {(1,3,100.0%), (33,10,82.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.01654/5.073, allocations: 5.376 MB / 1.757 GB, free: 33.4 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006667/5.08, allocations: 1.058 MB / 1.758 GB, free: 32.93 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.5927/5.673, allocations: 48.42 MB / 1.805 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0005937/5.673, allocations: 59.02 kB / 1.805 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.15e-05/5.673, allocations: 0.5625 kB / 1.805 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0162/5.69, allocations: 3.376 MB / 1.808 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.008318/5.698, allocations: 293.3 kB / 1.809 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01569/5.714, allocations: 0.5312 MB / 1.809 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0156/5.729, allocations: 3.655 MB / 1.813 GB, free: 196.7 MB / 0.67 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.06866/5.798, allocations: 25.61 MB / 1.838 GB, free: 191.5 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.06717/5.865, allocations: 38.11 MB / 1.875 GB, free: 175 MB / 0.67 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01663/5.882, allocations: 10.54 MB / 1.885 GB, free: 166.1 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.01206/5.894, allocations: 6.477 MB / 1.891 GB, free: 159.6 MB / 0.67 GB
Notification: Performance of Backend: time 5.81e-07/5.894, allocations: 4 kB / 1.891 GB, free: 159.6 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.07178/5.965, allocations: 21.15 MB / 1.912 GB, free: 139.9 MB / 0.67 GB
Notification: Performance of SimCode: time 0.05545/6.021, allocations: 30.08 MB / 1.942 GB, free: 110 MB / 0.67 GB
Notification: Performance of Templates: time 0.6493/6.67, allocations: 361.9 MB / 2.295 GB, free: 14.04 MB / 0.67 GB
"
[Timeout remaining time 653]
make -j1 -f Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.makefile [Timeout 660]
(rm -f Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.pipe ; mkfifo Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.pipe ; head -c 1048576 < Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.pipe >> ../files/Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.sim & ./Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Chemical_master_Chemical.Obsolete.Examples.Hemoglobin.HemoglobinCarboxylation.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 25.002960690006148]
Failed to read output from testmodel.py, exit status != 0:
6.6791185199981555 6.863601113 5.614315885
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbe063f2f50>