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.0/package.mo", uses=false)
Using package Chemical with version 1.4.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.0/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: "" <> buildModelFMU(Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC,fileNamePrefix="Chemical_Chemical_Examples_Hemoglobin_Allosteric_Hemoglobin2_MWC",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC,fileNamePrefix="Chemical_Chemical_Examples_Hemoglobin_Allosteric_Hemoglobin2_MWC",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008596/0.0008595, allocations: 99.78 kB / 15.05 MB, free: 1.105 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.0008694/0.0008694, allocations: 177.3 kB / 15.95 MB, free: 216 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.284/1.284, allocations: 223.2 MB / 239.9 MB, free: 5.434 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.0/package.mo): time 0.1915/0.1915, allocations: 15.25 MB / 306.2 MB, free: 15.73 MB / 254.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.000283/0.0002831, allocations: 8 kB / 371.5 MB, free: 3.906 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.253/0.2533, allocations: 54.62 MB / 426.1 MB, free: 39.8 MB / 350.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 1.4.0/package.mo:3011:6-3011:65: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.0/package.mo:3011:6-3011:65: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.006979/0.2603, allocations: 8.646 MB / 434.7 MB, free: 36.84 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01876/0.2791, allocations: 22.05 MB / 456.8 MB, free: 21.03 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001446/0.2806, allocations: 38.75 kB / 456.8 MB, free: 21.01 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001265/0.2818, allocations: 0.5414 MB / 457.3 MB, free: 20.72 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004747/0.2866, allocations: 2.113 MB / 459.5 MB, free: 19.32 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.007451/0.2941, allocations: 3.131 MB / 462.6 MB, free: 17.42 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0052/0.2993, allocations: 4.72 MB / 467.3 MB, free: 14.93 MB / 350.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.005074/0.3044, allocations: 4.38 MB / 471.7 MB, free: 13.34 MB / 350.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.007541/0.312, allocations: 4.196 MB / 475.9 MB, free: 11.29 MB / 350.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.009458/0.3215, allocations: 3.845 MB / 479.7 MB, free: 9.977 MB / 350.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003363/0.3249, allocations: 396.8 kB / 480.1 MB, free: 9.977 MB / 350.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01323/0.3381, allocations: 3.387 MB / 483.5 MB, free: 9.402 MB / 350.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003553/0.3417, allocations: 1.375 MB / 484.9 MB, free: 8.723 MB / 350.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002971/0.3447, allocations: 0.9522 MB / 485.8 MB, free: 8.258 MB / 350.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0122/0.3569, allocations: 7.458 MB / 493.3 MB, free: 2.816 MB / 350.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.897e-06/0.357, allocations: 0 / 493.3 MB, free: 2.816 MB / 350.1 MB
Notification: Performance of FrontEnd: time 1.913e-06/0.357, allocations: 0 / 493.3 MB, free: 2.816 MB / 350.1 MB
Notification: Performance of Transformations before backend: time 0.0002921/0.3573, allocations: 0 / 493.3 MB, free: 2.816 MB / 350.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.01674/0.374, allocations: 6.074 MB / 499.4 MB, free: 12.66 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.589e-05/0.3741, allocations: 12.03 kB / 499.4 MB, free: 12.65 MB / 366.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002395/0.3765, allocations: 0.6654 MB / 0.4883 GB, free: 11.98 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006661/0.3832, allocations: 1.208 MB / 0.4895 GB, free: 10.76 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.007448/0.3907, allocations: 2.876 MB / 0.4923 GB, free: 7.809 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006474/0.3913, allocations: 0.6721 MB / 0.493 GB, free: 7.066 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002082/0.3934, allocations: 499.8 kB / 0.4934 GB, free: 6.578 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02005/0.4135, allocations: 9.298 MB / 0.5025 GB, free: 13.17 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001532/0.4137, allocations: 3.984 kB / 0.5025 GB, free: 13.16 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001414/0.4151, allocations: 196 kB / 0.5027 GB, free: 12.97 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001059/0.4152, allocations: 120 kB / 0.5028 GB, free: 12.86 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02408/0.4393, allocations: 12.56 MB / 0.5151 GB, free: 280 kB / 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.06659/0.5059, allocations: 29.85 MB / 0.5442 GB, free: 1.723 MB / 414.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.06036/0.5663, allocations: 41.25 MB / 0.5845 GB, free: 5.926 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.2376/0.804, allocations: 20.68 MB / 0.6047 GB, free: 131.8 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.198e-05/0.8041, allocations: 64.2 kB / 0.6048 GB, free: 131.7 MB / 462.1 MB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003575/0.8077, allocations: 0.9601 MB / 0.6057 GB, free: 131.4 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=324): time 1.296e-05/0.8077, allocations: 2.438 kB / 0.6057 GB, free: 131.4 MB / 462.1 MB
Notification: Performance of matching and sorting (n=376): time 0.09333/0.901, allocations: 24.44 MB / 0.6296 GB, free: 124.1 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.079e-05/0.9011, allocations: 92.89 kB / 0.6297 GB, free: 124 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004467/0.9056, allocations: 2.935 MB / 0.6325 GB, free: 122.6 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0009921/0.9066, allocations: 87.11 kB / 0.6326 GB, free: 122.5 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001286/0.9079, allocations: 2.162 MB / 0.6347 GB, free: 120.6 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001757/0.9097, allocations: 1.297 MB / 0.636 GB, free: 119.5 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000331/0.91, allocations: 94.41 kB / 0.6361 GB, free: 119.5 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0002308/0.9103, allocations: 0.4928 MB / 0.6366 GB, free: 119 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004503/0.9148, allocations: 2.942 MB / 0.6394 GB, free: 117.5 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00492/0.9197, allocations: 4.447 MB / 0.6438 GB, free: 113.9 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004885/0.9246, allocations: 3.567 MB / 0.6473 GB, free: 110.3 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.125e-05/0.9247, allocations: 16 kB / 0.6473 GB, free: 110.3 MB / 462.1 MB
Notification: Performance of matching and sorting (n=617) (initialization): time 0.0526/0.9773, allocations: 13.8 MB / 0.6608 GB, free: 96.46 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 4.98e-05/0.9774, allocations: 30.19 kB / 0.6608 GB, free: 96.43 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.081e-05/0.9774, allocations: 48 kB / 0.6608 GB, free: 96.39 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008743/0.9862, allocations: 3.492 MB / 0.6642 GB, free: 92.91 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006095/0.9924, allocations: 1.271 MB / 0.6655 GB, free: 91.64 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02797/1.02, allocations: 17.55 MB / 0.6826 GB, free: 73.7 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004699/1.025, allocations: 367.4 kB / 0.683 GB, free: 73.34 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005289/1.026, allocations: 279.8 kB / 0.6832 GB, free: 73.06 MB / 462.1 MB
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 (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(9,100.0%) 4,(1,100.0%) 3}
 * Non-linear torn systems: 1 {1 3}
Notification: Performance of prepare postOptimizeDAE: time 0.002436/1.028, allocations: 1.445 MB / 0.6847 GB, free: 71.61 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006215/1.029, allocations: 351.5 kB / 0.685 GB, free: 71.26 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03285/1.062, allocations: 9.67 MB / 0.6944 GB, free: 61.56 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.892e-05/1.062, allocations: 36 kB / 0.6945 GB, free: 61.52 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.517e-05/1.062, allocations: 8 kB / 0.6945 GB, free: 61.52 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001019/1.062, allocations: 19.92 kB / 0.6945 GB, free: 61.5 MB / 462.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04154/1.103, allocations: 15.4 MB / 0.7095 GB, free: 46.05 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.256e-05/1.103, allocations: 7.953 kB / 0.7095 GB, free: 46.05 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004375/1.108, allocations: 0.8507 MB / 0.7104 GB, free: 45.2 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005693/1.114, allocations: 2.136 MB / 0.7125 GB, free: 43.05 MB / 462.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007951/1.114, allocations: 139.9 kB / 0.7126 GB, free: 42.91 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01961/1.134, allocations: 11.96 MB / 0.7243 GB, free: 30.56 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.208e-06/1.134, allocations: 4 kB / 0.7243 GB, free: 30.56 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03827/1.172, allocations: 13.25 MB / 0.7372 GB, free: 17.08 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.003464/1.176, allocations: 0.9257 MB / 0.7381 GB, free: 16.13 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001326/1.177, allocations: 95.97 kB / 0.7382 GB, free: 16.04 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003465/1.181, allocations: 255.4 kB / 0.7385 GB, free: 15.79 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001212/1.182, allocations: 462.6 kB / 0.7389 GB, free: 15.34 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004449/1.182, allocations: 145 kB / 0.739 GB, free: 15.2 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.002558/1.185, allocations: 1.961 MB / 0.741 GB, free: 13.23 MB / 462.1 MB
Notification: Performance of sort global known variables: time 1.51e-07/1.185, allocations: 0 / 0.741 GB, free: 13.23 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.008308/1.193, allocations: 2.018 MB / 0.7429 GB, free: 11.34 MB / 462.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 15
 * Number of states: 19 (solution.temperature,OxyRHm[1].logn,OxyRHm[2].logn,OxyRHm[3].logn,OxyRHm[4].logn,DeoxyRHm[1].logn,DeoxyRHm[2].logn,DeoxyRHm[3].logn,DeoxyRHm[4].logn,OxyTHm[1].logn,OxyTHm[2].logn,OxyTHm[3].logn,OxyTHm[4].logn,DeoxyTHm[1].logn,DeoxyTHm[2].logn,DeoxyTHm[3].logn,DeoxyTHm[4].logn,oxygen_unbound.logn,H2O.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 (408):
 * Single equations (assignments): 405
 * 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: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 3,(9,100.0%) 4}
 * Non-linear torn systems: 1 {1 3}
Notification: Performance of Backend phase and start with SimCode phase: time 0.04653/1.24, allocations: 15.8 MB / 0.7584 GB, free: 11.62 MB / 478.1 MB
Notification: Performance of simCode: created initialization part: time 0.1927/1.432, allocations: 7.373 MB / 0.7656 GB, free: 144 MB / 478.1 MB
Notification: Performance of simCode: created event and clocks part: time 9.818e-06/1.433, allocations: 0 / 0.7656 GB, free: 144 MB / 478.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.008456/1.441, allocations: 4.078 MB / 0.7695 GB, free: 143.6 MB / 478.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004037/1.445, allocations: 0.6636 MB / 0.7702 GB, free: 143.6 MB / 478.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03566/1.481, allocations: 53 MB / 0.8219 GB, free: 97.56 MB / 478.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004063/1.485, allocations: 3.4 MB / 0.8253 GB, free: 96.44 MB / 478.1 MB
Notification: Performance of simCode: alias equations: time 0.005638/1.491, allocations: 1.599 MB / 0.8268 GB, free: 96.28 MB / 478.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003575/1.494, allocations: 2.043 MB / 0.8288 GB, free: 94.77 MB / 478.1 MB
Notification: Performance of SimCode: time 1.263e-06/1.494, allocations: 0 / 0.8288 GB, free: 94.77 MB / 478.1 MB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3396/1.834, allocations: 121.3 MB / 0.9472 GB, free: 132.1 MB / 478.1 MB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001761/1.834, allocations: 210 kB / 0.9474 GB, free: 131.9 MB / 478.1 MB
Notification: Performance of buildModelFMU: Generate platform static: time 5.158/6.992, allocations: 0 / 0.9474 GB, free: 131.9 MB / 478.1 MB
(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 & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_Chemical_Chemical_Examples_Hemoglobin_Allosteric_Hemoglobin2_MWC_fmu --startTime=0 --stopTime=15000 --timeout=50 --tolerance=1e-06 Chemical_Chemical_Examples_Hemoglobin_Allosteric_Hemoglobin2_MWC.fmu > Chemical_Chemical.Examples.Hemoglobin.Allosteric_Hemoglobin2_MWC.pipe 2>&1)
Environment - environmentSimulation: