Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.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.Hemoglobin.Allosteric_Hemoglobin2_MWC,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC")
translateModel(Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001047/0.001047, allocations: 109.8 kB / 16.42 MB, free: 6.512 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.001245/0.001245, allocations: 187.9 kB / 17.35 MB, free: 5.738 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.275/1.275, allocations: 222.9 MB / 241.1 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1-master/package.mo): time 0.07573/0.07573, allocations: 15.32 MB / 306.5 MB, free: 15.41 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001178/0.001178, allocations: 60.12 kB / 425.9 MB, free: 11.44 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08142/0.08261, allocations: 53.61 MB / 479.5 MB, free: 5.777 MB / 350.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.1-master/package.mo:4740:7-4740: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 1.4.1-master/package.mo:4740:7-4740: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.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC): time 0.03675/0.1194, allocations: 29.19 MB / 0.4968 GB, free: 8.648 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.2066/0.326, allocations: 26.06 MB / 0.5222 GB, free: 15.08 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001247/0.3273, allocations: 28 kB / 0.5222 GB, free: 15.07 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001431/0.3287, allocations: 0.602 MB / 0.5228 GB, free: 14.73 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004962/0.3337, allocations: 2.144 MB / 0.5249 GB, free: 13.56 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.008404/0.3421, allocations: 3.513 MB / 0.5284 GB, free: 11.65 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007216/0.3493, allocations: 7.398 MB / 0.5356 GB, free: 8.168 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006/0.3553, allocations: 5.919 MB / 0.5414 GB, free: 5.082 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005562/0.3609, allocations: 4.88 MB / 0.5461 GB, free: 2.535 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006671/0.3676, allocations: 4.616 MB / 0.5506 GB, free: 13.91 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.00205/0.3697, allocations: 0.6706 MB / 0.5513 GB, free: 13.24 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01116/0.3808, allocations: 3.328 MB / 0.5545 GB, free: 9.906 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002804/0.3837, allocations: 1.784 MB / 0.5563 GB, free: 8.117 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.005925/0.3896, allocations: 3.342 MB / 0.5595 GB, free: 4.762 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01938/0.409, allocations: 11.11 MB / 0.5704 GB, free: 9.621 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.221e-06/0.409, allocations: 0 / 0.5704 GB, free: 9.621 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.334e-06/0.409, allocations: 0 / 0.5704 GB, free: 9.621 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0003995/0.4094, allocations: 0 / 0.5704 GB, free: 9.621 MB / 414.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1226
 * Number of variables: 1226
Notification: Performance of Generate backend data structure: time 0.01985/0.4293, allocations: 7.424 MB / 0.5776 GB, free: 2.199 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.971e-05/0.4294, allocations: 8.031 kB / 0.5776 GB, free: 2.191 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006402/0.4358, allocations: 1.213 MB / 0.5788 GB, free: 0.9766 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006197/0.442, allocations: 3.22 MB / 0.582 GB, free: 13.69 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004858/0.4425, allocations: 0.6804 MB / 0.5826 GB, free: 12.94 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00106/0.4435, allocations: 0.4923 MB / 0.5831 GB, free: 12.45 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01475/0.4583, allocations: 9.362 MB / 0.5923 GB, free: 2.984 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.059e-05/0.4584, allocations: 10.64 kB / 0.5923 GB, free: 2.977 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006588/0.4591, allocations: 196 kB / 0.5925 GB, free: 2.785 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.476e-05/0.4591, allocations: 116 kB / 0.5926 GB, free: 2.672 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01887/0.478, allocations: 12.59 MB / 0.6049 GB, free: 6.062 MB / 446.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.04936/0.5274, allocations: 30.16 MB / 0.6343 GB, free: 7.238 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.05961/0.587, allocations: 41.27 MB / 0.6746 GB, free: 11.43 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008021/0.5951, allocations: 5.068 MB / 0.6796 GB, free: 6.328 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.03237/0.6275, allocations: 20.9 MB / 0.7 GB, free: 496 kB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.487e-05/0.6276, allocations: 67.8 kB / 0.7001 GB, free: 420 kB / 0.5294 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003259/0.6308, allocations: 0.9591 MB / 0.701 GB, free: 15.45 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=324): time 6.933e-06/0.6309, allocations: 3.938 kB / 0.701 GB, free: 15.45 MB / 0.545 GB
Notification: Performance of matching and sorting (n=376): time 0.2901/0.921, allocations: 24.4 MB / 0.7248 GB, free: 186.9 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.458e-05/0.9211, allocations: 89.3 kB / 0.7249 GB, free: 186.8 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003734/0.9248, allocations: 2.959 MB / 0.7278 GB, free: 185.8 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006559/0.9255, allocations: 90.45 kB / 0.7279 GB, free: 185.7 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009799/0.9265, allocations: 2.159 MB / 0.73 GB, free: 184.1 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008915/0.9274, allocations: 1.297 MB / 0.7313 GB, free: 183.1 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001322/0.9287, allocations: 0.9742 MB / 0.7322 GB, free: 182.7 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 3.881e-05/0.9287, allocations: 304.2 kB / 0.7325 GB, free: 182.4 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003963/0.9327, allocations: 2.957 MB / 0.7354 GB, free: 181.4 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004258/0.937, allocations: 4.48 MB / 0.7398 GB, free: 178.6 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008339/0.9453, allocations: 7.811 MB / 0.7474 GB, free: 174.1 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.207e-05/0.9454, allocations: 14.16 kB / 0.7474 GB, free: 174.1 MB / 0.545 GB
Notification: Performance of matching and sorting (n=617) (initialization): time 0.05574/1.001, allocations: 13.82 MB / 0.7609 GB, free: 166.8 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 3.881e-05/1.001, allocations: 30.2 kB / 0.7609 GB, free: 166.8 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.691e-05/1.001, allocations: 48.66 kB / 0.761 GB, free: 166.8 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008042/1.009, allocations: 3.467 MB / 0.7644 GB, free: 163.9 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00476/1.014, allocations: 1.274 MB / 0.7656 GB, free: 162.6 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02842/1.042, allocations: 18.47 MB / 0.7836 GB, free: 143.8 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00409/1.047, allocations: 262.3 kB / 0.7839 GB, free: 143.6 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003852/1.047, allocations: 279.8 kB / 0.7842 GB, free: 143.3 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 218
 * 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 (599):
 * Single equations (assignments): 596
 * 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
   {(9,4,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.001998/1.049, allocations: 1.451 MB / 0.7856 GB, free: 141.8 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005824/1.05, allocations: 347.4 kB / 0.7859 GB, free: 141.5 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03383/1.083, allocations: 9.668 MB / 0.7954 GB, free: 131.9 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.354e-05/1.083, allocations: 19.92 kB / 0.7954 GB, free: 131.9 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.414e-06/1.083, allocations: 4 kB / 0.7954 GB, free: 131.9 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001377/1.085, allocations: 0.9245 MB / 0.7963 GB, free: 130.9 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005596/1.085, allocations: 87.97 kB / 0.7964 GB, free: 130.8 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00217/1.088, allocations: 167.8 kB / 0.7965 GB, free: 130.7 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001657/1.089, allocations: 0.8221 MB / 0.7973 GB, free: 129.9 MB / 0.545 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01234/1.102, allocations: 6.899 MB / 0.8041 GB, free: 123 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01067/1.112, allocations: 9.885 MB / 0.8137 GB, free: 113.1 MB / 0.545 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00293/1.115, allocations: 2.984 MB / 0.8166 GB, free: 110.1 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.001943/1.117, allocations: 1.989 MB / 0.8186 GB, free: 108.1 MB / 0.545 GB
Notification: Performance of Backend: time 2.51e-07/1.117, allocations: 0 / 0.8186 GB, free: 108.1 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01194/1.129, allocations: 6.521 MB / 0.8249 GB, free: 101.9 MB / 0.545 GB
Notification: Performance of SimCode: time 0.009397/1.139, allocations: 9.042 MB / 0.8338 GB, free: 92.93 MB / 0.545 GB
Notification: Performance of Templates: time 0.2822/1.421, allocations: 95.9 MB / 0.9274 GB, free: 179.1 MB / 0.545 GB
make -j1 -f Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.makefile
(rm -f Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.pipe ; mkfifo Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.pipe ; head -c 1048576 < Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.pipe >> ../files/Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.sim & ./Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.pipe 2>&1)