Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001553/0.001553, allocations: 104.5 kB / 16.45 MB, free: 6.445 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001403/0.001403, allocations: 187.7 kB / 19.4 MB, free: 3.66 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.434/1.434, allocations: 225.6 MB / 247.8 MB, free: 3.758 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo): time 0.8765/0.8765, allocations: 134 MB / 436.6 MB, free: 13.94 MB / 350.1 MB " [Timeout remaining time 179] Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.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(IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised,fileNamePrefix="IDEAS_IDEAS_Fluid_Examples_Performance_PressureDrop_SeriesFlowOptimised",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised,fileNamePrefix="IDEAS_IDEAS_Fluid_Examples_Performance_PressureDrop_SeriesFlowOptimised",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 0.002157/0.002157, allocations: 71.88 kB / 0.6051 GB, free: 8.23 MB / 0.4981 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.4914/0.4935, allocations: 84.67 MB / 0.6878 GB, free: 1.887 MB / 0.4981 GB Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised): time 0.21/0.7035, allocations: 162.1 MB / 0.8461 GB, free: 3.996 MB / 0.6544 GB Notification: Performance of NFInst.instExpressions: time 0.00383/0.7074, allocations: 1.753 MB / 0.8479 GB, free: 2.238 MB / 0.6544 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0004098/0.7078, allocations: 11.88 kB / 0.8479 GB, free: 2.227 MB / 0.6544 GB Notification: Performance of NFTyping.typeComponents: time 0.0006752/0.7084, allocations: 170.9 kB / 0.848 GB, free: 2.059 MB / 0.6544 GB Notification: Performance of NFTyping.typeBindings: time 0.001635/0.7101, allocations: 0.5562 MB / 0.8486 GB, free: 1.5 MB / 0.6544 GB Notification: Performance of NFTyping.typeClassSections: time 0.001303/0.7114, allocations: 392 kB / 0.8489 GB, free: 1.121 MB / 0.6544 GB Notification: Performance of NFFlatten.flatten: time 0.003414/0.7148, allocations: 2.527 MB / 0.8514 GB, free: 14.59 MB / 0.67 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001295/0.7161, allocations: 0.7928 MB / 0.8522 GB, free: 13.78 MB / 0.67 GB Notification: Performance of NFEvalConstants.evaluate: time 0.001988/0.7181, allocations: 1.039 MB / 0.8532 GB, free: 12.74 MB / 0.67 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001017/0.7191, allocations: 0.7506 MB / 0.8539 GB, free: 11.98 MB / 0.67 GB Notification: Performance of NFPackage.collectConstants: time 0.0001254/0.7192, allocations: 52 kB / 0.854 GB, free: 11.93 MB / 0.67 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001712/0.7209, allocations: 0.7679 MB / 0.8547 GB, free: 11.16 MB / 0.67 GB Notification: Performance of NFScalarize.scalarize: time 0.0003624/0.7213, allocations: 314.7 kB / 0.855 GB, free: 10.86 MB / 0.67 GB Notification: Performance of NFVerifyModel.verify: time 0.0007268/0.722, allocations: 0.5479 MB / 0.8556 GB, free: 10.3 MB / 0.67 GB Notification: Performance of NFConvertDAE.convert: time 0.002159/0.7242, allocations: 1.408 MB / 0.8569 GB, free: 8.895 MB / 0.67 GB Notification: Performance of FrontEnd - DAE generated: time 5.1e-06/0.7242, allocations: 3.938 kB / 0.8569 GB, free: 8.891 MB / 0.67 GB Notification: Performance of FrontEnd: time 1.764e-06/0.7242, allocations: 4 kB / 0.857 GB, free: 8.887 MB / 0.67 GB Notification: Performance of Transformations before backend: time 1.547e-05/0.7242, allocations: 4 kB / 0.857 GB, free: 8.883 MB / 0.67 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 126 * Number of variables: 126 Notification: Performance of Generate backend data structure: time 0.002528/0.7267, allocations: 1.477 MB / 0.8584 GB, free: 7.344 MB / 0.67 GB Notification: Performance of prepare preOptimizeDAE: time 5.279e-05/0.7268, allocations: 12.03 kB / 0.8584 GB, free: 7.332 MB / 0.67 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001142/0.7269, allocations: 94.52 kB / 0.8585 GB, free: 7.23 MB / 0.67 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008231/0.7277, allocations: 195.1 kB / 0.8587 GB, free: 7.039 MB / 0.67 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001617/0.7293, allocations: 0.8578 MB / 0.8595 GB, free: 6.125 MB / 0.67 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.328e-05/0.7294, allocations: 41 kB / 0.8596 GB, free: 6.086 MB / 0.67 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001083/0.7295, allocations: 64 kB / 0.8596 GB, free: 6.023 MB / 0.67 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001444/0.7309, allocations: 0.7964 MB / 0.8604 GB, free: 5.152 MB / 0.67 GB Notification: Performance of preOpt findStateOrder (simulation): time 1.823e-05/0.731, allocations: 0 / 0.8604 GB, free: 5.152 MB / 0.67 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.57e-05/0.731, allocations: 24 kB / 0.8604 GB, free: 5.129 MB / 0.67 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.296e-05/0.7311, allocations: 19.88 kB / 0.8604 GB, free: 5.109 MB / 0.67 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0009705/0.732, allocations: 0.5375 MB / 0.861 GB, free: 4.57 MB / 0.67 GB Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts. Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.4117/1.144, allocations: 4.59 MB / 0.8655 GB, free: 173.2 MB / 0.67 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0008122/1.145, allocations: 393.9 kB / 0.8658 GB, free: 173.2 MB / 0.67 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0003851/1.145, allocations: 227.4 kB / 0.866 GB, free: 173.2 MB / 0.67 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0002612/1.145, allocations: 199.1 kB / 0.8662 GB, free: 173 MB / 0.67 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0005026/1.146, allocations: 288 kB / 0.8665 GB, free: 172.9 MB / 0.67 GB Notification: Performance of pre-optimization done (n=31): time 1.793e-06/1.146, allocations: 0 / 0.8665 GB, free: 172.9 MB / 0.67 GB Notification: Performance of matching and sorting (n=31): time 0.001442/1.147, allocations: 0.6378 MB / 0.8671 GB, free: 172.8 MB / 0.67 GB Notification: Performance of inlineWhenForInitialization (initialization): time 3.278e-05/1.147, allocations: 82.05 kB / 0.8672 GB, free: 172.7 MB / 0.67 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00145/1.149, allocations: 1.118 MB / 0.8683 GB, free: 172.3 MB / 0.67 GB Notification: Performance of collectPreVariables (initialization): time 3.75e-05/1.149, allocations: 37.89 kB / 0.8683 GB, free: 172.2 MB / 0.67 GB Notification: Performance of collectInitialEqns (initialization): time 0.0003276/1.149, allocations: 498.6 kB / 0.8688 GB, free: 171.9 MB / 0.67 GB Notification: Performance of collectInitialBindings (initialization): time 7.268e-05/1.149, allocations: 115.6 kB / 0.8689 GB, free: 171.9 MB / 0.67 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001548/1.149, allocations: 111.5 kB / 0.869 GB, free: 171.8 MB / 0.67 GB Notification: Performance of setup shared object (initialization): time 5.483e-05/1.149, allocations: 324.8 kB / 0.8693 GB, free: 171.5 MB / 0.67 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003898/1.15, allocations: 213.1 kB / 0.8695 GB, free: 171.4 MB / 0.67 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005372/1.15, allocations: 0.5195 MB / 0.8701 GB, free: 171 MB / 0.67 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0008278/1.151, allocations: 0.6259 MB / 0.8707 GB, free: 170.7 MB / 0.67 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.491e-06/1.151, allocations: 1.719 kB / 0.8707 GB, free: 170.7 MB / 0.67 GB Notification: Performance of matching and sorting (n=54) (initialization): time 0.001196/1.152, allocations: 0.7285 MB / 0.8714 GB, free: 170.4 MB / 0.67 GB Notification: Performance of prepare postOptimizeDAE: time 3.131e-05/1.152, allocations: 35.14 kB / 0.8714 GB, free: 170.4 MB / 0.67 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.888e-06/1.152, allocations: 5.188 kB / 0.8714 GB, free: 170.4 MB / 0.67 GB Notification: Performance of postOpt tearingSystem (initialization): time 2.285e-05/1.152, allocations: 13.42 kB / 0.8714 GB, free: 170.4 MB / 0.67 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004148/1.153, allocations: 110.7 kB / 0.8715 GB, free: 170.3 MB / 0.67 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.468e-05/1.153, allocations: 16.22 kB / 0.8715 GB, free: 170.3 MB / 0.67 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005568/1.153, allocations: 76.56 kB / 0.8716 GB, free: 170.3 MB / 0.67 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.417e-05/1.153, allocations: 24.83 kB / 0.8716 GB, free: 170.3 MB / 0.67 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0006775/1.154, allocations: 0.4994 MB / 0.8721 GB, free: 170.1 MB / 0.67 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0004666/1.154, allocations: 492.2 kB / 0.8726 GB, free: 169.7 MB / 0.67 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0006765/1.155, allocations: 0.5577 MB / 0.8731 GB, free: 169.4 MB / 0.67 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.376e-06/1.155, allocations: 1.906 kB / 0.8731 GB, free: 169.4 MB / 0.67 GB Notification: Performance of matching and sorting (n=54) (initialization_lambda0): time 0.0009857/1.156, allocations: 0.6453 MB / 0.8738 GB, free: 169.1 MB / 0.67 GB Notification: Performance of prepare postOptimizeDAE: time 2.532e-05/1.156, allocations: 37.25 kB / 0.8738 GB, free: 169.1 MB / 0.67 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.916e-06/1.156, allocations: 8.844 kB / 0.8738 GB, free: 169.1 MB / 0.67 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.059e-05/1.156, allocations: 8.25 kB / 0.8738 GB, free: 169.1 MB / 0.67 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003492/1.157, allocations: 103.2 kB / 0.8739 GB, free: 169 MB / 0.67 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.514e-05/1.157, allocations: 23.33 kB / 0.8739 GB, free: 169 MB / 0.67 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005414/1.157, allocations: 69 kB / 0.874 GB, free: 169 MB / 0.67 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.87e-05/1.157, allocations: 17.97 kB / 0.874 GB, free: 169 MB / 0.67 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 24 * Number of states: 0 () * Number of discrete variables: 5 (pulse_p.count,$PRE.pulse_p.count,pulse_p.T_start,$PRE.pulse_p.T_start,$whenCondition1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (53): * Single equations (assignments): 52 * Array equations: 0 * Algorithm blocks: 1 * 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.0001608/1.157, allocations: 216 kB / 0.8742 GB, free: 168.8 MB / 0.67 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001589/1.157, allocations: 105.8 kB / 0.8743 GB, free: 168.8 MB / 0.67 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0009938/1.158, allocations: 496.6 kB / 0.8748 GB, free: 168.6 MB / 0.67 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.237e-06/1.158, allocations: 7.375 kB / 0.8748 GB, free: 168.6 MB / 0.67 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 6.893e-06/1.158, allocations: 1.281 kB / 0.8748 GB, free: 168.6 MB / 0.67 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.551e-06/1.158, allocations: 4.266 kB / 0.8748 GB, free: 168.6 MB / 0.67 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002269/1.161, allocations: 1.388 MB / 0.8762 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.498e-06/1.161, allocations: 1.844 kB / 0.8762 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002664/1.161, allocations: 66.55 kB / 0.8762 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt tearingSystem (simulation): time 7.705e-06/1.161, allocations: 2.969 kB / 0.8762 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.515e-05/1.161, allocations: 13.17 kB / 0.8763 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.463e-06/1.161, allocations: 8.5 kB / 0.8763 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.328e-06/1.161, allocations: 6.094 kB / 0.8763 GB, free: 167.8 MB / 0.67 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.000817/1.162, allocations: 0.6189 MB / 0.8769 GB, free: 167.3 MB / 0.67 GB Notification: Performance of postOpt removeConstants (simulation): time 0.000288/1.162, allocations: 213.1 kB / 0.8771 GB, free: 167.2 MB / 0.67 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.892e-05/1.162, allocations: 7.438 kB / 0.8771 GB, free: 167.2 MB / 0.67 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000346/1.163, allocations: 22.5 kB / 0.8771 GB, free: 167.2 MB / 0.67 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 7.994e-05/1.163, allocations: 33.66 kB / 0.8771 GB, free: 167.1 MB / 0.67 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.175e-05/1.163, allocations: 8.625 kB / 0.8772 GB, free: 167.1 MB / 0.67 GB Notification: Performance of sorting global known variables: time 0.000875/1.164, allocations: 0.7934 MB / 0.8779 GB, free: 166.7 MB / 0.67 GB Notification: Performance of sort global known variables: time 7e-08/1.164, allocations: 0 / 0.8779 GB, free: 166.7 MB / 0.67 GB Notification: Performance of remove unused functions: time 0.001114/1.165, allocations: 409.1 kB / 0.8783 GB, free: 166.7 MB / 0.67 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 8 * Number of states: 0 () * Number of discrete variables: 3 ($whenCondition1,pulse_p.T_start,pulse_p.count) * Number of discrete states: 1 (pulse_p.count) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (31): * Single equations (assignments): 29 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 2 * 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.001155/1.166, allocations: 1.229 MB / 0.8795 GB, free: 165.7 MB / 0.67 GB Notification: Performance of simCode: created initialization part: time 0.001227/1.167, allocations: 0.7857 MB / 0.8803 GB, free: 165.3 MB / 0.67 GB Notification: Performance of simCode: created event and clocks part: time 3.887e-06/1.167, allocations: 1.422 kB / 0.8803 GB, free: 165.3 MB / 0.67 GB Notification: Performance of simCode: created simulation system equations: time 0.0003122/1.167, allocations: 195.7 kB / 0.8805 GB, free: 165.2 MB / 0.67 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001603/1.169, allocations: 457.4 kB / 0.8809 GB, free: 164.9 MB / 0.67 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009044/1.178, allocations: 8.173 MB / 0.8889 GB, free: 160.1 MB / 0.67 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004341/1.178, allocations: 1.185 MB / 0.89 GB, free: 159.1 MB / 0.67 GB Notification: Performance of simCode: alias equations: time 0.0008813/1.179, allocations: 413.9 kB / 0.8904 GB, free: 159 MB / 0.67 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003065/1.18, allocations: 325.4 kB / 0.8908 GB, free: 158.7 MB / 0.67 GB Notification: Performance of SimCode: time 6.21e-07/1.18, allocations: 0 / 0.8908 GB, free: 158.7 MB / 0.67 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.04684/1.226, allocations: 26.26 MB / 0.9164 GB, free: 133.1 MB / 0.67 GB Notification: Performance of buildModelFMU: Generate platform static: time 9.686/10.91, allocations: 8.297 kB / 0.9164 GB, free: 133.1 MB / 0.67 GB " [Timeout remaining time 649] (rm -f IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.pipe ; mkfifo IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.pipe >> ../files/IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --tempDir=temp_IDEAS_IDEAS_Fluid_Examples_Performance_PressureDrop_SeriesFlowOptimised_fmu --startTime=0 --stopTime=1 --stepSize=0.0004 --timeout=50 --tolerance=1e-06 IDEAS_IDEAS_Fluid_Examples_Performance_PressureDrop_SeriesFlowOptimised.fmu > IDEAS_IDEAS.Fluid.Examples.Performance.PressureDrop.SeriesFlowOptimised.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 14.405299640726298] Failed to read output from testmodel.py, exit status != 0: 10.994206685107201 11.004022211 10.277499965 Calling exit ...