Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1.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.001271/0.001271, allocations: 107.6 kB / 21.2 MB, free: 0.6641 MB / 14.72 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.001324/0.001324, allocations: 210.8 kB / 24.49 MB, free: 3.961 MB / 14.72 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 1.462/1.462, allocations: 230.6 MB / 258.3 MB, free: 7.801 MB / 206.1 MB " [Timeout remaining time 178] 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(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|core.B|core.Hstat|core.MagRel|winding1.i|winding2.i",fileNamePrefix="Modelica_4.1.0_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1") translateModel(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|core.B|core.Hstat|core.MagRel|winding1.i|winding2.i",fileNamePrefix="Modelica_4.1.0_Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.184e-05/2.184e-05, allocations: 2.281 kB / 378.9 MB, free: 2.523 MB / 302.1 MB Notification: Performance of NFInst.instantiate(Modelica.Magnetic.FluxTubes.Examples.Hysteresis.SinglePhaseTransformerWithHysteresis1): time 0.179/0.179, allocations: 3.189 MB / 382.1 MB, free: 13.04 MB / 302.1 MB Notification: Performance of NFInst.instExpressions: time 0.00289/0.1819, allocations: 1.614 MB / 383.7 MB, free: 12.73 MB / 302.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0002668/0.1822, allocations: 9.562 kB / 383.8 MB, free: 12.73 MB / 302.1 MB Notification: Performance of NFTyping.typeComponents: time 0.004004/0.1862, allocations: 2.659 MB / 386.4 MB, free: 12.48 MB / 302.1 MB Notification: Performance of NFTyping.typeBindings: time 0.0008629/0.187, allocations: 223.6 kB / 386.6 MB, free: 12.37 MB / 302.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.0009032/0.1879, allocations: 325.8 kB / 386.9 MB, free: 12.3 MB / 302.1 MB Notification: Performance of NFFlatten.flatten: time 0.003356/0.1913, allocations: 2.383 MB / 389.3 MB, free: 12.06 MB / 302.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0002675/0.1916, allocations: 98.12 kB / 389.4 MB, free: 12.01 MB / 302.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.001193/0.1928, allocations: 0.7566 MB / 390.2 MB, free: 11.84 MB / 302.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.00134/0.1941, allocations: 1.076 MB / 391.3 MB, free: 11.69 MB / 302.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0001044/0.1942, allocations: 44.94 kB / 391.3 MB, free: 11.69 MB / 302.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002834/0.197, allocations: 1.765 MB / 393.1 MB, free: 11.34 MB / 302.1 MB Notification: Performance of combineBinaries: time 0.001372/0.1984, allocations: 1.484 MB / 394.5 MB, free: 10.41 MB / 302.1 MB Notification: Performance of replaceArrayConstructors: time 0.000347/0.1988, allocations: 0.6855 MB / 395.2 MB, free: 9.945 MB / 302.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0001001/0.1989, allocations: 41.69 kB / 395.3 MB, free: 9.945 MB / 302.1 MB Notification: Performance of FrontEnd: time 0.0001044/0.199, allocations: 11.53 kB / 395.3 MB, free: 9.945 MB / 302.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 89 (89) * Number of variables: 89 (89) Notification: Performance of [SIM] Bindings: time 0.002397/0.2014, allocations: 2.74 MB / 398 MB, free: 8.074 MB / 302.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.0002782/0.2016, allocations: 285.7 kB / 398.3 MB, free: 7.852 MB / 302.1 MB Notification: Performance of [SIM] Early Inline: time 0.0008999/0.2025, allocations: 1.244 MB / 399.5 MB, free: 6.875 MB / 302.1 MB Notification: Performance of [SIM] Simplify 1: time 0.0003667/0.2029, allocations: 313.8 kB / 399.9 MB, free: 6.648 MB / 302.1 MB Notification: Performance of [SIM] Alias: time 0.001876/0.2048, allocations: 1.621 MB / 401.5 MB, free: 5.461 MB / 302.1 MB Notification: Performance of [SIM] Simplify 2: time 0.0002496/0.205, allocations: 234.5 kB / 401.7 MB, free: 5.277 MB / 302.1 MB Notification: Performance of [SIM] Remove Stream: time 9.787e-05/0.2051, allocations: 116.6 kB / 401.8 MB, free: 5.164 MB / 302.1 MB Notification: Performance of [SIM] Detect States: time 0.0003134/0.2054, allocations: 306.7 kB / 402.1 MB, free: 4.867 MB / 302.1 MB Notification: Performance of [SIM] Events: time 0.0001196/0.2056, allocations: 69.7 kB / 402.2 MB, free: 4.824 MB / 302.1 MB Notification: Performance of [SIM] Partitioning: time 0.0004533/0.206, allocations: 476.6 kB / 402.7 MB, free: 4.48 MB / 302.1 MB Error: Internal error NBSorting.tarjan failed to sort system: System Variables (65/65) ************************** (1|1) [ALGB] (1) Real $FUN_4 (2|2) [ALGB] (1) Real $FUN_3 (3|3) [ALGB] (1) Real $FUN_2 (4|4) [DER-] (1) Real $DER.core.B (5|5) [ALGB] (1) Real winding1.V_m (6|6) [ALGB] (1) Real $FUN_1 (7|7) [ALGB] (1) Real winding2.V_m (8|8) [DISC] (1) Boolean $SEV_0 (9|9) [ALGB] (1) protected Real core.dHyst (10|10) [DSTA] (1) protected Real[1] core.tabris.y (11|11) [DER-] (1) Real $DER.winding1.Phi_ind (12|12) [ALGB] (1) Real core.V_m (13|13) [ALGB] (1) Real winding2.p.v (14|14) [DSTA] (1) protected Real[1] core.tabris.u (15|15) [DDER] (1) Real $DER.core.hystR (16|16) [ALGB] (1) Real core.derHstat = der(core.Hstat) (start = 0.0) (17|17) [ALGB] (1) Real vSource.v (18|18) [ALGB] (1) Real winding2.Phi_leak (StateSelect = never) (19|19) [ALGB] (1) Real winding1.Phi_leak (StateSelect = never) (20|20) [ALGB] (1) protected Real core.k (21|21) [ALGB] (1) Real core.Heddy (22|22) [ALGB] (1) Real core.Phi (start = 0.0) (23|23) [ALGB] (1) Real vSource.p.v (24|24) [ALGB] (1) Real vSource.i (fixed = true, start = 0.0) (25|25) [ALGB] (1) protected Real core.diffHyst (26|26) [ALGB] (1) Real core.LossPower (27|27) [ALGB] (1) Real resistor2.LossPower (28|28) [ALGB] (1) Real resistor1.LossPower (29|29) [ALGB] (1) Real core.LossPowerEddy (30|30) [DER-] (1) Real $DER.winding2.Phi_ind (31|31) [DDER] (1) Real $DER.core.Hstat (32|32) [DISC] (1) Boolean core.asc (fixed = false, start = true) (33|33) [ALGB] (1) Real winding1.L_stat (34|34) [ALGB] (1) Real resistor1.n.v (35|35) [ALGB] (1) Real winding2.L_stat (36|36) [DSTA] (1) protected Real[1] core.tabfal.y (37|37) [ALGB] (1) Real resistor2.v (38|38) [ALGB] (1) Real resistor1.v (39|39) [STAT] (1) protected Real[1] core.tabfal.u (40|40) [DISC] (1) Boolean $TEV_0 (41|41) [ALGB] (1) Real winding2.v (42|42) [ALGB] (1) Real winding1.v (43|43) [ALGB] (1) Real core.H (start = 0.0) (44|44) [ALGB] (1) Real winding1.Psi (45|45) [ALGB] (1) Real resistor2.i (fixed = true, start = 0.0) (46|46) [ALGB] (1) Real winding2.Psi (47|47) [ALGB] (1) Real core.LossPowerStat (48|48) [DDER] (1) Real $DER.core.hystF (49|49) [ALGB] (1) Real winding1.port_p.V_m (50|50) [ALGB] (1) Real core.port_n.V_m (51|51) [DSTA] (1) Real $FUN_8 (52|52) [ALGB] (1) Real resistor1.R_actual (53|53) [DSTA] (1) Real $FUN_7 (54|54) [ALGB] (1) Real resistor2.R_actual (55|55) [ALGB] (1) Real $FUN_6 (56|56) [ALGB] (1) Real $FUN_5 (57|57) [DDER] (1) Real $DER.$FUN_7 (58|58) [DDER] (1) Real[1] $DER.core.tabris.y (59|59) [DDER] (1) Real[1] $DER.core.tabris.u (60|60) [DER-] (1) Real[1] $DER.core.tabfal.u (61|61) [DDER] (1) Real $DER.$FUN_8 (62|62) [DDER] (1) Real[1] $DER.core.tabfal.y (63|63) [DSTA] (1) protected Real core.hystR (64|64) [DSTA] (1) protected Real core.hystF (65|65) [DSTA] (1) Real core.Hstat System Equations (64/64) ************************** (1|1) [SCAL] (1) core.Phi = core.B * core.A ($RES_SIM_10) (2|2) [SCAL] (1) core.H = core.V_m / core.l ($RES_SIM_11) (3|3) [SCAL] (1) core.H = core.Hstat + core.Heddy ($RES_SIM_12) (4|4) [SCAL] (1) core.k = if initial() then 0.01 else if core.asc then max(0.01, (core.hystF - core.B) / core.diffHyst) else max(0.01, (core.B - core.hystR) / core.diffHyst) ($RES_SIM_14) (5|5) [SCAL] (1) core.tabris.y[1] = $FUN_7 ($RES_SIM_30) (6|6) [SCAL] (1) $FUN_8 = Modelica.Blocks.Tables.Internal.getTable1DValue(core.tabfal.tableID, 1, core.tabfal.u[1]) ($RES_AUX_99) (7|7) [SCAL] (1) resistor2.v = winding2.p.v ($RES_SIM_32) (8|8) [SCAL] (1) core.dHyst = if initial() then 0.0 else if core.asc then $DER.core.hystR - core.mu0 * $DER.core.Hstat else $DER.core.hystF - core.mu0 * $DER.core.Hstat ($RES_SIM_18) (9|9) [SCAL] (1) winding2.v = winding2.p.v ($RES_SIM_51) (10|10) [SCAL] (1) resistor2.LossPower = resistor2.v * resistor2.i ($RES_SIM_36) (11|11) [SCAL] (1) vSource.v = vSource.p.v ($RES_SIM_52) (12|12) [SCAL] (1) resistor2.v = resistor2.R_actual * resistor2.i ($RES_SIM_37) (13|13) [SCAL] (1) resistor2.R_actual = resistor2.R * (1.0 + resistor2.alpha * (resistor2.T - resistor2.T_ref)) ($RES_SIM_38) (14|14) [SCAL] (1) winding1.N * $DER.winding1.Phi_ind = -winding1.v ($RES_SIM_70) (15|15) [SCAL] (1) winding1.V_m = -vSource.i * winding1.N ($RES_SIM_71) (16|16) [SCAL] (1) vSource.v = vSource.signalSource.offset + (if $TEV_0 then 0.0 else vSource.signalSource.amplitude * sin(vSource.signalSource.phase + 6.283185307179586 * (time - vSource.signalSource.startTime) * vSource.signalSource.f)) ($RES_SIM_56) (17|17) [SCAL] (1) resistor1.v = vSource.p.v - resistor1.n.v ($RES_SIM_57) (18|18) [SCAL] (1) winding1.V_m = winding1.port_p.V_m ($RES_SIM_74) (19|19) [SCAL] (1) winding1.v = resistor1.n.v ($RES_SIM_77) (20|20) [SCAL] (1) core.diffHyst = core.hystF - core.hystR ($RES_SIM_22) (21|21) [SCAL] (1) $DER.core.B = core.k * core.dHyst + core.mu0 * $DER.core.Hstat ($RES_SIM_23) (22|22) [SCAL] (1) $TEV_0 = time < vSource.signalSource.startTime ($RES_EVT_107) (23|23) [SCAL] (1) core.asc = $SEV_0 ($RES_SIM_24) (24|24) [SCAL] (1) $SEV_0 = $DER.core.Hstat > 0.0 ($RES_EVT_108) (25|25) [SCAL] (1) winding2.L_stat = noEvent(if $FUN_4 > 2.220446049250313e-14 then $FUN_5 else $FUN_6) ($RES_SIM_40) (26|26) [SCAL] (1) core.hystF = core.mu0 * core.Hstat + core.tabfal.y[1] + core.eps ($RES_SIM_25) (27|27) [SCAL] (1) winding2.Psi = winding2.N * winding2.Phi_ind ($RES_SIM_41) (28|28) [SCAL] (1) core.hystR = (core.mu0 * core.Hstat + core.tabris.y[1]) - core.eps ($RES_SIM_26) (29|29) [SCAL] (1) core.tabfal.u[1] = core.Hstat ($RES_SIM_27) (30|30) [SCAL] (1) core.Phi = winding2.Phi_ind + winding2.Phi_leak ($RES_SIM_42) (31|31) [SCAL] (1) core.tabris.u[1] = core.Hstat ($RES_SIM_28) (32|32) [SCAL] (1) winding2.Phi_leak = winding2.G_m * winding2.V_m ($RES_SIM_43) (33|33) [SCAL] (1) core.tabfal.y[1] = $FUN_8 ($RES_SIM_29) (34|34) [SCAL] (1) winding2.N * $DER.winding2.Phi_ind = -winding2.v ($RES_SIM_44) (35|35) [SCAL] (1) winding2.V_m = -resistor2.i * winding2.N ($RES_SIM_45) (36|36) [SCAL] (1) resistor1.LossPower = -resistor1.v * vSource.i ($RES_SIM_61) (37|37) [SCAL] (1) resistor1.v = -resistor1.R_actual * vSource.i ($RES_SIM_62) (38|38) [SCAL] (1) resistor1.R_actual = resistor1.R * (1.0 + resistor1.alpha * (resistor1.T - resistor1.T_ref)) ($RES_SIM_63) (39|39) [SCAL] (1) winding2.V_m = core.port_n.V_m ($RES_SIM_48) (40|40) [SCAL] (1) core.Heddy = core.eddyCurrentFactor * $DER.core.B ($RES_SIM_9) (41|41) [SCAL] (1) $FUN_7 = Modelica.Blocks.Tables.Internal.getTable1DValue(core.tabris.tableID, 1, core.tabris.u[1]) ($RES_AUX_100) (42|42) [SCAL] (1) core.LossPowerStat = $DER.core.B * core.Hstat * core.V ($RES_SIM_8) (43|43) [SCAL] (1) $FUN_6 = abs(4.503599627370496e13 * winding2.Psi) ($RES_AUX_101) (44|44) [SCAL] (1) winding1.L_stat = noEvent(if $FUN_1 > 2.220446049250313e-14 then $FUN_2 else $FUN_3) ($RES_SIM_66) (45|45) [SCAL] (1) core.LossPowerEddy = $DER.core.B * core.Heddy * core.V ($RES_SIM_7) (46|46) [SCAL] (1) $FUN_5 = abs(-winding2.Psi / resistor2.i) ($RES_AUX_102) (47|47) [SCAL] (1) winding1.Psi = winding1.N * winding1.Phi_ind ($RES_SIM_67) (48|48) [SCAL] (1) core.LossPower = core.LossPowerStat + core.LossPowerEddy ($RES_SIM_6) (49|49) [SCAL] (1) $FUN_4 = abs(-resistor2.i) ($RES_AUX_103) (50|50) [SCAL] (1) -core.Phi = winding1.Phi_ind + winding1.Phi_leak ($RES_SIM_68) (51|51) [SCAL] (1) $FUN_3 = abs(4.503599627370496e13 * winding1.Psi) ($RES_AUX_104) (52|52) [SCAL] (1) winding1.Phi_leak = winding1.G_m * winding1.V_m ($RES_SIM_69) (53|53) [SCAL] (1) $FUN_2 = abs(-winding1.Psi / vSource.i) ($RES_AUX_105) (54|54) [SCAL] (1) core.V_m = winding1.port_p.V_m - core.port_n.V_m ($RES_SIM_3) (55|55) [SCAL] (1) $FUN_1 = abs(-vSource.i) ($RES_AUX_106) (56|56) [SCAL] (1) core.derHstat = $DER.core.Hstat ($RES_BND_98) (57|57) [SCAL] (1) $DER.core.hystF = core.mu0 * core.derHstat + $DER.core.tabfal.y[1] ($RES_SIM_111) (58|58) [SCAL] (1) $DER.core.hystR = core.mu0 * core.derHstat + $DER.core.tabris.y[1] ($RES_SIM_112) (59|59) [SCAL] (1) $DER.$FUN_7 = Modelica.Blocks.Tables.Internal.getDerTable1DValue(core.tabris.tableID, 1, core.tabris.u[1], $DER.core.tabris.u[1]) ($RES_SIM_113) (60|60) [SCAL] (1) $DER.core.tabfal.u[1] = core.derHstat ($RES_SIM_114) (61|61) [SCAL] (1) $DER.core.tabris.y[1] = $DER.$FUN_7 ($RES_SIM_115) (62|62) [SCAL] (1) $DER.core.tabris.u[1] = core.derHstat ($RES_SIM_116) (63|63) [SCAL] (1) $DER.$FUN_8 = Modelica.Blocks.Tables.Internal.getDerTable1DValue(core.tabfal.tableID, 1, core.tabfal.u[1], $DER.core.tabfal.u[1]) ($RES_SIM_117) (64|64) [SCAL] (1) $DER.core.tabfal.y[1] = $DER.$FUN_8 ($RES_SIM_118) =================== Scalar Matching =================== variable to equation ********************** var 1 --> eqn 49 var 2 --> eqn 51 var 3 --> eqn 53 var 4 --> eqn 40 var 5 --> eqn 52 var 6 --> eqn 55 var 7 --> eqn 32 var 8 --> eqn 24 var 9 --> eqn 21 var 10 --> eqn 5 var 11 --> eqn 14 var 12 --> eqn 54 var 13 --> eqn 7 var 14 --> eqn 31 var 15 --> eqn 58 var 16 --> eqn 57 var 17 --> eqn 16 var 18 --> eqn 30 var 19 --> eqn 50 var 20 --> eqn 4 var 21 --> eqn 45 var 22 --> eqn 1 var 23 --> eqn 11 var 24 --> eqn 15 var 25 --> eqn 20 var 26 --> eqn -1 var 27 --> eqn 10 var 28 --> eqn 36 var 29 --> eqn 48 var 30 --> eqn 34 var 31 --> eqn 56 var 32 --> eqn 23 var 33 --> eqn 44 var 34 --> eqn 17 var 35 --> eqn 25 var 36 --> eqn 33 var 37 --> eqn 12 var 38 --> eqn 37 var 39 --> eqn 29 var 40 --> eqn 22 var 41 --> eqn 9 var 42 --> eqn 19 var 43 --> eqn 2 var 44 --> eqn 47 var 45 --> eqn 35 var 46 --> eqn 27 var 47 --> eqn 42 var 48 --> eqn 8 var 49 --> eqn 18 var 50 --> eqn 39 var 51 --> eqn 6 var 52 --> eqn 38 var 53 --> eqn 41 var 54 --> eqn 13 var 55 --> eqn 43 var 56 --> eqn 46 var 57 --> eqn 59 var 58 --> eqn 61 var 59 --> eqn 62 var 60 --> eqn 60 var 61 --> eqn 63 var 62 --> eqn 64 var 63 --> eqn 28 var 64 --> eqn 26 var 65 --> eqn 3 equation to variable ********************** eqn 1 --> var 22 eqn 2 --> var 43 eqn 3 --> var 65 eqn 4 --> var 20 eqn 5 --> var 10 eqn 6 --> var 51 eqn 7 --> var 13 eqn 8 --> var 48 eqn 9 --> var 41 eqn 10 --> var 27 eqn 11 --> var 23 eqn 12 --> var 37 eqn 13 --> var 54 eqn 14 --> var 11 eqn 15 --> var 24 eqn 16 --> var 17 eqn 17 --> var 34 eqn 18 --> var 49 eqn 19 --> var 42 eqn 20 --> var 25 eqn 21 --> var 9 eqn 22 --> var 40 eqn 23 --> var 32 eqn 24 --> var 8 eqn 25 --> var 35 eqn 26 --> var 64 eqn 27 --> var 46 eqn 28 --> var 63 eqn 29 --> var 39 eqn 30 --> var 18 eqn 31 --> var 14 eqn 32 --> var 7 eqn 33 --> var 36 eqn 34 --> var 30 eqn 35 --> var 45 eqn 36 --> var 28 eqn 37 --> var 38 eqn 38 --> var 52 eqn 39 --> var 50 eqn 40 --> var 4 eqn 41 --> var 53 eqn 42 --> var 47 eqn 43 --> var 55 eqn 44 --> var 33 eqn 45 --> var 21 eqn 46 --> var 56 eqn 47 --> var 44 eqn 48 --> var 29 eqn 49 --> var 1 eqn 50 --> var 19 eqn 51 --> var 2 eqn 52 --> var 5 eqn 53 --> var 3 eqn 54 --> var 12 eqn 55 --> var 6 eqn 56 --> var 31 eqn 57 --> var 16 eqn 58 --> var 15 eqn 59 --> var 57 eqn 60 --> var 60 eqn 61 --> var 58 eqn 62 --> var 59 eqn 63 --> var 61 eqn 64 --> var 62 " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 2.411577992141247] Failed to read output from testmodel.py, exit status != 0: ['time', 'core.B', 'core.Hstat', 'core.MagRel', 'winding1.i', 'winding2.i'] 0.21629565209150314 0.23044275899999994 0.028469799 Calling exit ...