Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.StateGraph.Examples.ShowCompositeStep.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.001295/0.001295, 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.001402/0.001402, allocations: 205.2 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.458/1.458, 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.StateGraph.Examples.ShowCompositeStep,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5022,variableFilter="time|compositeStep.transition3.enableFire|compositeStep.transition4.enableFire|compositeStep.transition4a.enableFire|compositeStep.transition5.enableFire|compositeStep.transition6.enableFire|compositeStep.transition6a.enableFire|transition1.enableFire|transition2.enableFire|transition7.enableFire",fileNamePrefix="Modelica_4.1.0_Modelica.StateGraph.Examples.ShowCompositeStep") translateModel(Modelica.StateGraph.Examples.ShowCompositeStep,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5022,variableFilter="time|compositeStep.transition3.enableFire|compositeStep.transition4.enableFire|compositeStep.transition4a.enableFire|compositeStep.transition5.enableFire|compositeStep.transition6.enableFire|compositeStep.transition6a.enableFire|transition1.enableFire|transition2.enableFire|transition7.enableFire",fileNamePrefix="Modelica_4.1.0_Modelica.StateGraph.Examples.ShowCompositeStep") [Timeout 660] "Notification: Performance of FrontEnd - Absyn->SCode: time 2.677e-05/2.677e-05, allocations: 6.219 kB / 378.9 MB, free: 2.555 MB / 302.1 MB Notification: Performance of NFInst.instantiate(Modelica.StateGraph.Examples.ShowCompositeStep): time 0.004114/0.00414, allocations: 2.485 MB / 381.4 MB, free: 60 kB / 302.1 MB Notification: Performance of NFInst.instExpressions: time 0.1957/0.1998, allocations: 1.06 MB / 382.5 MB, free: 13.02 MB / 302.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0002432/0.2, allocations: 26.47 kB / 382.5 MB, free: 13.02 MB / 302.1 MB Notification: Performance of NFTyping.typeComponents: time 0.0002756/0.2003, allocations: 118.3 kB / 382.6 MB, free: 13 MB / 302.1 MB Notification: Performance of NFTyping.typeBindings: time 0.0003011/0.2006, allocations: 85.92 kB / 382.7 MB, free: 12.95 MB / 302.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.001038/0.2017, allocations: 0.5927 MB / 383.3 MB, free: 12.93 MB / 302.1 MB Notification: Performance of NFFlatten.flatten: time 0.001582/0.2032, allocations: 1.509 MB / 384.8 MB, free: 12.38 MB / 302.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.00117/0.2044, allocations: 0.7608 MB / 385.5 MB, free: 12.03 MB / 302.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0006516/0.2051, allocations: 0.5721 MB / 386.1 MB, free: 11.75 MB / 302.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0005344/0.2056, allocations: 0.5292 MB / 386.6 MB, free: 11.47 MB / 302.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0002064/0.2058, allocations: 142.7 kB / 386.8 MB, free: 11.47 MB / 302.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0002646/0.2061, allocations: 156 kB / 386.9 MB, free: 11.46 MB / 302.1 MB Notification: Performance of combineBinaries: time 0.001081/0.2072, allocations: 1.775 MB / 388.7 MB, free: 10.22 MB / 302.1 MB Notification: Performance of replaceArrayConstructors: time 0.0004713/0.2076, allocations: 1.02 MB / 389.7 MB, free: 9.52 MB / 302.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0001402/0.2078, allocations: 58.22 kB / 389.8 MB, free: 9.52 MB / 302.1 MB Notification: Performance of FrontEnd: time 0.0002097/0.208, allocations: 171.6 kB / 390 MB, free: 9.461 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: 261 (255) * Number of variables: 261 (249) Notification: Performance of [SIM] Bindings: time 0.00379/0.2118, allocations: 3.989 MB / 394 MB, free: 6.836 MB / 302.1 MB Notification: Performance of [SIM] FunctionAlias: time 0.0009439/0.2127, allocations: 0.903 MB / 394.9 MB, free: 6.215 MB / 302.1 MB Notification: Performance of [SIM] Early Inline: time 0.003307/0.216, allocations: 3.222 MB / 398.1 MB, free: 4.246 MB / 302.1 MB Notification: Performance of [SIM] Simplify 1: time 0.0009731/0.217, allocations: 0.5593 MB / 398.6 MB, free: 3.871 MB / 302.1 MB Notification: Performance of [SIM] Alias: time 0.005236/0.2222, allocations: 4.29 MB / 402.9 MB, free: 1.004 MB / 302.1 MB Notification: Performance of [SIM] Simplify 2: time 0.0005716/0.2228, allocations: 410.9 kB / 403.3 MB, free: 0.6797 MB / 302.1 MB Notification: Performance of [SIM] Remove Stream: time 0.0002932/0.2231, allocations: 310.4 kB / 403.6 MB, free: 424 kB / 302.1 MB Notification: Performance of [SIM] Detect States: time 0.001279/0.2244, allocations: 0.9962 MB / 404.6 MB, free: 15.54 MB / 318.1 MB Notification: Performance of [SIM] Events: time 0.0005771/0.2249, allocations: 371.2 kB / 405 MB, free: 15.27 MB / 318.1 MB Notification: Performance of [SIM] Partitioning: time 0.001583/0.2265, allocations: 1.157 MB / 406.1 MB, free: 14.57 MB / 318.1 MB Notification: Performance of [SIM] Causalize: time 0.005258/0.2318, allocations: 3.451 MB / 409.6 MB, free: 13.35 MB / 318.1 MB Notification: Performance of [SIM] After Index Reduction Inline: time 0.00255/0.2343, allocations: 2.401 MB / 412 MB, free: 11.11 MB / 318.1 MB Notification: Performance of [INI] Simplify: time 0.002087/0.2364, allocations: 1.083 MB / 413.1 MB, free: 9.848 MB / 318.1 MB Notification: Performance of [INI] Inline: time 0.003369/0.2398, allocations: 3.228 MB / 416.3 MB, free: 6.602 MB / 318.1 MB Notification: Performance of [INI] Partitioning: time 0.0002018/0.24, allocations: 88.34 kB / 416.4 MB, free: 6.484 MB / 318.1 MB Notification: Performance of [INI] Cleanup: time 0.0005052/0.2405, allocations: 0.5425 MB / 416.9 MB, free: 5.941 MB / 318.1 MB Notification: Performance of [INI] Causalize: time 0.01075/0.2512, allocations: 6.996 MB / 423.9 MB, free: 14.7 MB / 334.1 MB Notification: Performance of [INI] Tearing: time 0.0008962/0.2521, allocations: 0.6491 MB / 424.6 MB, free: 14.04 MB / 334.1 MB Notification: Performance of [SIM] Initialization: time 1.362e-06/0.2521, allocations: 0 / 424.6 MB, free: 14.04 MB / 334.1 MB Notification: Performance of [SIM] Remove Dummies: time 3.881e-05/0.2522, allocations: 6 kB / 424.6 MB, free: 14.03 MB / 334.1 MB Notification: Performance of [SIM] Tearing: time 0.001031/0.2532, allocations: 0.5992 MB / 425.2 MB, free: 13.41 MB / 334.1 MB Notification: Performance of [SIM] Categorize: time 3.294e-05/0.2532, allocations: 4 kB / 425.2 MB, free: 13.41 MB / 334.1 MB Error: Internal error NFExpression.makeOne failed for: Boolean Error: Internal error NBSolve.solveStrongComponent failed with status = Solve.UNSOLVABLE while trying to solve following strong component: BLOCK: Resizable Component (status = Solve.UNPROCESSED, size = 3) ------------------------------------------------------------------- ### Variable: $SEV_18[$i1] ### Equation: [FOR-] (3) ($RES_SIM_103) [----] for $i1 in 1:3 loop [----] [SCAL] (1) compositeStep.Alternative1.split[$i1].available = if $SEV_17[$i1] then compositeStep.Alternative1.inPort.available else $SEV_18[$i1] ($RES_SIM_104) [----] end for; Error: Internal error NBSolve.solveStrongComponent failed with status = Solve.UNSOLVABLE while trying to solve following strong component: BLOCK: Algebraic Loop (Linear = false, Mixed = true, Homotopy = false, size = 15) ----------------------------------------------------------------------------------- Strict Tearing Set ******************** ### Iteration Variables: [DISC] (1) Boolean compositeStep.transition4.inPort.available; ### Residual Equations: ### Inner Equations: BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean compositeStep.transition3.inPort.available ### Equation: [SCAL] (1) compositeStep.transition3.inPort.available = compositeStep.Alternative1.split[1].available ($RES_SIM_232) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean $SEV_1 ### Equation: [SCAL] (1) $SEV_1 = compositeStep.transition3.inPort.available and not compositeStep.transition3.outPort.occupied ($RES_EVT_355) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean compositeStep.transition3.enableFire ### Equation: [SCAL] (1) compositeStep.transition3.enableFire = $SEV_1 ($RES_SIM_177) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean $SEV_0 ### Equation: [SCAL] (1) $SEV_0 = compositeStep.transition3.enableFire and time >= (compositeStep.transition3.t_start + compositeStep.transition3.waitTime) ($RES_EVT_354) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean compositeStep.transition3.fire ### Equation: [SCAL] (1) compositeStep.transition3.fire = $SEV_0 ($RES_SIM_178) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean $SEV_3 ### Equation: [SCAL] (1) $SEV_3 = compositeStep.transition4.inPort.available and not compositeStep.transition4.outPort.occupied ($RES_EVT_357) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean compositeStep.transition4.enableFire ### Equation: [SCAL] (1) compositeStep.transition4.enableFire = $SEV_3 ($RES_SIM_170) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean $SEV_2 ### Equation: [SCAL] (1) $SEV_2 = compositeStep.transition4.enableFire and time >= (compositeStep.transition4.t_start + compositeStep.transition4.waitTime) ($RES_EVT_356) BLOCK: Single Strong Component (status = Solve.UNPROCESSED, size = 1) ----------------------------------------------------------------------- ### Variable: Boolean compositeStep.transition4.fire ### Equation: [SCAL] (1) compositeStep.transition4.fire = $SEV_2 ($RES_SIM_171) BLOCK: Sliced Component (status = Solve.UNPROCESSED, size = 1) ---------------------------------------------------------------- ### Variable: compositeStep.Alternative1.split[2].available ### Equation: [SCAL] (1) compositeStep.transition4.inPort.available = compositeStep.Alternative1.split[2].available ($RES_SIM_230) BLOCK: Sliced Component (status = Solve.UNPROCESSED, size = 1) ---------------------------------------------------------------- ### Variable: compositeStep.Alternative1.split[1].reset ### Equation: [SCAL] (1) compositeStep.transition3.fire = compositeStep.Alternative1.split[1].reset ($RES_SIM_231) BLOCK: Sliced Component (status = Solve.UNPROCESSED, size = 1) ---------------------------------------------------------------- ### Variable: compositeStep.Alternative1.split[2].reset ### Equation: [SCAL] (1) compositeStep.transition4.fire = compositeStep.Alternative1.split[2].reset ($RES_SIM_229) BLOCK: Resizable Component (status = Solve.UNPROCESSED, size = 3) ------------------------------------------------------------------- ### Variable: $SEV_18[$i1] ### Equation: [FOR-] (3) ($RES_SIM_103) [----] for $i1 in 1:3 loop [----] [SCAL] (1) compositeStep.Alternative1.split[$i1].available = if $SEV_17[$i1] then compositeStep.Alternative1.inPort.available else $SEV_18[$i1] ($RES_SIM_104) [----] end for; " [Timeout remaining time 660] [Calling sys.exit(0), Time elapsed: 2.4519944763742387] Failed to read output from testmodel.py, exit status != 0: ['time', 'compositeStep.transition3.enableFire', 'compositeStep.transition4.enableFire', 'compositeStep.transition4a.enableFire', 'compositeStep.transition5.enableFire', 'compositeStep.transition6.enableFire', 'compositeStep.transition6a.enableFire', 'transition1.enableFire', 'transition2.enableFire', 'transition7.enableFire'] 0.26233825320377946 0.278623388 0.067460983 Calling exit ...