Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.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.0008389/0.0008389, allocations: 92.22 kB / 19.65 MB, free: 276 kB / 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.00107/0.00107, allocations: 173 kB / 23.03 MB, free: 1.543 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.9109/0.9109, allocations: 177.1 MB / 203.4 MB, free: 5.637 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Pharmacolibrary 25.9.0/package.mo", uses=false) [Timeout 180] "[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Pharmacolibrary 25.9.0/Examples/package.order:0:0-0:0:readonly] Warning: The package.order file does not list all .mo files and directories (containing package.mo) present in its directory. Missing names are: Paracetamol_Experiment Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Pharmacolibrary 25.9.0/package.mo): time 1.546/1.546, allocations: 273.5 MB / 0.5209 GB, free: 14.84 MB / 458.7 MB " [Timeout remaining time 178] Using package Pharmacolibrary with version 25.09 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Pharmacolibrary 25.9.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: translateModel(Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic,tolerance=1e-06,outputFormat="empty",numberOfIntervals=86400,variableFilter="",fileNamePrefix="Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic") translateModel(Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic,tolerance=1e-06,outputFormat="empty",numberOfIntervals=86400,variableFilter="",fileNamePrefix="Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 2.274e-06/2.274e-06, allocations: 0 / 0.647 GB, free: 38.61 MB / 0.5262 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.103e-05/2.33e-05, allocations: 4.984 kB / 0.647 GB, free: 38.6 MB / 0.5262 GB Notification: Performance of NFInst.instantiate(Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic): time 0.01429/0.01431, allocations: 15.43 MB / 0.662 GB, free: 28.59 MB / 0.5262 GB Notification: Performance of NFInst.instExpressions: time 0.007808/0.02212, allocations: 3.433 MB / 0.6654 GB, free: 26.21 MB / 0.5262 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.00212/0.02424, allocations: 31.41 kB / 0.6654 GB, free: 26.2 MB / 0.5262 GB Notification: Performance of NFTyping.typeComponents: time 0.00131/0.02555, allocations: 0.5012 MB / 0.6659 GB, free: 25.93 MB / 0.5262 GB Notification: Performance of NFTyping.typeBindings: time 0.002064/0.02761, allocations: 1.09 MB / 0.667 GB, free: 25.14 MB / 0.5262 GB Notification: Performance of NFTyping.typeClassSections: time 0.001371/0.02898, allocations: 0.9196 MB / 0.6679 GB, free: 24.55 MB / 0.5262 GB Notification: Performance of NFFlatten.flatten: time 0.003042/0.03202, allocations: 3.894 MB / 0.6717 GB, free: 22.36 MB / 0.5262 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0008331/0.03286, allocations: 0.5448 MB / 0.6722 GB, free: 22.04 MB / 0.5262 GB Notification: Performance of NFEvalConstants.evaluate: time 0.002224/0.03508, allocations: 1.952 MB / 0.6741 GB, free: 21.15 MB / 0.5262 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001431/0.03651, allocations: 1.166 MB / 0.6753 GB, free: 20.72 MB / 0.5262 GB Notification: Performance of NFPackage.collectConstants: time 0.0004949/0.03701, allocations: 333.7 kB / 0.6756 GB, free: 20.72 MB / 0.5262 GB Notification: Performance of NFFlatten.collectFunctions: time 0.00072/0.03773, allocations: 472.8 kB / 0.676 GB, free: 20.68 MB / 0.5262 GB Notification: Performance of NFScalarize.scalarize: time 0.0004422/0.03817, allocations: 0.7158 MB / 0.6767 GB, free: 20.19 MB / 0.5262 GB Notification: Performance of NFVerifyModel.verify: time 0.001135/0.0393, allocations: 1.122 MB / 0.6778 GB, free: 19.67 MB / 0.5262 GB Notification: Performance of NFConvertDAE.convert: time 0.002235/0.04154, allocations: 2.672 MB / 0.6804 GB, free: 18.25 MB / 0.5262 GB Notification: Performance of FrontEnd - DAE generated: time 5.07e-06/0.04154, allocations: 0.8906 kB / 0.6804 GB, free: 18.25 MB / 0.5262 GB Notification: Performance of FrontEnd: time 1.873e-06/0.04155, allocations: 4 kB / 0.6804 GB, free: 18.25 MB / 0.5262 GB Notification: Performance of Transformations before backend: time 4.067e-05/0.04159, allocations: 0 / 0.6804 GB, free: 18.25 MB / 0.5262 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 486 * Number of variables: 486 Notification: Performance of Generate backend data structure: time 0.003528/0.04512, allocations: 4.367 MB / 0.6847 GB, free: 14.93 MB / 0.5262 GB Notification: Performance of prepare preOptimizeDAE: time 4.135e-05/0.04516, allocations: 8.25 kB / 0.6847 GB, free: 14.93 MB / 0.5262 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001203/0.04636, allocations: 0.9137 MB / 0.6856 GB, free: 14.55 MB / 0.5262 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001344/0.0477, allocations: 1.31 MB / 0.6869 GB, free: 13.66 MB / 0.5262 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.794e-05/0.04779, allocations: 147 kB / 0.687 GB, free: 13.6 MB / 0.5262 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002436/0.04804, allocations: 224.9 kB / 0.6872 GB, free: 13.52 MB / 0.5262 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.002393/0.05043, allocations: 2.51 MB / 0.6897 GB, free: 11.9 MB / 0.5262 GB Notification: Performance of preOpt findStateOrder (simulation): time 6.005e-05/0.05049, allocations: 28.77 kB / 0.6897 GB, free: 11.88 MB / 0.5262 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000124/0.05061, allocations: 89.27 kB / 0.6898 GB, free: 11.82 MB / 0.5262 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.444e-05/0.05065, allocations: 44.09 kB / 0.6898 GB, free: 11.82 MB / 0.5262 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001577/0.05222, allocations: 1.913 MB / 0.6917 GB, free: 10.39 MB / 0.5262 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005125/0.05735, allocations: 6.152 MB / 0.6977 GB, free: 4.715 MB / 0.5262 GB Notification: Performance of preOpt comSubExp (simulation): time 0.001605/0.05895, allocations: 2.022 MB / 0.6997 GB, free: 2.453 MB / 0.5262 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0007891/0.05974, allocations: 1.422 MB / 0.7011 GB, free: 0.8281 MB / 0.5262 GB Notification: Performance of preOpt evalFunc (simulation): time 7.274e-05/0.05982, allocations: 43.75 kB / 0.7011 GB, free: 0.7852 MB / 0.5262 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001963/0.06178, allocations: 3.209 MB / 0.7043 GB, free: 13.12 MB / 0.5418 GB Notification: Performance of pre-optimization done (n=300): time 3.607e-06/0.06178, allocations: 0 / 0.7043 GB, free: 13.12 MB / 0.5418 GB Notification: Performance of matching and sorting (n=318): time 0.0106/0.07238, allocations: 12.63 MB / 0.7166 GB, free: 15.43 MB / 0.5574 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.001008/0.07339, allocations: 1.98 MB / 0.7185 GB, free: 13 MB / 0.5574 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001677/0.07507, allocations: 1.83 MB / 0.7203 GB, free: 11.13 MB / 0.5574 GB Notification: Performance of collectPreVariables (initialization): time 0.0001474/0.07522, allocations: 121.7 kB / 0.7204 GB, free: 11.01 MB / 0.5574 GB Notification: Performance of collectInitialEqns (initialization): time 0.0006852/0.0759, allocations: 1.152 MB / 0.7216 GB, free: 9.844 MB / 0.5574 GB Notification: Performance of collectInitialBindings (initialization): time 0.0005688/0.07647, allocations: 1.027 MB / 0.7226 GB, free: 8.809 MB / 0.5574 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005488/0.07702, allocations: 0.535 MB / 0.7231 GB, free: 8.262 MB / 0.5574 GB Notification: Performance of setup shared object (initialization): time 0.0001004/0.07712, allocations: 476.8 kB / 0.7235 GB, free: 7.793 MB / 0.5574 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.001657/0.07878, allocations: 1.689 MB / 0.7252 GB, free: 5.875 MB / 0.5574 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001608/0.08038, allocations: 2.738 MB / 0.7279 GB, free: 2.453 MB / 0.5574 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.00427/0.08465, allocations: 5.743 MB / 0.7335 GB, free: 11.68 MB / 0.573 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.708e-05/0.08467, allocations: 8 kB / 0.7335 GB, free: 11.68 MB / 0.573 GB Notification: Performance of matching and sorting (n=462) (initialization): time 0.004577/0.08925, allocations: 5.129 MB / 0.7385 GB, free: 6.105 MB / 0.573 GB Notification: Performance of prepare postOptimizeDAE: time 0.0003482/0.0896, allocations: 1.021 MB / 0.7395 GB, free: 4.863 MB / 0.573 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.965e-05/0.08963, allocations: 24 kB / 0.7395 GB, free: 4.84 MB / 0.573 GB Notification: Performance of postOpt tearingSystem (initialization): time 4.804e-05/0.08967, allocations: 39.94 kB / 0.7395 GB, free: 4.801 MB / 0.573 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001332/0.09101, allocations: 0.6787 MB / 0.7402 GB, free: 4.121 MB / 0.573 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.235e-05/0.09105, allocations: 51.8 kB / 0.7403 GB, free: 4.07 MB / 0.573 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00118/0.09223, allocations: 148 kB / 0.7404 GB, free: 3.926 MB / 0.573 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003509/0.09258, allocations: 406 kB / 0.7408 GB, free: 3.531 MB / 0.573 GB Warning: Assuming fixed start value for the following 84 variables: patient_high.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient_high.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient_high.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient_high.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient_high.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient_low.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient_low.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient_low.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient_low.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient_low.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real patient.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real patient.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_high.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_high.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref_high.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_high.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref_high.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_low.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_low.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref_low.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref_low.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref_low.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref.central.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref.periodicDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref.periodicDose.absorptionLumen.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref.periodicDose.variableDose.TotalCumulativeMass:VARIABLE(min = -1e-12 unit = \"kg\" fixed = true ) \"Total dose adminitrated by this source\" type: Real ref.peripheral.AUC:VARIABLE(unit = \"kg.s/m3\" fixed = true ) \"area under curve\" type: Real ref.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_low.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref_high.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_low.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.peripheral.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.peripheral.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.periodicDose.absorptionLumen.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.periodicDose.absorptionLumen.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.central.Cmin:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real patient_high.central.Cmax:DISCRETE(min = -1e-9 unit = \"kg/m3\" fixed = true ) type: Real ref.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_low.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_low.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_low.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_high.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_high.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean ref_high.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_low.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_low.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_low.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_high.peripheral.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_high.periodicDose.absorptionLumen.rising:DISCRETE(fixed = true protected = true ) type: Boolean patient_high.central.rising:DISCRETE(fixed = true protected = true ) type: Boolean Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 96 * Number of states: 0 () * Number of discrete variables: 186 ($PRE.ref.peripheral.Cmin,ref.peripheral.Cmin,$PRE.ref.peripheral.Cmax,ref.peripheral.Cmax,$PRE.ref.periodicDose.absorptionLumen.Cmin,ref.periodicDose.absorptionLumen.Cmin,$PRE.ref.periodicDose.absorptionLumen.Cmax,ref.periodicDose.absorptionLumen.Cmax,$PRE.ref.central.Cmin,ref.central.Cmin,$PRE.ref.central.Cmax,ref.central.Cmax,$PRE.ref_low.peripheral.Cmin,ref_low.peripheral.Cmin,$PRE.ref_low.peripheral.Cmax,ref_low.peripheral.Cmax,$PRE.ref_low.periodicDose.absorptionLumen.Cmin,ref_low.periodicDose.absorptionLumen.Cmin,$PRE.ref_low.periodicDose.absorptionLumen.Cmax,ref_low.periodicDose.absorptionLumen.Cmax,$PRE.ref_low.central.Cmin,ref_low.central.Cmin,$PRE.ref_low.central.Cmax,ref_low.central.Cmax,$PRE.ref_high.peripheral.Cmin,ref_high.peripheral.Cmin,$PRE.ref_high.peripheral.Cmax,ref_high.peripheral.Cmax,$PRE.ref_high.periodicDose.absorptionLumen.Cmin,ref_high.periodicDose.absorptionLumen.Cmin,$PRE.ref_high.periodicDose.absorptionLumen.Cmax,ref_high.periodicDose.absorptionLumen.Cmax,$PRE.ref_high.central.Cmin,ref_high.central.Cmin,$PRE.ref_high.central.Cmax,ref_high.central.Cmax,$PRE.patient.peripheral.Cmin,patient.peripheral.Cmin,$PRE.patient.peripheral.Cmax,patient.peripheral.Cmax,$PRE.patient.periodicDose.absorptionLumen.Cmin,patient.periodicDose.absorptionLumen.Cmin,$PRE.patient.periodicDose.absorptionLumen.Cmax,patient.periodicDose.absorptionLumen.Cmax,$PRE.patient.central.Cmin,patient.central.Cmin,$PRE.patient.central.Cmax,patient.central.Cmax,$PRE.patient_low.peripheral.Cmin,patient_low.peripheral.Cmin,$PRE.patient_low.peripheral.Cmax,patient_low.peripheral.Cmax,$PRE.patient_low.periodicDose.absorptionLumen.Cmin,patient_low.periodicDose.absorptionLumen.Cmin,$PRE.patient_low.periodicDose.absorptionLumen.Cmax,patient_low.periodicDose.absorptionLumen.Cmax,$PRE.patient_low.central.Cmin,patient_low.central.Cmin,$PRE.patient_low.central.Cmax,patient_low.central.Cmax,$PRE.patient_high.peripheral.Cmin,patient_high.peripheral.Cmin,$PRE.patient_high.peripheral.Cmax,patient_high.peripheral.Cmax,$PRE.patient_high.periodicDose.absorptionLumen.Cmin,patient_high.periodicDose.absorptionLumen.Cmin,$PRE.patient_high.periodicDose.absorptionLumen.Cmax,patient_high.periodicDose.absorptionLumen.Cmax,$PRE.patient_high.central.Cmin,patient_high.central.Cmin,$PRE.patient_high.central.Cmax,patient_high.central.Cmax,$PRE.ref.peripheral.rising,$PRE.ref.periodicDose.pulse.count,$PRE.ref.periodicDose.pulse.T_start,$PRE.ref.periodicDose.absorptionLumen.rising,$PRE.ref.central.rising,ref.CYP2C19.status,ref.SLC22A2.status,ref.peripheral.rising,ref.periodicDose.pulse.count,ref.periodicDose.pulse.T_start,ref.periodicDose.absorptionLumen.rising,ref.central.rising,$whenCondition41,$whenCondition40,$whenCondition39,$whenCondition38,$whenCondition37,$whenCondition36,$whenCondition42,$PRE.ref_low.peripheral.rising,$PRE.ref_low.periodicDose.pulse.count,$PRE.ref_low.periodicDose.pulse.T_start,$PRE.ref_low.periodicDose.absorptionLumen.rising,$PRE.ref_low.central.rising,ref_low.CYP2C19.status,ref_low.SLC22A2.status,ref_low.peripheral.rising,ref_low.periodicDose.pulse.count,ref_low.periodicDose.pulse.T_start,ref_low.periodicDose.absorptionLumen.rising,ref_low.central.rising,$whenCondition34,$whenCondition33,$whenCondition32,$whenCondition31,$whenCondition30,$whenCondition29,$whenCondition35,$PRE.ref_high.peripheral.rising,$PRE.ref_high.periodicDose.pulse.count,$PRE.ref_high.periodicDose.pulse.T_start,$PRE.ref_high.periodicDose.absorptionLumen.rising,$PRE.ref_high.central.rising,ref_high.CYP2C19.status,ref_high.SLC22A2.status,ref_high.peripheral.rising,ref_high.periodicDose.pulse.count,ref_high.periodicDose.pulse.T_start,ref_high.periodicDose.absorptionLumen.rising,ref_high.central.rising,$whenCondition27,$whenCondition26,$whenCondition25,$whenCondition24,$whenCondition23,$whenCondition22,$whenCondition28,$PRE.patient.peripheral.rising,$PRE.patient.periodicDose.pulse.count,$PRE.patient.periodicDose.pulse.T_start,$PRE.patient.periodicDose.absorptionLumen.rising,$PRE.patient.central.rising,patient.CYP2C19.status,patient.SLC22A2.status,patient.peripheral.rising,patient.periodicDose.pulse.count,patient.periodicDose.pulse.T_start,patient.periodicDose.absorptionLumen.rising,patient.central.rising,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition21,$PRE.patient_low.peripheral.rising,$PRE.patient_low.periodicDose.pulse.count,$PRE.patient_low.periodicDose.pulse.T_start,$PRE.patient_low.periodicDose.absorptionLumen.rising,$PRE.patient_low.central.rising,patient_low.CYP2C19.status,patient_low.SLC22A2.status,patient_low.peripheral.rising,patient_low.periodicDose.pulse.count,patient_low.periodicDose.pulse.T_start,patient_low.periodicDose.absorptionLumen.rising,patient_low.central.rising,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition14,$PRE.patient_high.peripheral.rising,$PRE.patient_high.periodicDose.pulse.count,$PRE.patient_high.periodicDose.pulse.T_start,$PRE.patient_high.periodicDose.absorptionLumen.rising,$PRE.patient_high.central.rising,patient_high.CYP2C19.status,patient_high.SLC22A2.status,patient_high.peripheral.rising,patient_high.periodicDose.pulse.count,patient_high.periodicDose.pulse.T_start,patient_high.periodicDose.absorptionLumen.rising,patient_high.central.rising,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,$whenCondition7) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (426): * Single equations (assignments): 390 * Array equations: 0 * Algorithm blocks: 36 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of prepare postOptimizeDAE: time 0.001604/0.09418, allocations: 1.646 MB / 0.7424 GB, free: 1.742 MB / 0.573 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00113/0.09531, allocations: 0.7634 MB / 0.7431 GB, free: 0.9766 MB / 0.573 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002617/0.09557, allocations: 263.5 kB / 0.7434 GB, free: 0.7109 MB / 0.573 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.935e-05/0.09559, allocations: 31.94 kB / 0.7434 GB, free: 0.6797 MB / 0.573 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.573e-05/0.09562, allocations: 0 / 0.7434 GB, free: 0.6797 MB / 0.573 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.893e-05/0.09564, allocations: 15.95 kB / 0.7434 GB, free: 0.6641 MB / 0.573 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.007036/0.1027, allocations: 7.298 MB / 0.7506 GB, free: 8.953 MB / 0.5887 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.635e-06/0.1027, allocations: 4 kB / 0.7506 GB, free: 8.949 MB / 0.5887 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001162/0.1038, allocations: 455.4 kB / 0.751 GB, free: 8.504 MB / 0.5887 GB Notification: Performance of postOpt tearingSystem (simulation): time 1.555e-05/0.1039, allocations: 8 kB / 0.751 GB, free: 8.496 MB / 0.5887 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001708/0.104, allocations: 119.5 kB / 0.7511 GB, free: 8.383 MB / 0.5887 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.309e-05/0.104, allocations: 19.88 kB / 0.7511 GB, free: 8.363 MB / 0.5887 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.455e-06/0.104, allocations: 0 / 0.7511 GB, free: 8.363 MB / 0.5887 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006142/0.1102, allocations: 7.306 MB / 0.7583 GB, free: 460 kB / 0.5887 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001008/0.1112, allocations: 0.595 MB / 0.7589 GB, free: 15.85 MB / 0.6043 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003812/0.1116, allocations: 132 kB / 0.759 GB, free: 15.72 MB / 0.6043 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007178/0.1123, allocations: 111.9 kB / 0.7591 GB, free: 15.61 MB / 0.6043 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003714/0.1127, allocations: 275.2 kB / 0.7593 GB, free: 15.35 MB / 0.6043 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003084/0.113, allocations: 373.5 kB / 0.7597 GB, free: 14.99 MB / 0.6043 GB Notification: Performance of sorting global known variables: time 0.001111/0.1141, allocations: 1.05 MB / 0.7607 GB, free: 13.92 MB / 0.6043 GB Notification: Performance of sort global known variables: time 9e-08/0.1141, allocations: 4 kB / 0.7607 GB, free: 13.92 MB / 0.6043 GB Notification: Performance of remove unused functions: time 0.001884/0.116, allocations: 0.7656 MB / 0.7615 GB, free: 13.15 MB / 0.6043 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 6 * Number of states: 54 (patient_high.central.C,patient_high.central.AUC,patient_high.elim.MExc,patient_high.periodicDose.TotalCumulativeMass,patient_high.periodicDose.absorptionLumen.C,patient_high.periodicDose.absorptionLumen.AUC,patient_high.periodicDose.variableDose.TotalCumulativeMass,patient_high.peripheral.C,patient_high.peripheral.AUC,patient_low.central.C,patient_low.central.AUC,patient_low.elim.MExc,patient_low.periodicDose.TotalCumulativeMass,patient_low.periodicDose.absorptionLumen.C,patient_low.periodicDose.absorptionLumen.AUC,patient_low.periodicDose.variableDose.TotalCumulativeMass,patient_low.peripheral.C,patient_low.peripheral.AUC,patient.central.C,patient.central.AUC,patient.elim.MExc,patient.periodicDose.TotalCumulativeMass,patient.periodicDose.absorptionLumen.C,patient.periodicDose.absorptionLumen.AUC,patient.periodicDose.variableDose.TotalCumulativeMass,patient.peripheral.C,patient.peripheral.AUC,ref_high.central.C,ref_high.central.AUC,ref_high.elim.MExc,ref_high.periodicDose.TotalCumulativeMass,ref_high.periodicDose.absorptionLumen.C,ref_high.periodicDose.absorptionLumen.AUC,ref_high.periodicDose.variableDose.TotalCumulativeMass,ref_high.peripheral.C,ref_high.peripheral.AUC,ref_low.central.C,ref_low.central.AUC,ref_low.elim.MExc,ref_low.periodicDose.TotalCumulativeMass,ref_low.periodicDose.absorptionLumen.C,ref_low.periodicDose.absorptionLumen.AUC,ref_low.periodicDose.variableDose.TotalCumulativeMass,ref_low.peripheral.C,ref_low.peripheral.AUC,ref.central.C,ref.central.AUC,ref.elim.MExc,ref.periodicDose.TotalCumulativeMass,ref.periodicDose.absorptionLumen.C,ref.periodicDose.absorptionLumen.AUC,ref.periodicDose.variableDose.TotalCumulativeMass,ref.peripheral.C,ref.peripheral.AUC) * Number of discrete variables: 120 ($whenCondition7,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,patient_high.central.Cmax,patient_high.central.Cmin,patient_high.central.rising,patient_high.periodicDose.absorptionLumen.Cmax,patient_high.periodicDose.absorptionLumen.Cmin,patient_high.periodicDose.absorptionLumen.rising,patient_high.periodicDose.pulse.T_start,patient_high.periodicDose.pulse.count,patient_high.peripheral.Cmax,patient_high.peripheral.Cmin,patient_high.peripheral.rising,patient_high.SLC22A2.status,patient_high.CYP2C19.status,$whenCondition14,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,patient_low.central.Cmax,patient_low.central.Cmin,patient_low.central.rising,patient_low.periodicDose.absorptionLumen.Cmax,patient_low.periodicDose.absorptionLumen.Cmin,patient_low.periodicDose.absorptionLumen.rising,patient_low.periodicDose.pulse.T_start,patient_low.periodicDose.pulse.count,patient_low.peripheral.Cmax,patient_low.peripheral.Cmin,patient_low.peripheral.rising,patient_low.SLC22A2.status,patient_low.CYP2C19.status,$whenCondition21,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20,patient.central.Cmax,patient.central.Cmin,patient.central.rising,patient.periodicDose.absorptionLumen.Cmax,patient.periodicDose.absorptionLumen.Cmin,patient.periodicDose.absorptionLumen.rising,patient.periodicDose.pulse.T_start,patient.periodicDose.pulse.count,patient.peripheral.Cmax,patient.peripheral.Cmin,patient.peripheral.rising,patient.SLC22A2.status,patient.CYP2C19.status,$whenCondition28,$whenCondition22,$whenCondition23,$whenCondition24,$whenCondition25,$whenCondition26,$whenCondition27,ref_high.central.Cmax,ref_high.central.Cmin,ref_high.central.rising,ref_high.periodicDose.absorptionLumen.Cmax,ref_high.periodicDose.absorptionLumen.Cmin,ref_high.periodicDose.absorptionLumen.rising,ref_high.periodicDose.pulse.T_start,ref_high.periodicDose.pulse.count,ref_high.peripheral.Cmax,ref_high.peripheral.Cmin,ref_high.peripheral.rising,ref_high.SLC22A2.status,ref_high.CYP2C19.status,$whenCondition35,$whenCondition29,$whenCondition30,$whenCondition31,$whenCondition32,$whenCondition33,$whenCondition34,ref_low.central.Cmax,ref_low.central.Cmin,ref_low.central.rising,ref_low.periodicDose.absorptionLumen.Cmax,ref_low.periodicDose.absorptionLumen.Cmin,ref_low.periodicDose.absorptionLumen.rising,ref_low.periodicDose.pulse.T_start,ref_low.periodicDose.pulse.count,ref_low.peripheral.Cmax,ref_low.peripheral.Cmin,ref_low.peripheral.rising,ref_low.SLC22A2.status,ref_low.CYP2C19.status,$whenCondition42,$whenCondition36,$whenCondition37,$whenCondition38,$whenCondition39,$whenCondition40,$whenCondition41,ref.central.Cmax,ref.central.Cmin,ref.central.rising,ref.periodicDose.absorptionLumen.Cmax,ref.periodicDose.absorptionLumen.Cmin,ref.periodicDose.absorptionLumen.rising,ref.periodicDose.pulse.T_start,ref.periodicDose.pulse.count,ref.peripheral.Cmax,ref.peripheral.Cmin,ref.peripheral.rising,ref.SLC22A2.status,ref.CYP2C19.status) * Number of discrete states: 108 (ref.periodicDose.pulse.count,ref.CYP2C19.status,ref.SLC22A2.status,ref.peripheral.Cmin,$whenCondition41,ref.peripheral.Cmax,$whenCondition40,ref.peripheral.rising,ref.periodicDose.absorptionLumen.Cmin,$whenCondition39,ref.periodicDose.absorptionLumen.Cmax,$whenCondition38,ref.periodicDose.absorptionLumen.rising,ref.central.Cmin,$whenCondition37,ref.central.Cmax,$whenCondition36,ref.central.rising,ref_low.periodicDose.pulse.count,ref_low.CYP2C19.status,ref_low.SLC22A2.status,ref_low.peripheral.Cmin,$whenCondition34,ref_low.peripheral.Cmax,$whenCondition33,ref_low.peripheral.rising,ref_low.periodicDose.absorptionLumen.Cmin,$whenCondition32,ref_low.periodicDose.absorptionLumen.Cmax,$whenCondition31,ref_low.periodicDose.absorptionLumen.rising,ref_low.central.Cmin,$whenCondition30,ref_low.central.Cmax,$whenCondition29,ref_low.central.rising,ref_high.periodicDose.pulse.count,ref_high.CYP2C19.status,ref_high.SLC22A2.status,ref_high.peripheral.Cmin,$whenCondition27,ref_high.peripheral.Cmax,$whenCondition26,ref_high.peripheral.rising,ref_high.periodicDose.absorptionLumen.Cmin,$whenCondition25,ref_high.periodicDose.absorptionLumen.Cmax,$whenCondition24,ref_high.periodicDose.absorptionLumen.rising,ref_high.central.Cmin,$whenCondition23,ref_high.central.Cmax,$whenCondition22,ref_high.central.rising,patient.periodicDose.pulse.count,patient.CYP2C19.status,patient.SLC22A2.status,patient.peripheral.Cmin,$whenCondition20,patient.peripheral.Cmax,$whenCondition19,patient.peripheral.rising,patient.periodicDose.absorptionLumen.Cmin,$whenCondition18,patient.periodicDose.absorptionLumen.Cmax,$whenCondition17,patient.periodicDose.absorptionLumen.rising,patient.central.Cmin,$whenCondition16,patient.central.Cmax,$whenCondition15,patient.central.rising,patient_low.periodicDose.pulse.count,patient_low.CYP2C19.status,patient_low.SLC22A2.status,patient_low.peripheral.Cmin,$whenCondition13,patient_low.peripheral.Cmax,$whenCondition12,patient_low.peripheral.rising,patient_low.periodicDose.absorptionLumen.Cmin,$whenCondition11,patient_low.periodicDose.absorptionLumen.Cmax,$whenCondition10,patient_low.periodicDose.absorptionLumen.rising,patient_low.central.Cmin,$whenCondition9,patient_low.central.Cmax,$whenCondition8,patient_low.central.rising,patient_high.periodicDose.pulse.count,patient_high.CYP2C19.status,patient_high.SLC22A2.status,patient_high.peripheral.Cmin,$whenCondition6,patient_high.peripheral.Cmax,$whenCondition5,patient_high.peripheral.rising,patient_high.periodicDose.absorptionLumen.Cmin,$whenCondition4,patient_high.periodicDose.absorptionLumen.Cmax,$whenCondition3,patient_high.periodicDose.absorptionLumen.rising,patient_high.central.Cmin,$whenCondition2,patient_high.central.Cmax,$whenCondition1,patient_high.central.rising) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (252): * Single equations (assignments): 210 * Array equations: 0 * Algorithm blocks: 30 * Record equations: 0 * When equations: 12 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.0005399/0.1165, allocations: 415.2 kB / 0.7619 GB, free: 12.75 MB / 0.6043 GB Notification: Performance of simCode: created initialization part: time 0.004315/0.1208, allocations: 5.813 MB / 0.7676 GB, free: 6.09 MB / 0.6043 GB Notification: Performance of simCode: created event and clocks part: time 2.855e-06/0.1208, allocations: 0 / 0.7676 GB, free: 6.09 MB / 0.6043 GB Notification: Performance of simCode: created simulation system equations: time 0.002273/0.1231, allocations: 3.553 MB / 0.771 GB, free: 1.98 MB / 0.6043 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002175/0.1253, allocations: 0.8262 MB / 0.7718 GB, free: 1.199 MB / 0.6043 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008537/0.1338, allocations: 7.587 MB / 0.7792 GB, free: 9.469 MB / 0.6199 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004213/0.1342, allocations: 0.5435 MB / 0.7798 GB, free: 8.891 MB / 0.6199 GB Notification: Performance of simCode: alias equations: time 0.000791/0.135, allocations: 257 kB / 0.78 GB, free: 8.648 MB / 0.6199 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003382/0.1354, allocations: 172.5 kB / 0.7802 GB, free: 8.48 MB / 0.6199 GB Notification: Performance of SimCode: time 1.252e-06/0.1354, allocations: 0 / 0.7802 GB, free: 8.48 MB / 0.6199 GB Notification: Performance of Templates: time 0.05568/0.191, allocations: 48.75 MB / 0.8278 GB, free: 9.812 MB / 0.6668 GB " [Timeout remaining time 660] make -j1 -f Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.makefile [Timeout 660] (rm -f Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.pipe ; mkfifo Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.pipe ; head -c 1048576 < Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.pipe >> ../files/Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.sim & ./Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic -abortSlowSimulation -alarm=240 -lv LOG_STATS > Pharmacolibrary_Pharmacolibrary.Examples.Pharmacogenomics.PKPG_2C_generic.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 6.4939360888674855]