Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.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.0008563/0.0008563, allocations: 80.44 kB / 19.17 MB, free: 0.7422 MB / 13.93 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.001002/0.001002, allocations: 169.9 kB / 22.46 MB, free: 2.105 MB / 18.57 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 0.9362/0.9362, allocations: 177.1 MB / 202.8 MB, free: 5.746 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo): time 0.09098/0.09098, allocations: 17.38 MB / 276.6 MB, free: 11.84 MB / 266.7 MB
"
[Timeout remaining time 180]
Using package Chemical with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/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: "" <> buildModelFMU(Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood,fileNamePrefix="Chemical_Chemical_Obsolete_Examples_AcidBase_CarbonDioxideInBlood",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood,fileNamePrefix="Chemical_Chemical_Obsolete_Examples_AcidBase_CarbonDioxideInBlood",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 2.985e-06/2.985e-06, allocations: 0 / 411.5 MB, free: 404 kB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.585e-05/2.883e-05, allocations: 6.219 kB / 411.5 MB, free: 396 kB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/Obsolete.mo:14337:7-14337: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 2.0.0/Obsolete.mo:14337:7-14337: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.AcidBase.CarbonDioxideInBlood): time 0.3119/0.3119, allocations: 33.61 MB / 445.1 MB, free: 43.65 MB / 410.8 MB
Notification: Performance of NFInst.instExpressions: time 0.02473/0.3366, allocations: 41.74 MB / 486.8 MB, free: 20.5 MB / 410.8 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002005/0.3386, allocations: 47.72 kB / 486.9 MB, free: 20.48 MB / 410.8 MB
Notification: Performance of NFTyping.typeComponents: time 0.001622/0.3403, allocations: 0.9463 MB / 487.8 MB, free: 19.97 MB / 410.8 MB
Notification: Performance of NFTyping.typeBindings: time 0.007625/0.3479, allocations: 3.838 MB / 491.7 MB, free: 17.67 MB / 410.8 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01078/0.3587, allocations: 6.677 MB / 498.3 MB, free: 13.75 MB / 410.8 MB
Notification: Performance of NFFlatten.flatten: time 0.003278/0.3619, allocations: 4.684 MB / 0.4912 GB, free: 11.39 MB / 410.8 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001863/0.3638, allocations: 2.229 MB / 0.4934 GB, free: 10.32 MB / 410.8 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002486/0.3663, allocations: 3.15 MB / 0.4965 GB, free: 9.141 MB / 410.8 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002115/0.3684, allocations: 2.117 MB / 0.4985 GB, free: 8.578 MB / 410.8 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004476/0.3689, allocations: 420.5 kB / 0.4989 GB, free: 8.578 MB / 410.8 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01168/0.3805, allocations: 5.068 MB / 0.5039 GB, free: 7.406 MB / 410.8 MB
Notification: Performance of NFScalarize.scalarize: time 0.0007776/0.3813, allocations: 1.008 MB / 0.5049 GB, free: 6.766 MB / 410.8 MB
Notification: Performance of NFVerifyModel.verify: time 0.001696/0.383, allocations: 1.503 MB / 0.5063 GB, free: 6.117 MB / 410.8 MB
Notification: Performance of NFConvertDAE.convert: time 0.01667/0.3997, allocations: 10.89 MB / 0.517 GB, free: 160 kB / 410.8 MB
Notification: Performance of FrontEnd - DAE generated: time 4.699e-06/0.3997, allocations: 0 / 0.517 GB, free: 160 kB / 410.8 MB
Notification: Performance of FrontEnd: time 1.323e-06/0.3997, allocations: 3.281 kB / 0.517 GB, free: 160 kB / 410.8 MB
Notification: Performance of Transformations before backend: time 5.351e-05/0.3997, allocations: 0 / 0.517 GB, free: 160 kB / 410.8 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 803
 * Number of variables: 803
Notification: Performance of Generate backend data structure: time 0.006061/0.4058, allocations: 4.722 MB / 0.5216 GB, free: 13.02 MB / 426.8 MB
Notification: Performance of prepare preOptimizeDAE: time 4.478e-05/0.4058, allocations: 14.62 kB / 0.5216 GB, free: 13.02 MB / 426.8 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003726/0.4062, allocations: 423.5 kB / 0.522 GB, free: 12.69 MB / 426.8 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00202/0.4082, allocations: 0.8446 MB / 0.5228 GB, free: 12.3 MB / 426.8 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001936/0.4102, allocations: 1.622 MB / 0.5244 GB, free: 11.09 MB / 426.8 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001836/0.4104, allocations: 348.8 kB / 0.5248 GB, free: 10.85 MB / 426.8 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003316/0.4107, allocations: 330.1 kB / 0.5251 GB, free: 10.73 MB / 426.8 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004427/0.4151, allocations: 4.545 MB / 0.5295 GB, free: 8.078 MB / 426.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.779e-05/0.4152, allocations: 192 / 0.5295 GB, free: 8.078 MB / 426.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002226/0.4154, allocations: 128.8 kB / 0.5296 GB, free: 7.977 MB / 426.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.064e-05/0.4154, allocations: 78 kB / 0.5297 GB, free: 7.977 MB / 426.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005377/0.4208, allocations: 6.173 MB / 0.5357 GB, free: 5.168 MB / 426.8 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.0135/0.4343, allocations: 12.03 MB / 0.5475 GB, free: 9.59 MB / 442.8 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.005528/0.4398, allocations: 5.313 MB / 0.5527 GB, free: 4.293 MB / 442.8 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001952/0.4418, allocations: 2.059 MB / 0.5547 GB, free: 2.227 MB / 442.8 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01134/0.4531, allocations: 9.125 MB / 0.5636 GB, free: 9.062 MB / 458.8 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.584e-05/0.4531, allocations: 60.8 kB / 0.5637 GB, free: 8.992 MB / 458.8 MB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001001/0.4541, allocations: 469.8 kB / 0.5641 GB, free: 8.531 MB / 458.8 MB
Notification: Performance of pre-optimization done (n=284): time 6.021e-06/0.4541, allocations: 0 / 0.5641 GB, free: 8.531 MB / 458.8 MB
Notification: Performance of matching and sorting (n=387): time 0.04453/0.4987, allocations: 23.36 MB / 0.5869 GB, free: 1.008 MB / 474.8 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.425e-05/0.4987, allocations: 94.45 kB / 0.587 GB, free: 0.9023 MB / 474.8 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001459/0.5002, allocations: 1.777 MB / 0.5887 GB, free: 15.08 MB / 490.8 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001804/0.5004, allocations: 85.7 kB / 0.5888 GB, free: 14.99 MB / 490.8 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005791/0.501, allocations: 1.328 MB / 0.5901 GB, free: 13.65 MB / 490.8 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0007108/0.5017, allocations: 1.363 MB / 0.5914 GB, free: 12.27 MB / 490.8 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009299/0.5026, allocations: 0.772 MB / 0.5922 GB, free: 11.49 MB / 490.8 MB
Notification: Performance of setup shared object (initialization): time 6.316e-05/0.5027, allocations: 309.1 kB / 0.5925 GB, free: 11.18 MB / 490.8 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001926/0.5046, allocations: 1.839 MB / 0.5943 GB, free: 9.336 MB / 490.8 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002484/0.5071, allocations: 2.846 MB / 0.5971 GB, free: 6.055 MB / 490.8 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005424/0.5125, allocations: 4.971 MB / 0.6019 GB, free: 0.6562 MB / 490.8 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.304e-05/0.5125, allocations: 10.62 kB / 0.6019 GB, free: 0.6484 MB / 490.8 MB
Notification: Performance of matching and sorting (n=542) (initialization): time 0.01073/0.5232, allocations: 6.841 MB / 0.6086 GB, free: 9.754 MB / 0.4949 GB
Notification: Performance of prepare postOptimizeDAE: time 3.116e-05/0.5233, allocations: 20 kB / 0.6086 GB, free: 9.734 MB / 0.4949 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.925e-05/0.5233, allocations: 28 kB / 0.6087 GB, free: 9.707 MB / 0.4949 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005242/0.5285, allocations: 2.292 MB / 0.6109 GB, free: 7.387 MB / 0.4949 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001817/0.5304, allocations: 0.8932 MB / 0.6118 GB, free: 6.492 MB / 0.4949 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01565/0.546, allocations: 17.53 MB / 0.6289 GB, free: 4.121 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001846/0.5479, allocations: 143.9 kB / 0.629 GB, free: 3.98 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002771/0.5481, allocations: 231.9 kB / 0.6293 GB, free: 3.754 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 133
 * 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 (469):
 * Single equations (assignments): 460
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(3,16,77.8%), (1,3,100.0%), (2,6,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 5 systems
   {(3,26), (1,3), (1,6), (1,2), (1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.001118/0.5493, allocations: 0.6054 MB / 0.6298 GB, free: 3.141 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004428/0.5497, allocations: 286.1 kB / 0.6301 GB, free: 2.859 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1739/0.7236, allocations: 6.583 MB / 0.6365 GB, free: 156.6 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.845e-05/0.7236, allocations: 44.75 kB / 0.6366 GB, free: 156.6 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.367e-06/0.7236, allocations: 3.312 kB / 0.6366 GB, free: 156.6 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.143e-05/0.7236, allocations: 23.2 kB / 0.6366 GB, free: 156.6 MB / 0.5105 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01179/0.7354, allocations: 10.09 MB / 0.6465 GB, free: 153.2 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.045e-05/0.7354, allocations: 13.61 kB / 0.6465 GB, free: 153.1 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001343/0.7367, allocations: 0.6461 MB / 0.6471 GB, free: 153.1 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004253/0.741, allocations: 2.015 MB / 0.6491 GB, free: 152.7 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001906/0.7412, allocations: 140.2 kB / 0.6492 GB, free: 152.6 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01036/0.7515, allocations: 16.15 MB / 0.665 GB, free: 139.8 MB / 0.5105 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.976e-06/0.7516, allocations: 6.875 kB / 0.665 GB, free: 139.8 MB / 0.5105 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01044/0.762, allocations: 8.507 MB / 0.6733 GB, free: 136.3 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001083/0.7631, allocations: 0.6423 MB / 0.6739 GB, free: 136.2 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002422/0.7633, allocations: 80.38 kB / 0.674 GB, free: 136.1 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009146/0.7642, allocations: 100.8 kB / 0.6741 GB, free: 136.1 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004672/0.7647, allocations: 353.8 kB / 0.6744 GB, free: 136 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001928/0.7649, allocations: 158.2 kB / 0.6746 GB, free: 136 MB / 0.5105 GB
Notification: Performance of sorting global known variables: time 0.0009415/0.7658, allocations: 1.124 MB / 0.6757 GB, free: 135.5 MB / 0.5105 GB
Notification: Performance of sort global known variables: time 4e-08/0.7658, allocations: 0 / 0.6757 GB, free: 135.5 MB / 0.5105 GB
Notification: Performance of remove unused functions: time 0.004414/0.7702, allocations: 2.204 MB / 0.6778 GB, free: 135.3 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 18
 * Number of states: 14 (blood_erythrocytes.temperature,blood_erythrocytes.mass,blood_plasma.temperature,blood_plasma.mass,HCO3.logn,CO2.logn,H2O.logn,HCO3_E.logn,CO2_E.logn,H2O_E.logn,Cl_E.logn,Cl.logn,others_E.logn,others_P.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 (354):
 * Single equations (assignments): 345
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(3,16,77.8%), (1,3,100.0%), (2,6,100.0%), (1,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 5 systems
   {(2,8), (4,30), (1,3), (1,2), (1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01485/0.7851, allocations: 11.31 MB / 0.6889 GB, free: 130.8 MB / 0.5105 GB
Notification: Performance of simCode: created initialization part: time 0.006892/0.792, allocations: 5.593 MB / 0.6943 GB, free: 128.2 MB / 0.5105 GB
Notification: Performance of simCode: created event and clocks part: time 4.669e-06/0.792, allocations: 7.75 kB / 0.6944 GB, free: 128.2 MB / 0.5105 GB
Notification: Performance of simCode: created simulation system equations: time 0.003423/0.7954, allocations: 3.337 MB / 0.6976 GB, free: 126.5 MB / 0.5105 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001701/0.7971, allocations: 0.5609 MB / 0.6982 GB, free: 126.3 MB / 0.5105 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03401/0.8311, allocations: 31.23 MB / 0.7287 GB, free: 101 MB / 0.5105 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007959/0.8319, allocations: 1.334 MB / 0.73 GB, free: 100.1 MB / 0.5105 GB
Notification: Performance of simCode: alias equations: time 0.001639/0.8336, allocations: 0.6691 MB / 0.7306 GB, free: 99.67 MB / 0.5105 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001163/0.8347, allocations: 1.548 MB / 0.7321 GB, free: 98.3 MB / 0.5105 GB
Notification: Performance of SimCode: time 1.092e-06/0.8347, allocations: 0 / 0.7321 GB, free: 98.3 MB / 0.5105 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.2609/1.096, allocations: 115.6 MB / 0.8451 GB, free: 141.4 MB / 0.5105 GB
Notification: Performance of buildModelFMU: Generate platform static: time 11.98/13.07, allocations: 7.891 kB / 0.8451 GB, free: 141.4 MB / 0.5105 GB
"
[Timeout remaining time 647]
(rm -f Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.pipe ; mkfifo Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.pipe ; head -c 1048576 < Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.pipe >> ../files/Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_Chemical_Chemical_Obsolete_Examples_AcidBase_CarbonDioxideInBlood_fmu --startTime=0 --stopTime=3 --stepSize=0.0012 --timeout=50 --tolerance=1e-06 Chemical_Chemical_Obsolete_Examples_AcidBase_CarbonDioxideInBlood.fmu > Chemical_Chemical.Obsolete.Examples.AcidBase.CarbonDioxideInBlood.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 15.063662331551313]
Failed to read output from testmodel.py, exit status != 0:
13.218255543150008 13.228431869 12.826659358
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7da424c810a0>