R Under development (unstable) (2026-03-12 r89611 ucrt) -- "Unsuffered Consequences" Copyright (C) 2026 The R Foundation for Statistical Computing Platform: x86_64-w64-mingw32/x64 R is free software and comes with ABSOLUTELY NO WARRANTY. You are welcome to redistribute it under certain conditions. Type 'license()' or 'licence()' for distribution details. R is a collaborative project with many contributors. Type 'contributors()' for more information and 'citation()' on how to cite R or R packages in publications. Type 'demo()' for some demos, 'help()' for on-line help, or 'help.start()' for an HTML browser interface to help. Type 'q()' to quit R. > #! /usr/bin/env Rscript > ## This runs testme test script inst/testme/test-capture_journals.R > ## Don't edit - it was autogenerated by inst/testme/deploy.R > future:::testme("capture_journals") Test 'capture_journals' ... chr "none" > library(future) [22:16:43.847] Option 'future.startup.script': TRUE [22:16:43.848] Future startup scripts considered: '.future.R', '~/.future.R' [22:16:43.849] Future startup scripts found: > capture_journals <- future:::capture_journals > message("*** capture_journals() ...") *** capture_journals() ... > slow_fcn <- function(x) { + Sys.sleep(0.01 * (1 + 1/x)) + } > plan(multisession, workers = 2) [22:16:43.850] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:43.853] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:43.854] | : all.equal() for FutureStrategyList ... [22:16:43.855] | : . New stack: [22:16:43.855] | : . List of 1 [22:16:43.855] | : . $ :function (..., workers = 2) [22:16:43.855] | : . ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:43.855] | : . ..- attr(*, "init")= logi TRUE [22:16:43.855] | : . ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:43.855] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:43.855] | : . .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.855] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.855] | : . ..- attr(*, "untweakable")= chr "persistent" [22:16:43.855] | : . ..- attr(*, "tweaks")=List of 1 [22:16:43.855] | : . .. ..$ workers: num 2 [22:16:43.855] | : . ..- attr(*, "call")= language plan(multisession, workers = 2) [22:16:43.855] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:43.860] | : . Old stack: [22:16:43.861] | : . List of 1 [22:16:43.861] | : . $ :function (..., envir = parent.frame()) [22:16:43.861] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:43.861] | : . ..- attr(*, "init")= logi TRUE [22:16:43.861] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:43.861] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:43.861] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:43.861] | : . ..- attr(*, "call")= language plan(sequential) [22:16:43.861] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:43.864] | : . Not identical [22:16:43.865] | : . all.equal() for future ... [22:16:43.865] | : . ' List of 2 [22:16:43.865] | : . ' $ target :function (..., workers = 2) [22:16:43.865] | : . ' ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:43.865] | : . ' ..- attr(*, "init")= logi TRUE [22:16:43.865] | : . ' ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:43.865] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:43.865] | : . ' .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.865] | : . ' ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.865] | : . ' ..- attr(*, "untweakable")= chr "persistent" [22:16:43.865] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:43.865] | : . ' .. ..$ workers: num 2 [22:16:43.865] | : . ' ..- attr(*, "call")= language plan(multisession, workers = 2) [22:16:43.865] | : . ' $ current:function (..., envir = parent.frame()) [22:16:43.865] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:43.865] | : . ' ..- attr(*, "init")= logi TRUE [22:16:43.865] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:43.865] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:43.865] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:43.865] | : . ' ..- attr(*, "call")= language plan(sequential) [22:16:43.872] | : . ' Formals differ [22:16:43.872] | : . all.equal() for future ... done [22:16:43.873] | : . Future strategies differ at level 1 [22:16:43.873] | : all.equal() for FutureStrategyList ... done [22:16:43.874] | : plan(): Setting new future backend stack: [22:16:43.874] | : List of future strategies: [22:16:43.874] | : 1. multisession: [22:16:43.874] | : - args: function (..., workers = 2) [22:16:43.874] | : - tweaked: TRUE [22:16:43.874] | : - call: plan(multisession, workers = 2) [22:16:43.875] | : List of 1 [22:16:43.875] | : $ :function (..., workers = 2) [22:16:43.875] | : ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:43.875] | : ..- attr(*, "init")= logi TRUE [22:16:43.875] | : ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:43.875] | : interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:43.875] | : .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.875] | : ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.875] | : ..- attr(*, "untweakable")= chr "persistent" [22:16:43.875] | : ..- attr(*, "tweaks")=List of 1 [22:16:43.875] | : .. ..$ workers: num 2 [22:16:43.875] | : ..- attr(*, "call")= language plan(multisession, workers = 2) [22:16:43.875] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:43.881] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... [22:16:43.881] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:43.882] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [22:16:43.882] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:43.883] | : . Legacy shutdown of cluster workers ... [22:16:43.884] | : . ' Stopping existing cluster ... [22:16:43.884] | : . ' , No pre-existing cluster. Skipping [22:16:43.885] | : . ' Stopping existing cluster ... done [22:16:43.885] | : . Legacy shutdown of cluster workers ... done [22:16:43.886] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:43.886] | : plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... [22:16:43.887] | : . function (..., workers = 2) [22:16:43.887] | : . - attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:43.887] | : . - attr(*, "init")= logi TRUE [22:16:43.887] | : . - attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:43.887] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:43.887] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.887] | : . - attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:43.887] | : . - attr(*, "untweakable")= chr "persistent" [22:16:43.887] | : . - attr(*, "tweaks")=List of 1 [22:16:43.887] | : . ..$ workers: num 2 [22:16:43.887] | : . - attr(*, "call")= language plan(multisession, workers = 2) [22:16:43.891] | : . init: TRUE [22:16:43.891] | : . makeFutureBackend() ... [22:16:43.892] | : . ' Backend function: <'NULL'> [22:16:43.892] | : . ' Evaluator tweak arguments: [n=1] [22:16:43.893] | : . ' List of 1 [22:16:43.893] | : . ' $ workers: num 2 [22:16:43.894] | : . ' Evaluator formal arguments: [n=1] [22:16:43.895] | : . ' Dotted pair list of 1 [22:16:43.895] | : . ' $ workers: num 2 [22:16:43.896] | : . ' Arguments passed to the future-backend factory: [n=1] [22:16:43.896] | : . ' List of 1 [22:16:43.896] | : . ' $ workers: num 2 [22:16:43.897] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... [22:16:43.898] | : . ' , Arguments: [22:16:43.898] | : . ' , List of 5 [22:16:43.898] | : . ' , $ workers : num 2 [22:16:43.898] | : . ' , $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:16:43.898] | : . ' , $ interrupts : logi TRUE [22:16:43.898] | : . ' , $ gc : logi FALSE [22:16:43.898] | : . ' , $ earlySignal : logi FALSE [22:16:43.901] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... [22:16:43.902] | : . ' , ; workers: 2 [22:16:43.902] | : . ' , ; getCluster() ... [22:16:43.903] | : . ' , ; getCluster() ... done [22:16:43.903] | : . ' , ; Stopping existing cluster ... [22:16:43.904] | : . ' , ; ` No pre-existing cluster. Skipping [22:16:43.904] | : . ' , ; Stopping existing cluster ... done [22:16:43.905] | : . ' , ; Starting new cluster ... [22:16:43.905] | : . ' , ; ` makeCluster(workers, ...) ... [22:16:43.906] | : . ' , ; ` | Arguments: [22:16:43.906] | : . ' , ; ` | List of 2 [22:16:43.906] | : . ' , ; ` | $ : int 2 [22:16:43.906] | : . ' , ; ` | $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:16:43.908] | : . ' , ; ` | .makeCluster() ... [22:16:43.908] | : . ' , ; ` | : workers: '2' [22:16:43.909] | : . ' , ; ` | : R_FUTURE_PLAN: NA [22:16:43.909] | : . ' , ; ` | : parallelly::makeClusterPSOCK() arguments: [22:16:43.910] | : . ' , ; ` | : List of 2 [22:16:43.910] | : . ' , ; ` | : $ : int 2 [22:16:43.910] | : . ' , ; ` | : $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:16:44.719] | : . ' , ; ` | : Generated cluster UUID [22:16:44.720] | : . ' , ; ` | : Cluster UUID: 'd8392266062df92ffda370863bed683b' [22:16:44.720] | : . ' , ; ` | : Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:44.724] | : . ' , ; ` | .makeCluster() ... done [22:16:44.724] | : . ' , ; ` | Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:44.726] | : . ' , ; ` makeCluster(workers, ...) ... done [22:16:44.727] | : . ' , ; ` Number of workers: 2 [22:16:44.728] | : . ' , ; ` Workers UUID: 'd8392266062df92ffda370863bed683b' [22:16:44.728] | : . ' , ; Starting new cluster ... done [22:16:44.729] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... done [22:16:44.729] | : . ' , Backend: <'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [22:16:44.730] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... done [22:16:44.730] | : . makeFutureBackend() ... done [22:16:44.731] | : . nbrOfWorkers(NULL) ... [22:16:44.731] | : . ' List of 4 [22:16:44.731] | : . ' $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:44.731] | : . ' $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:44.731] | : . ' $ : language nbrOfWorkers() [22:16:44.731] | : . ' $ : language nbrOfWorkers.NULL() [22:16:44.734] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.735] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.735] | : . ' Number of workers: 2 [22:16:44.736] | : . nbrOfWorkers(NULL) ... done [22:16:44.736] | : . plan(): nbrOfWorkers() = 2 [22:16:44.737] | : plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... done [22:16:44.737] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done > js <- capture_journals({ + fs <- lapply(3:1, FUN = function(x) future(slow_fcn(x))) + vs <- value(fs) + }) [22:16:44.738] | future(..., label = NULL) ... [22:16:44.738] | : lazy: FALSE [22:16:44.739] | : stdout: TRUE [22:16:44.739] | : conditions: [n=1] 'condition' [22:16:44.741] | : getGlobalsAndPackages() ... [22:16:44.741] | : . Searching for globals ... [22:16:44.762] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [22:16:44.762] | : . Searching for globals ... done [22:16:44.763] | : . Resolving globals: FALSE [22:16:44.764] | : . Search for packages associated with the globals ... [22:16:44.765] | : . ' Packages associated with globals: [1] 'base' [22:16:44.766] | : . ' Packages: [1] 'base' [22:16:44.766] | : . Search for packages associated with the globals ... done [22:16:44.767] | : . Packages after dropping 'base': [0] [22:16:44.768] | : . globals: [2] 'slow_fcn', 'x' [22:16:44.768] | : . packages: [0] [22:16:44.769] | : getGlobalsAndPackages() ... done [22:16:44.771] | : run() for 'Future' () ... [22:16:44.771] | : . state: 'created' [22:16:44.772] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.773] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.773] | : . Using MultisessionFutureBackend ... [22:16:44.774] | : . ' Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:16:44.775] | : . ' Launching futures ... [22:16:44.775] | : . ' , launchFuture() for ClusterFutureBackend ... [22:16:44.776] | : . ' , ; Workers: [n=2] [22:16:44.777] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:44.779] | : . ' , ; FutureRegistry: 'workers-d8392266062df92ffda370863bed683b' [22:16:44.780] | : . ' , ; requestWorker() ... [22:16:44.781] | : . ' , ; ` requestNode() ... [22:16:44.781] | : . ' , ; ` | Number of workers: 2 [22:16:44.782] | : . ' , ; ` | Polling for a free worker ... [22:16:44.782] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.783] | : . ' , ; ` | : . Created empty registry 'workers-d8392266062df92ffda370863bed683b' [22:16:44.784] | : . ' , ; ` | : . Listing all futures [22:16:44.784] | : . ' , ; ` | : . Number of registered futures: 0 [22:16:44.785] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.785] | : . ' , ; ` | : Total time: 0 [22:16:44.786] | : . ' , ; ` | Polling for a free worker ... done [22:16:44.786] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.787] | : . ' , ; ` | : Listing all futures [22:16:44.787] | : . ' , ; ` | : Number of registered futures: 0 [22:16:44.788] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.788] | : . ' , ; ` | avail: [n=2] '1', '2' [22:16:44.789] | : . ' , ; ` | Index of first available worker: 1 [22:16:44.789] | : . ' , ; ` | Validate that the worker is functional ... [22:16:44.790] | : . ' , ; ` | : Connection is valid [22:16:44.791] | : . ' , ; ` | : RichSOCKnode of a socket cluster on local host 'localhost' with pid 37796 (R Under development (unstable) (2026-03-12 r89611 ucrt), x86_64-w64-mingw32) using socket connection #4 ('<-CRANwin3.fb05.statistik.uni-dortmund.de:39835') [22:16:44.792] | : . ' , ; ` | : Worker is functional [22:16:44.793] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.794] | : . ' , ; ` | : . Listing all futures [22:16:44.794] | : . ' , ; ` | : . Number of registered futures: 0 [22:16:44.795] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.795] | : . ' , ; ` | Validate that the worker is functional ... done [22:16:44.796] | : . ' , ; ` requestNode() ... done [22:16:44.797] | : . ' , ; ` cluster node index: 1 [22:16:44.798] | : . ' , ; requestWorker() ... done [22:16:44.798] | : . ' , ; eraseGlobalEnvironment() ... [22:16:44.800] | : . ' , ; eraseGlobalEnvironment() ... done [22:16:44.801] | : . ' , ; launchFuture() ... [22:16:44.801] | : . ' , ; ` cluster node index: 1 [22:16:44.802] | : . ' , ; ` getFutureData() ... [22:16:44.802] | : . ' , ; ` | getFutureCore() ... [22:16:44.803] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [22:16:44.803] | : . ' , ; ` | getFutureCore() ... done [22:16:44.804] | : . ' , ; ` | getFutureCapture() ... [22:16:44.805] | : . ' , ; ` | getFutureCapture() ... done [22:16:44.805] | : . ' , ; ` | getFutureContext() ... [22:16:44.806] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.806] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [22:16:44.807] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.807] | : . ' , ; ` | : Packages needed by future backend (n = 0): [22:16:44.808] | : . ' , ; ` | getFutureContext() ... done [22:16:44.809] | : . ' , ; ` getFutureData() ... done [22:16:44.810] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... [22:16:44.810] | : . ' , ; ` | Appended future to position #1 [22:16:44.811] | : . ' , ; ` | Number of registered futures: 1 [22:16:44.811] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... done [22:16:44.812] | : . ' , ; launchFuture() ... done [22:16:44.812] | : . ' , ; MultisessionFuture started [22:16:44.813] | : . ' , launchFuture() for ClusterFutureBackend ... done [22:16:44.813] | : . ' Launching futures ... done [22:16:44.814] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:44.814] | : . Using MultisessionFutureBackend ... done [22:16:44.815] | : run() for 'Future' () ... done [22:16:44.816] | future(..., label = NULL) ... done [22:16:44.818] | future(..., label = NULL) ... [22:16:44.819] | : lazy: FALSE [22:16:44.820] | : stdout: TRUE [22:16:44.820] | : conditions: [n=1] 'condition' [22:16:44.821] | : getGlobalsAndPackages() ... [22:16:44.821] | : . Searching for globals ... [22:16:44.837] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [22:16:44.838] | : . Searching for globals ... done [22:16:44.838] | : . Resolving globals: FALSE [22:16:44.839] | : . Search for packages associated with the globals ... [22:16:44.840] | : . ' Packages associated with globals: [1] 'base' [22:16:44.841] | : . ' Packages: [1] 'base' [22:16:44.841] | : . Search for packages associated with the globals ... done [22:16:44.842] | : . Packages after dropping 'base': [0] [22:16:44.842] | : . globals: [2] 'slow_fcn', 'x' [22:16:44.843] | : . packages: [0] [22:16:44.843] | : getGlobalsAndPackages() ... done [22:16:44.845] | : run() for 'Future' () ... [22:16:44.845] | : . state: 'created' [22:16:44.846] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.846] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.847] | : . Using MultisessionFutureBackend ... [22:16:44.847] | : . ' Number of futures since start: 1 (1 created, 1 launched, 0 finished) [22:16:44.848] | : . ' Launching futures ... [22:16:44.848] | : . ' , launchFuture() for ClusterFutureBackend ... [22:16:44.849] | : . ' , ; Workers: [n=2] [22:16:44.850] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:44.852] | : . ' , ; FutureRegistry: 'workers-d8392266062df92ffda370863bed683b' [22:16:44.853] | : . ' , ; requestWorker() ... [22:16:44.853] | : . ' , ; ` requestNode() ... [22:16:44.854] | : . ' , ; ` | Number of workers: 2 [22:16:44.854] | : . ' , ; ` | Polling for a free worker ... [22:16:44.855] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.856] | : . ' , ; ` | : . Listing all futures [22:16:44.857] | : . ' , ; ` | : . Number of registered futures: 1 [22:16:44.857] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.858] | : . ' , ; ` | : Total time: 0 [22:16:44.859] | : . ' , ; ` | Polling for a free worker ... done [22:16:44.859] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.860] | : . ' , ; ` | : Listing all futures [22:16:44.860] | : . ' , ; ` | : Number of registered futures: 1 [22:16:44.861] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.861] | : . ' , ; ` | avail: [n=2] '2' [22:16:44.862] | : . ' , ; ` | Index of first available worker: 2 [22:16:44.863] | : . ' , ; ` | Validate that the worker is functional ... [22:16:44.863] | : . ' , ; ` | : Connection is valid [22:16:44.864] | : . ' , ; ` | : RichSOCKnode of a socket cluster on local host 'localhost' with pid 77768 (R Under development (unstable) (2026-03-12 r89611 ucrt), x86_64-w64-mingw32) using socket connection #5 ('<-CRANwin3.fb05.statistik.uni-dortmund.de:39835') [22:16:44.866] | : . ' , ; ` | : Worker is functional [22:16:44.867] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.868] | : . ' , ; ` | : . Listing all futures [22:16:44.868] | : . ' , ; ` | : . Number of registered futures: 1 [22:16:44.869] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.869] | : . ' , ; ` | Validate that the worker is functional ... done [22:16:44.870] | : . ' , ; ` requestNode() ... done [22:16:44.872] | : . ' , ; ` cluster node index: 2 [22:16:44.872] | : . ' , ; requestWorker() ... done [22:16:44.877] | : . ' , ; eraseGlobalEnvironment() ... [22:16:44.878] | : . ' , ; eraseGlobalEnvironment() ... done [22:16:44.879] | : . ' , ; launchFuture() ... [22:16:44.880] | : . ' , ; ` cluster node index: 2 [22:16:44.880] | : . ' , ; ` getFutureData() ... [22:16:44.880] | : . ' , ; ` | getFutureCore() ... [22:16:44.881] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [22:16:44.881] | : . ' , ; ` | getFutureCore() ... done [22:16:44.882] | : . ' , ; ` | getFutureCapture() ... [22:16:44.882] | : . ' , ; ` | getFutureCapture() ... done [22:16:44.883] | : . ' , ; ` | getFutureContext() ... [22:16:44.883] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.884] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [22:16:44.884] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.885] | : . ' , ; ` | : Packages needed by future backend (n = 0): [22:16:44.885] | : . ' , ; ` | getFutureContext() ... done [22:16:44.886] | : . ' , ; ` getFutureData() ... done [22:16:44.887] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... [22:16:44.890] | : . ' , ; ` | Appended future to position #2 [22:16:44.891] | : . ' , ; ` | Number of registered futures: 2 [22:16:44.891] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... done [22:16:44.892] | : . ' , ; launchFuture() ... done [22:16:44.892] | : . ' , ; MultisessionFuture started [22:16:44.893] | : . ' , launchFuture() for ClusterFutureBackend ... done [22:16:44.893] | : . ' Launching futures ... done [22:16:44.894] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:44.894] | : . Using MultisessionFutureBackend ... done [22:16:44.895] | : run() for 'Future' () ... done [22:16:44.896] | future(..., label = NULL) ... done [22:16:44.897] | future(..., label = NULL) ... [22:16:44.897] | : lazy: FALSE [22:16:44.898] | : stdout: TRUE [22:16:44.898] | : conditions: [n=1] 'condition' [22:16:44.899] | : getGlobalsAndPackages() ... [22:16:44.899] | : . Searching for globals ... [22:16:44.913] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [22:16:44.914] | : . Searching for globals ... done [22:16:44.914] | : . Resolving globals: FALSE [22:16:44.915] | : . Search for packages associated with the globals ... [22:16:44.916] | : . ' Packages associated with globals: [1] 'base' [22:16:44.916] | : . ' Packages: [1] 'base' [22:16:44.917] | : . Search for packages associated with the globals ... done [22:16:44.918] | : . Packages after dropping 'base': [0] [22:16:44.918] | : . globals: [2] 'slow_fcn', 'x' [22:16:44.919] | : . packages: [0] [22:16:44.919] | : getGlobalsAndPackages() ... done [22:16:44.920] | : run() for 'Future' () ... [22:16:44.921] | : . state: 'created' [22:16:44.921] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:44.922] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:44.922] | : . Using MultisessionFutureBackend ... [22:16:44.923] | : . ' Number of futures since start: 2 (2 created, 2 launched, 0 finished) [22:16:44.923] | : . ' Launching futures ... [22:16:44.924] | : . ' , launchFuture() for ClusterFutureBackend ... [22:16:44.924] | : . ' , ; Workers: [n=2] [22:16:44.925] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:44.927] | : . ' , ; FutureRegistry: 'workers-d8392266062df92ffda370863bed683b' [22:16:44.928] | : . ' , ; requestWorker() ... [22:16:44.928] | : . ' , ; ` requestNode() ... [22:16:44.929] | : . ' , ; ` | Number of workers: 2 [22:16:44.929] | : . ' , ; ` | Polling for a free worker ... [22:16:44.930] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:44.930] | : . ' , ; ` | : . Listing all futures [22:16:44.931] | : . ' , ; ` | : . Number of registered futures: 2 [22:16:44.931] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:44.932] | : . ' , ; ` | : Poll #1 (0): usedNodes() = 2, workers = 2 [22:16:44.949] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'collect-first', earlySignal = 1) ... [22:16:44.950] | : . ' , ; ` | : . collectValues('workers-d8392266062df92ffda370863bed683b', firstOnly = TRUE) ... [22:16:44.950] | : . ' , ; ` | : . ' resolved() ... [22:16:44.951] | : . ' , ; ` | : . ' , resolved() for MultisessionFuture () ... [22:16:44.952] | : . ' , ; ` | : . ' , ; Cluster node socket connection: index=4, id=276 [22:16:44.953] | : . ' , ; ` | : . ' , ; assertValidConnection() ... [22:16:44.953] | : . ' , ; ` | : . ' , ; ` cluster node index: 1 [22:16:44.954] | : . ' , ; ` | : . ' , ; assertValidConnection() ... done [22:16:44.955] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() for ClusterFuture ... [22:16:44.956] | : . ' , ; ` | : . ' , ; ` cluster node index: 1 [22:16:44.956] | : . ' , ; ` | : . ' , ; ` [1] TRUE [22:16:44.957] | : . ' , ; ` | : . ' , ; ` Received data: [22:16:44.958] | : . ' , ; ` | : . ' , ; ` List of 5 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` $ type : chr "VALUE" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` $ value :List of 16 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ value : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ visible : logi FALSE [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ stdout : chr "" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ conditions : list() [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ rng : logi FALSE [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ seed : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ misuseGlobalEnv : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ misuseConnections :List of 3 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ added : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ removed : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ replaced: NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ misuseDevices : NULL [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ misuseDefaultDevice: list() [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ uuid : chr [1:2] "a673d494c643b78e91ab20f02ea45217" "1" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ session_uuid : chr "c6c91120b4bb9305a21a1d0ef435f3aa" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..- attr(*, "source")=List of 5 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ host : Named chr "CRANWIN3" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ pid : int 37796 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ random: int 2147483647 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ r_info :List of 4 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. .. ..$ : int [1:3] 4 6 0 [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ os : chr "windows" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ os_name : chr "Windows" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` .. ..$ captures_utf8: logi TRUE [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..$ version : chr "1.8" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..- attr(*, "class")= chr "FutureResult" [22:16:44.958] | : . ' , ; ` | : . ' , ; ` $ success: logi TRUE [22:16:44.958] | : . ' , ; ` | : . ' , ; ` $ time : 'proc_time' Named num [1:5] 0.04 0 0.08 NA NA [22:16:44.958] | : . ' , ; ` | : . ' , ; ` ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:44.958] | : . ' , ; ` | : . ' , ; ` $ tag : NULL [22:16:44.977] | : . ' , ; ` | : . ' , ; ` Received FutureResult [22:16:44.979] | : . ' , ; ` | : . ' , ; ` FutureResult: [22:16:44.979] | : . ' , ; ` | : . ' , ; ` value: 'NULL' [22:16:44.979] | : . ' , ; ` | : . ' , ; ` visible: FALSE [22:16:44.979] | : . ' , ; ` | : . ' , ; ` stdout: character [22:16:44.979] | : . ' , ; ` | : . ' , ; ` conditions: [n = 0] [22:16:44.979] | : . ' , ; ` | : . ' , ; ` RNG used: FALSE [22:16:44.979] | : . ' , ; ` | : . ' , ; ` duration: 0.07933903 secs (started 2026-03-13 22:16:44.811363) [22:16:44.979] | : . ' , ; ` | : . ' , ; ` version: 1.8 [22:16:44.982] | : . ' , ; ` | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... [22:16:44.982] | : . ' , ; ` | : . ' , ; ` | Removed future from position #1 [22:16:44.983] | : . ' , ; ` | : . ' , ; ` | Number of registered futures: 1 [22:16:44.984] | : . ' , ; ` | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... done [22:16:44.985] | : . ' , ; ` | : . ' , ; ` Erased future from future backend [22:16:44.986] | : . ' , ; ` | : . ' , ; ` result() for ClusterFuture ... [22:16:44.987] | : . ' , ; ` | : . ' , ; ` | result already collected: FutureResult [22:16:44.988] | : . ' , ; ` | : . ' , ; ` result() for ClusterFuture ... done [22:16:44.993] | : . ' , ; ` | : . ' , ; ` signalConditions() ... [22:16:44.994] | : . ' , ; ` | : . ' , ; ` | include = 'immediateCondition' [22:16:44.995] | : . ' , ; ` | : . ' , ; ` | exclude = [22:16:44.996] | : . ' , ; ` | : . ' , ; ` | resignal = FALSE [22:16:44.997] | : . ' , ; ` | : . ' , ; ` | Number of conditions: 0 [22:16:44.997] | : . ' , ; ` | : . ' , ; ` | int 0 [22:16:44.999] | : . ' , ; ` | : . ' , ; ` signalConditions() ... done [22:16:45.000] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() for ClusterFuture ... done [22:16:45.000] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:45.001] | : . ' , ; ` | : . ' , resolved() for MultisessionFuture () ... done [22:16:45.002] | : . ' , ; ` | : . ' , assertFutureResult() ... [22:16:45.003] | : . ' , ; ` | : . ' , ; Future uuid: a673d494c643b78e91ab20f02ea45217-1 [22:16:45.003] | : . ' , ; ` | : . ' , ; identical; success [22:16:45.004] | : . ' , ; ` | : . ' , assertFutureResult() ... done [22:16:45.005] | : . ' , ; ` | : . ' resolved() ... done [22:16:45.007] | : . ' , ; ` | : . ' Future at position #1 is resolved ... [22:16:45.008] | : . ' , ; ` | : . ' , value() for MultisessionFuture () ... [22:16:45.009] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [22:16:45.010] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [22:16:45.011] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [22:16:45.012] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [22:16:45.012] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [22:16:45.013] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [22:16:45.014] | : . ' , ; ` | : . ' , ; signalConditions() ... [22:16:45.015] | : . ' , ; ` | : . ' , ; ` include = 'immediateCondition' [22:16:45.016] | : . ' , ; ` | : . ' , ; ` exclude = [22:16:45.016] | : . ' , ; ` | : . ' , ; ` resignal = FALSE [22:16:45.017] | : . ' , ; ` | : . ' , ; ` Number of conditions: 0 [22:16:45.018] | : . ' , ; ` | : . ' , ; ` int 0 [22:16:45.019] | : . ' , ; ` | : . ' , ; signalConditions() ... done [22:16:45.020] | : . ' , ; ` | : . ' , ; check for misuse ... [22:16:45.021] | : . ' , ; ` | : . ' , ; check for misuse ... done [22:16:45.021] | : . ' , ; ` | : . ' , value() for MultisessionFuture () ... done [22:16:45.022] | : . ' , ; ` | : . ' Future at position #1 is resolved ... done [22:16:45.023] | : . ' , ; ` | : . collectValues('workers-d8392266062df92ffda370863bed683b', firstOnly = TRUE) ... done [22:16:45.024] | : . ' , ; ` | : . Remove collected futures ... [22:16:45.025] | : . ' , ; ` | : . ' Indices of futures to drop: [n=1] '1' [22:16:45.026] | : . ' , ; ` | : . Remove collected futures ... done [22:16:45.026] | : . ' , ; ` | : FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'collect-first', earlySignal = 1) ... done [22:16:45.027] | : . ' , ; ` | : Early signaling of 2 future candidates ... [22:16:45.028] | : . ' , ; ` | : Number of futures with early signaling requested: 0 [22:16:45.028] | : . ' , ; ` | : Early signaling of 2 future candidates ... done [22:16:45.029] | : . ' , ; ` | : Number of registered futures: 2 [22:16:45.029] | : . ' , ; ` | Polling for a free worker ... done [22:16:45.030] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:45.030] | : . ' , ; ` | : Listing all futures [22:16:45.031] | : . ' , ; ` | : Number of registered futures: 1 [22:16:45.031] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:45.032] | : . ' , ; ` | Total time: 0.0999560356140137 [22:16:45.032] | : . ' , ; ` requestNode() ... done [22:16:45.033] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:45.033] | : . ' , ; ` | Listing all futures [22:16:45.034] | : . ' , ; ` | Number of registered futures: 1 [22:16:45.034] | : . ' , ; ` FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:45.035] | : . ' , ; ` avail: [n=2] '1' [22:16:45.035] | : . ' , ; ` Index of first available worker: 1 [22:16:45.036] | : . ' , ; ` Validate that the worker is functional ... [22:16:45.036] | : . ' , ; ` | Connection is valid [22:16:45.037] | : . ' , ; ` | RichSOCKnode of a socket cluster on local host 'localhost' with pid 37796 (R Under development (unstable) (2026-03-12 r89611 ucrt), x86_64-w64-mingw32) using socket connection #4 ('<-CRANwin3.fb05.statistik.uni-dortmund.de:39835') [22:16:45.038] | : . ' , ; ` | Worker is functional [22:16:45.039] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... [22:16:45.039] | : . ' , ; ` | : Listing all futures [22:16:45.040] | : . ' , ; ` | : Number of registered futures: 1 [22:16:45.040] | : . ' , ; ` | FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'list', earlySignal = 0) ... done [22:16:45.041] | : . ' , ; ` Validate that the worker is functional ... done [22:16:45.041] | : . ' , ; requestWorker() ... done [22:16:45.043] | : . ' , ; cluster node index: 1 [22:16:45.043] | : . ' , launchFuture() for ClusterFutureBackend ... done [22:16:45.044] | : . ' , eraseGlobalEnvironment() ... [22:16:45.046] | : . ' , eraseGlobalEnvironment() ... done [22:16:45.046] | : . ' , launchFuture() ... [22:16:45.047] | : . ' , ; cluster node index: 1 [22:16:45.047] | : . ' , ; getFutureData() ... [22:16:45.048] | : . ' , ; ` getFutureCore() ... [22:16:45.048] | : . ' , ; ` | Packages needed by the future expression (n = 0): [22:16:45.049] | : . ' , ; ` getFutureCore() ... done [22:16:45.049] | : . ' , ; ` getFutureCapture() ... [22:16:45.050] | : . ' , ; ` getFutureCapture() ... done [22:16:45.050] | : . ' , ; ` getFutureContext() ... [22:16:45.051] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:45.051] | : . ' , ; ` | : Getting stack without first backend: [n=0] [22:16:45.052] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:45.052] | : . ' , ; ` | Packages needed by future backend (n = 0): [22:16:45.053] | : . ' , ; ` getFutureContext() ... done [22:16:45.053] | : . ' , ; getFutureData() ... done [22:16:45.054] | : . ' , ; FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... [22:16:45.054] | : . ' , ; ` Appended future to position #2 [22:16:45.055] | : . ' , ; ` Number of registered futures: 2 [22:16:45.055] | : . ' , ; FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'add', earlySignal = 0) ... done [22:16:45.056] | : . ' , launchFuture() ... done [22:16:45.056] | : . ' , MultisessionFuture started [22:16:45.057] | : . ' Launching futures ... done [22:16:45.057] | : . Using MultisessionFutureBackend ... done [22:16:45.058] | : . Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:45.058] | : run() for 'Future' () ... done [22:16:45.059] | future(..., label = NULL) ... done [22:16:45.060] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:45.061] value() for list ... [22:16:45.062] | recursive: 0 [22:16:45.062] | reduce: FALSE [22:16:45.063] | length: 3 [22:16:45.064] | Number of remaining objects: 3 [22:16:45.065] | checking value #1: [22:16:45.065] | 'obj' is MultisessionFuture [22:16:45.066] | resolved() ... [22:16:45.066] | resolved() ... done [22:16:45.068] | MultisessionFuture #1 [22:16:45.068] | signalConditionsASAP(MultisessionFuture, pos=1) ... [22:16:45.069] | : nx: 3 [22:16:45.069] | : relay: TRUE [22:16:45.070] | : stdout: TRUE [22:16:45.070] | : signal: TRUE [22:16:45.071] | : resignal: FALSE [22:16:45.071] | : force: TRUE [22:16:45.072] | : relayed: [n=3] FALSE, FALSE, FALSE [22:16:45.072] | : queued futures: [n=3] FALSE, FALSE, FALSE [22:16:45.073] | : until=1 [22:16:45.073] | : relaying element #1 [22:16:45.074] | : value() for MultisessionFuture () ... [22:16:45.074] | : . result() for ClusterFuture ... [22:16:45.075] | : . ' result already collected: FutureResult [22:16:45.075] | : . result() for ClusterFuture ... done [22:16:45.076] | : . result() for ClusterFuture ... [22:16:45.076] | : . ' result already collected: FutureResult [22:16:45.077] | : . result() for ClusterFuture ... done [22:16:45.078] | : . signalConditions() ... [22:16:45.078] | : . ' include = 'immediateCondition' [22:16:45.079] | : . ' exclude = [22:16:45.079] | : . ' resignal = FALSE [22:16:45.079] | : . ' Number of conditions: 0 [22:16:45.080] | : . ' int 0 [22:16:45.084] | : . signalConditions() ... done [22:16:45.085] | : . relay stdout ... [22:16:45.085] | : . relay stdout ... done [22:16:45.086] | : . check for misuse ... [22:16:45.086] | : . check for misuse ... done [22:16:45.087] | : value() for MultisessionFuture () ... done [22:16:45.087] | : result() for ClusterFuture ... [22:16:45.088] | : . result already collected: FutureResult [22:16:45.088] | : result() for ClusterFuture ... done [22:16:45.089] | : signalConditions() ... [22:16:45.089] | : . include = 'immediateCondition' [22:16:45.090] | : . exclude = [22:16:45.090] | : . resignal = FALSE [22:16:45.091] | : . Number of conditions: 0 [22:16:45.091] | : . int 0 [22:16:45.092] | : signalConditions() ... done [22:16:45.093] | : result() for ClusterFuture ... [22:16:45.093] | : . result already collected: FutureResult [22:16:45.094] | : result() for ClusterFuture ... done [22:16:45.094] | : signalConditions() ... [22:16:45.095] | : . include = 'condition' [22:16:45.095] | : . exclude = 'error', 'immediateCondition' [22:16:45.096] | : . resignal = TRUE [22:16:45.096] | : . Number of conditions: 0 [22:16:45.097] | : . int 0 [22:16:45.097] | : signalConditions() ... done [22:16:45.098] | : relayed: [n=3] TRUE, FALSE, FALSE [22:16:45.098] | : queued futures: [n=3] TRUE, FALSE, FALSE [22:16:45.099] | signalConditionsASAP(MultisessionFuture, pos=1) ... done [22:16:45.099] | value(, ...) ... [22:16:45.100] | : value() for MultisessionFuture () ... [22:16:45.100] | : . result() for ClusterFuture ... [22:16:45.101] | : . ' result already collected: FutureResult [22:16:45.101] | : . result() for ClusterFuture ... done [22:16:45.102] | : . result() for ClusterFuture ... [22:16:45.103] | : . ' result already collected: FutureResult [22:16:45.103] | : . result() for ClusterFuture ... done [22:16:45.104] | : . signalConditions() ... [22:16:45.104] | : . ' include = 'immediateCondition' [22:16:45.105] | : . ' exclude = [22:16:45.105] | : . ' resignal = FALSE [22:16:45.105] | : . ' Number of conditions: 0 [22:16:45.106] | : . ' int 0 [22:16:45.107] | : . signalConditions() ... done [22:16:45.107] | : . check for misuse ... [22:16:45.108] | : . check for misuse ... done [22:16:45.108] | : value() for MultisessionFuture () ... done [22:16:45.109] | : value: [22:16:45.109] | value(, ...) ... done [22:16:45.110] | signalConditionsASAP(MultisessionFuture, pos=1) ... [22:16:45.110] | : nx: 3 [22:16:45.111] | : relay: TRUE [22:16:45.111] | : stdout: TRUE [22:16:45.112] | : signal: TRUE [22:16:45.112] | : resignal: FALSE [22:16:45.113] | : force: TRUE [22:16:45.113] | : relayed: [n=3] TRUE, FALSE, FALSE [22:16:45.114] | : queued futures: [n=3] TRUE, FALSE, FALSE [22:16:45.114] | : until=2 [22:16:45.115] | : relaying element #2 [22:16:45.115] | : relayed: [n=3] TRUE, FALSE, FALSE [22:16:45.115] | : queued futures: [n=3] TRUE, FALSE, FALSE [22:16:45.116] | signalConditionsASAP(MultisessionFuture, pos=1) ... done [22:16:45.117] | resolve() for MultisessionFuture ... [22:16:45.118] | : length: 2 (resolved future 1) [22:16:45.118] | : checking value #2: [22:16:45.119] | : 'obj' is MultisessionFuture [22:16:45.119] | : resolved() ... [22:16:45.120] | : . resolved() for MultisessionFuture () ... [22:16:45.121] | : . ' Cluster node socket connection: index=5, id=277 [22:16:45.121] | : . ' assertValidConnection() ... [22:16:45.122] | : . ' , cluster node index: 2 [22:16:45.123] | : . ' assertValidConnection() ... done [22:16:45.123] | : . ' receiveMessageFromWorker() for ClusterFuture ... [22:16:45.124] | : . ' , cluster node index: 2 [22:16:45.124] | : . ' , [1] TRUE [22:16:45.125] | : . ' , Received data: [22:16:45.125] | : . ' , List of 5 [22:16:45.125] | : . ' , $ type : chr "VALUE" [22:16:45.125] | : . ' , $ value :List of 16 [22:16:45.125] | : . ' , ..$ value : NULL [22:16:45.125] | : . ' , ..$ visible : logi FALSE [22:16:45.125] | : . ' , ..$ stdout : chr "" [22:16:45.125] | : . ' , ..$ conditions : list() [22:16:45.125] | : . ' , ..$ rng : logi FALSE [22:16:45.125] | : . ' , ..$ seed : NULL [22:16:45.125] | : . ' , ..$ misuseGlobalEnv : NULL [22:16:45.125] | : . ' , ..$ misuseConnections :List of 3 [22:16:45.125] | : . ' , .. ..$ added : NULL [22:16:45.125] | : . ' , .. ..$ removed : NULL [22:16:45.125] | : . ' , .. ..$ replaced: NULL [22:16:45.125] | : . ' , ..$ misuseDevices : NULL [22:16:45.125] | : . ' , ..$ misuseDefaultDevice: list() [22:16:45.125] | : . ' , ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:45.125] | : . ' , ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:45.125] | : . ' , ..$ uuid : chr [1:2] "a673d494c643b78e91ab20f02ea45217" "2" [22:16:45.125] | : . ' , ..$ session_uuid : chr "96078fdda703070070c2e16c3ccbedcb" [22:16:45.125] | : . ' , .. ..- attr(*, "source")=List of 5 [22:16:45.125] | : . ' , .. .. ..$ host : Named chr "CRANWIN3" [22:16:45.125] | : . ' , .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:45.125] | : . ' , .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:45.125] | : . ' , .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:45.125] | : . ' , .. .. ..$ pid : int 77768 [22:16:45.125] | : . ' , .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:45.125] | : . ' , .. .. ..$ random: int 2147483647 [22:16:45.125] | : . ' , ..$ r_info :List of 4 [22:16:45.125] | : . ' , .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:45.125] | : . ' , .. .. ..$ : int [1:3] 4 6 0 [22:16:45.125] | : . ' , .. ..$ os : chr "windows" [22:16:45.125] | : . ' , .. ..$ os_name : chr "Windows" [22:16:45.125] | : . ' , .. ..$ captures_utf8: logi TRUE [22:16:45.125] | : . ' , ..$ version : chr "1.8" [22:16:45.125] | : . ' , ..- attr(*, "class")= chr "FutureResult" [22:16:45.125] | : . ' , $ success: logi TRUE [22:16:45.125] | : . ' , $ time : 'proc_time' Named num [1:5] 0.05 0.02 0.09 NA NA [22:16:45.125] | : . ' , ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:45.125] | : . ' , $ tag : NULL [22:16:45.140] | : . ' , Received FutureResult [22:16:45.142] | : . ' , FutureResult: [22:16:45.142] | : . ' , value: 'NULL' [22:16:45.142] | : . ' , visible: FALSE [22:16:45.142] | : . ' , stdout: character [22:16:45.142] | : . ' , conditions: [n = 0] [22:16:45.142] | : . ' , RNG used: FALSE [22:16:45.142] | : . ' , duration: 0.09531522 secs (started 2026-03-13 22:16:44.889099) [22:16:45.142] | : . ' , version: 1.8 [22:16:45.143] | : . ' , FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... [22:16:45.143] | : . ' , ; Removed future from position #1 [22:16:45.144] | : . ' , ; Number of registered futures: 1 [22:16:45.144] | : . ' , FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... done [22:16:45.145] | : . ' , Erased future from future backend [22:16:45.146] | : . ' , result() for ClusterFuture ... [22:16:45.146] | : . ' , ; result already collected: FutureResult [22:16:45.147] | : . ' , result() for ClusterFuture ... done [22:16:45.150] | : . ' , signalConditions() ... [22:16:45.150] | : . ' , ; include = 'immediateCondition' [22:16:45.151] | : . ' , ; exclude = [22:16:45.151] | : . ' , ; resignal = FALSE [22:16:45.152] | : . ' , ; Number of conditions: 0 [22:16:45.152] | : . ' , ; int 0 [22:16:45.153] | : . ' , signalConditions() ... done [22:16:45.154] | : . ' receiveMessageFromWorker() for ClusterFuture ... done [22:16:45.154] | : . ' receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:45.155] | : . resolved() for MultisessionFuture () ... done [22:16:45.155] | : . assertFutureResult() ... [22:16:45.156] | : . ' Future uuid: a673d494c643b78e91ab20f02ea45217-2 [22:16:45.156] | : . ' identical; success [22:16:45.156] | : . assertFutureResult() ... done [22:16:45.157] | : resolved() ... done [22:16:45.158] | : MultisessionFuture #2 [22:16:45.159] | : signalConditionsASAP(MultisessionFuture, pos=2) ... [22:16:45.159] | : . nx: 3 [22:16:45.160] | : . relay: TRUE [22:16:45.160] | : . stdout: TRUE [22:16:45.161] | : . signal: TRUE [22:16:45.161] | : . resignal: FALSE [22:16:45.162] | : . force: TRUE [22:16:45.162] | : . relayed: [n=3] TRUE, FALSE, FALSE [22:16:45.162] | : . queued futures: [n=3] TRUE, FALSE, FALSE [22:16:45.163] | : . until=2 [22:16:45.163] | : . relaying element #2 [22:16:45.164] | : . value() for MultisessionFuture () ... [22:16:45.164] | : . ' result() for ClusterFuture ... [22:16:45.165] | : . ' , result already collected: FutureResult [22:16:45.165] | : . ' result() for ClusterFuture ... done [22:16:45.166] | : . ' result() for ClusterFuture ... [22:16:45.166] | : . ' , result already collected: FutureResult [22:16:45.167] | : . ' result() for ClusterFuture ... done [22:16:45.167] | : . ' signalConditions() ... [22:16:45.168] | : . ' , include = 'immediateCondition' [22:16:45.168] | : . ' , exclude = [22:16:45.169] | : . ' , resignal = FALSE [22:16:45.169] | : . ' , Number of conditions: 0 [22:16:45.170] | : . ' , int 0 [22:16:45.171] | : . ' signalConditions() ... done [22:16:45.171] | : . ' relay stdout ... [22:16:45.172] | : . ' relay stdout ... done [22:16:45.172] | : . ' check for misuse ... [22:16:45.173] | : . ' check for misuse ... done [22:16:45.173] | : . value() for MultisessionFuture () ... done [22:16:45.174] | : . result() for ClusterFuture ... [22:16:45.174] | : . ' result already collected: FutureResult [22:16:45.175] | : . result() for ClusterFuture ... done [22:16:45.175] | : . signalConditions() ... [22:16:45.176] | : . ' include = 'immediateCondition' [22:16:45.176] | : . ' exclude = [22:16:45.177] | : . ' resignal = FALSE [22:16:45.177] | : . ' Number of conditions: 0 [22:16:45.178] | : . ' int 0 [22:16:45.179] | : . signalConditions() ... done [22:16:45.179] | : . result() for ClusterFuture ... [22:16:45.180] | : . ' result already collected: FutureResult [22:16:45.180] | : . result() for ClusterFuture ... done [22:16:45.181] | : . signalConditions() ... [22:16:45.181] | : . ' include = 'condition' [22:16:45.182] | : . ' exclude = 'error', 'immediateCondition' [22:16:45.182] | : . ' resignal = TRUE [22:16:45.182] | : . ' Number of conditions: 0 [22:16:45.183] | : . ' int 0 [22:16:45.184] | : . signalConditions() ... done [22:16:45.184] | : . relayed: [n=3] TRUE, TRUE, FALSE [22:16:45.185] | : . queued futures: [n=3] TRUE, TRUE, FALSE [22:16:45.185] | : signalConditionsASAP(MultisessionFuture, pos=2) ... done [22:16:45.186] | : value(, ...) ... [22:16:45.186] | : . value() for MultisessionFuture () ... [22:16:45.187] | : . ' result() for ClusterFuture ... [22:16:45.187] | : . ' , result already collected: FutureResult [22:16:45.188] | : . ' result() for ClusterFuture ... done [22:16:45.188] | : . ' result() for ClusterFuture ... [22:16:45.189] | : . ' , result already collected: FutureResult [22:16:45.189] | : . ' result() for ClusterFuture ... done [22:16:45.190] | : . ' signalConditions() ... [22:16:45.190] | : . ' , include = 'immediateCondition' [22:16:45.191] | : . ' , exclude = [22:16:45.191] | : . ' , resignal = FALSE [22:16:45.192] | : . ' , Number of conditions: 0 [22:16:45.192] | : . ' , int 0 [22:16:45.193] | : . ' signalConditions() ... done [22:16:45.194] | : . ' check for misuse ... [22:16:45.194] | : . ' check for misuse ... done [22:16:45.195] | : . value() for MultisessionFuture () ... done [22:16:45.195] | : . value: [22:16:45.196] | : value(, ...) ... done [22:16:45.196] | : signalConditionsASAP(MultisessionFuture, pos=2) ... [22:16:45.197] | : . nx: 3 [22:16:45.197] | : . relay: TRUE [22:16:45.198] | : . stdout: TRUE [22:16:45.198] | : . signal: TRUE [22:16:45.199] | : . resignal: FALSE [22:16:45.199] | : . force: TRUE [22:16:45.200] | : . relayed: [n=3] TRUE, TRUE, FALSE [22:16:45.200] | : . queued futures: [n=3] TRUE, TRUE, FALSE [22:16:45.201] | : . until=3 [22:16:45.201] | : . relaying element #3 [22:16:45.201] | : . relayed: [n=3] TRUE, TRUE, FALSE [22:16:45.202] | : . queued futures: [n=3] TRUE, TRUE, FALSE [22:16:45.202] | : signalConditionsASAP(MultisessionFuture, pos=2) ... done [22:16:45.203] | : resolve() for MultisessionFuture ... [22:16:45.204] | : . length: 1 (resolved future 2) [22:16:45.205] | : . checking value #3: [22:16:45.205] | : . 'obj' is MultisessionFuture [22:16:45.206] | : . resolved() ... [22:16:45.206] | : . ' resolved() for MultisessionFuture () ... [22:16:45.207] | : . ' , Cluster node socket connection: index=4, id=276 [22:16:45.208] | : . ' , assertValidConnection() ... [22:16:45.208] | : . ' , ; cluster node index: 1 [22:16:45.209] | : . ' , assertValidConnection() ... done [22:16:45.210] | : . ' , receiveMessageFromWorker() for ClusterFuture ... [22:16:45.210] | : . ' , ; cluster node index: 1 [22:16:45.211] | : . ' , ; [1] TRUE [22:16:45.211] | : . ' , ; Received data: [22:16:45.212] | : . ' , ; List of 5 [22:16:45.212] | : . ' , ; $ type : chr "VALUE" [22:16:45.212] | : . ' , ; $ value :List of 16 [22:16:45.212] | : . ' , ; ..$ value : NULL [22:16:45.212] | : . ' , ; ..$ visible : logi FALSE [22:16:45.212] | : . ' , ; ..$ stdout : chr "" [22:16:45.212] | : . ' , ; ..$ conditions : list() [22:16:45.212] | : . ' , ; ..$ rng : logi FALSE [22:16:45.212] | : . ' , ; ..$ seed : NULL [22:16:45.212] | : . ' , ; ..$ misuseGlobalEnv : NULL [22:16:45.212] | : . ' , ; ..$ misuseConnections :List of 3 [22:16:45.212] | : . ' , ; .. ..$ added : NULL [22:16:45.212] | : . ' , ; .. ..$ removed : NULL [22:16:45.212] | : . ' , ; .. ..$ replaced: NULL [22:16:45.212] | : . ' , ; ..$ misuseDevices : NULL [22:16:45.212] | : . ' , ; ..$ misuseDefaultDevice: list() [22:16:45.212] | : . ' , ; ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:45" [22:16:45.212] | : . ' , ; ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:45" [22:16:45.212] | : . ' , ; ..$ uuid : chr [1:2] "a673d494c643b78e91ab20f02ea45217" "3" [22:16:45.212] | : . ' , ; ..$ session_uuid : chr "c6c91120b4bb9305a21a1d0ef435f3aa" [22:16:45.212] | : . ' , ; .. ..- attr(*, "source")=List of 5 [22:16:45.212] | : . ' , ; .. .. ..$ host : Named chr "CRANWIN3" [22:16:45.212] | : . ' , ; .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:45.212] | : . ' , ; .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:45.212] | : . ' , ; .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:45.212] | : . ' , ; .. .. ..$ pid : int 37796 [22:16:45.212] | : . ' , ; .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:44" [22:16:45.212] | : . ' , ; .. .. ..$ random: int 2147483647 [22:16:45.212] | : . ' , ; ..$ r_info :List of 4 [22:16:45.212] | : . ' , ; .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:45.212] | : . ' , ; .. .. ..$ : int [1:3] 4 6 0 [22:16:45.212] | : . ' , ; .. ..$ os : chr "windows" [22:16:45.212] | : . ' , ; .. ..$ os_name : chr "Windows" [22:16:45.212] | : . ' , ; .. ..$ captures_utf8: logi TRUE [22:16:45.212] | : . ' , ; ..$ version : chr "1.8" [22:16:45.212] | : . ' , ; ..- attr(*, "class")= chr "FutureResult" [22:16:45.212] | : . ' , ; $ success: logi TRUE [22:16:45.212] | : . ' , ; $ time : 'proc_time' Named num [1:5] 0 0 0.04 NA NA [22:16:45.212] | : . ' , ; ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:45.212] | : . ' , ; $ tag : NULL [22:16:45.227] | : . ' , ; Received FutureResult [22:16:45.229] | : . ' , ; FutureResult: [22:16:45.229] | : . ' , ; value: 'NULL' [22:16:45.229] | : . ' , ; visible: FALSE [22:16:45.229] | : . ' , ; stdout: character [22:16:45.229] | : . ' , ; conditions: [n = 0] [22:16:45.229] | : . ' , ; RNG used: FALSE [22:16:45.229] | : . ' , ; duration: 0.03721499 secs (started 2026-03-13 22:16:45.05459) [22:16:45.229] | : . ' , ; version: 1.8 [22:16:45.230] | : . ' , ; FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... [22:16:45.231] | : . ' , ; ` Removed future from position #1 [22:16:45.231] | : . ' , ; ` Number of registered futures: 0 [22:16:45.232] | : . ' , ; FutureRegistry('workers-d8392266062df92ffda370863bed683b', action = 'remove', earlySignal = 0) ... done [22:16:45.232] | : . ' , ; Erased future from future backend [22:16:45.233] | : . ' , ; result() for ClusterFuture ... [22:16:45.233] | : . ' , ; ` result already collected: FutureResult [22:16:45.234] | : . ' , ; result() for ClusterFuture ... done [22:16:45.237] | : . ' , ; signalConditions() ... [22:16:45.237] | : . ' , ; ` include = 'immediateCondition' [22:16:45.238] | : . ' , ; ` exclude = [22:16:45.238] | : . ' , ; ` resignal = FALSE [22:16:45.239] | : . ' , ; ` Number of conditions: 0 [22:16:45.239] | : . ' , ; ` int 0 [22:16:45.240] | : . ' , ; signalConditions() ... done [22:16:45.241] | : . ' , receiveMessageFromWorker() for ClusterFuture ... done [22:16:45.241] | : . ' , receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:45.242] | : . ' resolved() for MultisessionFuture () ... done [22:16:45.242] | : . ' assertFutureResult() ... [22:16:45.243] | : . ' , Future uuid: a673d494c643b78e91ab20f02ea45217-3 [22:16:45.243] | : . ' , identical; success [22:16:45.244] | : . ' assertFutureResult() ... done [22:16:45.244] | : . resolved() ... done [22:16:45.246] | : . MultisessionFuture #3 [22:16:45.246] | : . signalConditionsASAP(MultisessionFuture, pos=3) ... [22:16:45.247] | : . ' nx: 3 [22:16:45.247] | : . ' relay: TRUE [22:16:45.248] | : . ' stdout: TRUE [22:16:45.248] | : . ' signal: TRUE [22:16:45.249] | : . ' resignal: FALSE [22:16:45.249] | : . ' force: TRUE [22:16:45.250] | : . ' relayed: [n=3] TRUE, TRUE, FALSE [22:16:45.250] | : . ' queued futures: [n=3] TRUE, TRUE, FALSE [22:16:45.251] | : . ' until=3 [22:16:45.251] | : . ' relaying element #3 [22:16:45.252] | : . ' value() for MultisessionFuture () ... [22:16:45.252] | : . ' , result() for ClusterFuture ... [22:16:45.253] | : . ' , ; result already collected: FutureResult [22:16:45.253] | : . ' , result() for ClusterFuture ... done [22:16:45.254] | : . ' , result() for ClusterFuture ... [22:16:45.255] | : . ' , ; result already collected: FutureResult [22:16:45.255] | : . ' , result() for ClusterFuture ... done [22:16:45.256] | : . ' , signalConditions() ... [22:16:45.259] | : . ' , ; include = 'immediateCondition' [22:16:45.260] | : . ' , ; exclude = [22:16:45.261] | : . ' , ; resignal = FALSE [22:16:45.261] | : . ' , ; Number of conditions: 0 [22:16:45.261] | : . ' , ; int 0 [22:16:45.263] | : . ' , signalConditions() ... done [22:16:45.263] | : . ' , relay stdout ... [22:16:45.264] | : . ' , relay stdout ... done [22:16:45.264] | : . ' , check for misuse ... [22:16:45.265] | : . ' , check for misuse ... done [22:16:45.265] | : . ' value() for MultisessionFuture () ... done [22:16:45.266] | : . ' result() for ClusterFuture ... [22:16:45.266] | : . ' , result already collected: FutureResult [22:16:45.267] | : . ' result() for ClusterFuture ... done [22:16:45.267] | : . ' signalConditions() ... [22:16:45.268] | : . ' , include = 'immediateCondition' [22:16:45.268] | : . ' , exclude = [22:16:45.269] | : . ' , resignal = FALSE [22:16:45.269] | : . ' , Number of conditions: 0 [22:16:45.270] | : . ' , int 0 [22:16:45.271] | : . ' signalConditions() ... done [22:16:45.271] | : . ' result() for ClusterFuture ... [22:16:45.272] | : . ' , result already collected: FutureResult [22:16:45.272] | : . ' result() for ClusterFuture ... done [22:16:45.273] | : . ' signalConditions() ... [22:16:45.273] | : . ' , include = 'condition' [22:16:45.274] | : . ' , exclude = 'error', 'immediateCondition' [22:16:45.274] | : . ' , resignal = TRUE [22:16:45.275] | : . ' , Number of conditions: 0 [22:16:45.275] | : . ' , int 0 [22:16:45.276] | : . ' signalConditions() ... done [22:16:45.277] | : . ' relayed: [n=3] TRUE, TRUE, TRUE [22:16:45.277] | : . ' queued futures: [n=3] TRUE, TRUE, TRUE [22:16:45.278] | : . signalConditionsASAP(MultisessionFuture, pos=3) ... done [22:16:45.278] | : . value(, ...) ... [22:16:45.279] | : . ' value() for MultisessionFuture () ... [22:16:45.279] | : . ' , result() for ClusterFuture ... [22:16:45.280] | : . ' , ; result already collected: FutureResult [22:16:45.280] | : . ' , result() for ClusterFuture ... done [22:16:45.281] | : . ' , result() for ClusterFuture ... [22:16:45.281] | : . ' , ; result already collected: FutureResult [22:16:45.282] | : . ' , result() for ClusterFuture ... done [22:16:45.282] | : . ' , signalConditions() ... [22:16:45.283] | : . ' , ; include = 'immediateCondition' [22:16:45.283] | : . ' , ; exclude = [22:16:45.284] | : . ' , ; resignal = FALSE [22:16:45.284] | : . ' , ; Number of conditions: 0 [22:16:45.285] | : . ' , ; int 0 [22:16:45.286] | : . ' , signalConditions() ... done [22:16:45.286] | : . ' , check for misuse ... [22:16:45.287] | : . ' , check for misuse ... done [22:16:45.287] | : . ' value() for MultisessionFuture () ... done [22:16:45.288] | : . ' value: [22:16:45.288] | : . value(, ...) ... done [22:16:45.289] | : . signalConditionsASAP(MultisessionFuture, pos=3) ... [22:16:45.289] | : . ' nx: 3 [22:16:45.290] | : . ' relay: TRUE [22:16:45.290] | : . ' stdout: TRUE [22:16:45.291] | : . ' signal: TRUE [22:16:45.291] | : . ' resignal: FALSE [22:16:45.292] | : . ' force: TRUE [22:16:45.292] | : . ' relayed: [n=3] TRUE, TRUE, TRUE [22:16:45.292] | : . ' queued futures: [n=3] TRUE, TRUE, TRUE [22:16:45.293] | : . ' until=3 [22:16:45.294] | : . ' relayed: [n=3] TRUE, TRUE, TRUE [22:16:45.294] | : . ' queued futures: [n=3] TRUE, TRUE, TRUE [22:16:45.295] | : . signalConditionsASAP(MultisessionFuture, pos=3) ... done [22:16:45.295] | : . resolve() for MultisessionFuture ... [22:16:45.297] | : . ' length: 0 (resolved future 3) [22:16:45.297] | : . ' Relaying remaining futures ... [22:16:45.298] | : . ' , signalConditionsASAP(NULL, pos=0) ... [22:16:45.298] | : . ' , ; nx: 3 [22:16:45.299] | : . ' , ; relay: TRUE [22:16:45.299] | : . ' , ; stdout: TRUE [22:16:45.300] | : . ' , ; signal: TRUE [22:16:45.300] | : . ' , ; resignal: FALSE [22:16:45.301] | : . ' , ; force: TRUE [22:16:45.301] | : . ' , ; relayed: [n=3] TRUE, TRUE, TRUE [22:16:45.302] | : . ' , ; queued futures: [n=3] TRUE, TRUE, TRUE - flush all [22:16:45.302] | : . ' , ; relayed: [n=3] TRUE, TRUE, TRUE [22:16:45.303] | : . ' , ; queued futures: [n=3] TRUE, TRUE, TRUE [22:16:45.303] | : . ' , signalConditionsASAP(NULL, pos=0) ... done [22:16:45.304] | : . ' Relaying remaining futures ... done [22:16:45.304] | : . resolve() for MultisessionFuture ... done > print(js) [[1]] event category parent start at 1 create overhead 2026-03-13 22:16:44 0.00000000 secs 4 launch overhead 2026-03-13 22:16:44 0.03033805 secs 2 getWorker overhead launch 2026-03-13 22:16:44 0.04007506 secs 3 eraseWorker overhead launch 2026-03-13 22:16:44 0.05888891 secs 7 evaluate evaluation 2026-03-13 22:16:44 0.07091498 secs 5 receiveResult overhead gather 2026-03-13 22:16:44 0.21637297 secs 6 gather overhead 2026-03-13 22:16:44 0.24619794 secs duration future_label future_uuid 1 0.0296909809 secs a673d494c643b78e91ab20f02ea45217-1 4 0.0452940464 secs a673d494c643b78e91ab20f02ea45217-1 2 0.0163099766 secs a673d494c643b78e91ab20f02ea45217-1 3 0.0004701614 secs a673d494c643b78e91ab20f02ea45217-1 7 0.0793390274 secs a673d494c643b78e91ab20f02ea45217-1 5 0.0216670036 secs a673d494c643b78e91ab20f02ea45217-1 6 0.0026581287 secs a673d494c643b78e91ab20f02ea45217-1 session_uuid 1 a673d494c643b78e91ab20f02ea45217 4 a673d494c643b78e91ab20f02ea45217 2 a673d494c643b78e91ab20f02ea45217 3 a673d494c643b78e91ab20f02ea45217 7 c6c91120b4bb9305a21a1d0ef435f3aa 5 a673d494c643b78e91ab20f02ea45217 6 a673d494c643b78e91ab20f02ea45217 [[2]] event category parent start at 1 create overhead 2026-03-13 22:16:44 0.00000000 secs 4 launch overhead 2026-03-13 22:16:44 0.02395701 secs 2 getWorker overhead launch 2026-03-13 22:16:44 0.03216004 secs 3 eraseWorker overhead launch 2026-03-13 22:16:44 0.05670595 secs 7 evaluate evaluation 2026-03-13 22:16:44 0.06806684 secs 5 receiveResult overhead gather 2026-03-13 22:16:45 0.30353093 secs 6 gather overhead 2026-03-13 22:16:45 0.32511806 secs duration future_label future_uuid 1 0.0234680176 secs a673d494c643b78e91ab20f02ea45217-2 4 0.0510129929 secs a673d494c643b78e91ab20f02ea45217-2 2 0.0179297924 secs a673d494c643b78e91ab20f02ea45217-2 3 0.0005090237 secs a673d494c643b78e91ab20f02ea45217-2 7 0.0953152180 secs a673d494c643b78e91ab20f02ea45217-2 5 0.0168459415 secs a673d494c643b78e91ab20f02ea45217-2 6 0.0015289783 secs a673d494c643b78e91ab20f02ea45217-2 session_uuid 1 a673d494c643b78e91ab20f02ea45217 4 a673d494c643b78e91ab20f02ea45217 2 a673d494c643b78e91ab20f02ea45217 3 a673d494c643b78e91ab20f02ea45217 7 96078fdda703070070c2e16c3ccbedcb 5 a673d494c643b78e91ab20f02ea45217 6 a673d494c643b78e91ab20f02ea45217 [[3]] event category parent start at 1 create overhead 2026-03-13 22:16:44 0.00000000 secs 4 launch overhead 2026-03-13 22:16:44 0.02136111 secs 2 getWorker overhead launch 2026-03-13 22:16:44 0.02884507 secs 3 eraseWorker overhead launch 2026-03-13 22:16:45 0.14554811 secs 7 evaluate evaluation 2026-03-13 22:16:45 0.15533209 secs 5 receiveResult overhead gather 2026-03-13 22:16:45 0.31183910 secs 6 gather overhead 2026-03-13 22:16:45 0.33397222 secs duration future_label future_uuid 1 0.0209491253 secs a673d494c643b78e91ab20f02ea45217-3 4 0.1394250393 secs a673d494c643b78e91ab20f02ea45217-3 2 0.1142590046 secs a673d494c643b78e91ab20f02ea45217-3 3 0.0004239082 secs a673d494c643b78e91ab20f02ea45217-3 7 0.0372149944 secs a673d494c643b78e91ab20f02ea45217-3 5 0.0173199177 secs a673d494c643b78e91ab20f02ea45217-3 6 0.0015509129 secs a673d494c643b78e91ab20f02ea45217-3 session_uuid 1 a673d494c643b78e91ab20f02ea45217 4 a673d494c643b78e91ab20f02ea45217 2 a673d494c643b78e91ab20f02ea45217 3 a673d494c643b78e91ab20f02ea45217 7 c6c91120b4bb9305a21a1d0ef435f3aa 5 a673d494c643b78e91ab20f02ea45217 6 a673d494c643b78e91ab20f02ea45217 > stopifnot(is.list(js), all(vapply(js, FUN = is.data.frame, + FUN.VALUE = NA))) > plan(sequential) [22:16:45.313] | : . plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:45.314] | : . ' plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:45.314] | : . ' , all.equal() for FutureStrategyList ... [22:16:45.315] | : . ' , ; New stack: [22:16:45.315] | : . ' , ; List of 1 [22:16:45.315] | : . ' , ; $ :function (..., envir = parent.frame()) [22:16:45.315] | : . ' , ; ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:45.315] | : . ' , ; ..- attr(*, "init")= logi TRUE [22:16:45.315] | : . ' , ; ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:45.315] | : . ' , ; .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.315] | : . ' , ; ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.315] | : . ' , ; ..- attr(*, "call")= language plan(sequential) [22:16:45.315] | : . ' , ; - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:45.319] | : . ' , ; Old stack: [22:16:45.320] | : . ' , ; List of 1 [22:16:45.320] | : . ' , ; $ :function (..., workers = 2) [22:16:45.320] | : . ' , ; ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:45.320] | : . ' , ; ..- attr(*, "init")= chr "done" [22:16:45.320] | : . ' , ; ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:45.320] | : . ' , ; interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:45.320] | : . ' , ; .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.320] | : . ' , ; ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.320] | : . ' , ; ..- attr(*, "untweakable")= chr "persistent" [22:16:45.320] | : . ' , ; ..- attr(*, "tweaks")=List of 1 [22:16:45.320] | : . ' , ; .. ..$ workers: num 2 [22:16:45.320] | : . ' , ; ..- attr(*, "call")= language plan(multisession, workers = 2) [22:16:45.320] | : . ' , ; ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:45.320] | : . ' , ; .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:45.320] | : . ' , ; interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:45.320] | : . ' , ; .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.320] | : . ' , ; - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:45.325] | : . ' , ; Not identical [22:16:45.326] | : . ' , ; all.equal() for future ... [22:16:45.326] | : . ' , ; ` List of 2 [22:16:45.326] | : . ' , ; ` $ target :function (..., envir = parent.frame()) [22:16:45.326] | : . ' , ; ` ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:45.326] | : . ' , ; ` ..- attr(*, "init")= logi TRUE [22:16:45.326] | : . ' , ; ` ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:45.326] | : . ' , ; ` .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.326] | : . ' , ; ` ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.326] | : . ' , ; ` ..- attr(*, "call")= language plan(sequential) [22:16:45.326] | : . ' , ; ` $ current:function (..., workers = 2) [22:16:45.326] | : . ' , ; ` ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [22:16:45.326] | : . ' , ; ` ..- attr(*, "init")= chr "done" [22:16:45.326] | : . ' , ; ` ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:45.326] | : . ' , ; ` interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:45.326] | : . ' , ; ` .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.326] | : . ' , ; ` ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.326] | : . ' , ; ` ..- attr(*, "untweakable")= chr "persistent" [22:16:45.326] | : . ' , ; ` ..- attr(*, "tweaks")=List of 1 [22:16:45.326] | : . ' , ; ` .. ..$ workers: num 2 [22:16:45.326] | : . ' , ; ` ..- attr(*, "call")= language plan(multisession, workers = 2) [22:16:45.326] | : . ' , ; ` ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:45.326] | : . ' , ; ` .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:16:45.326] | : . ' , ; ` interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:16:45.326] | : . ' , ; ` .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:16:45.334] | : . ' , ; ` Formals differ [22:16:45.335] | : . ' , ; all.equal() for future ... done [22:16:45.335] | : . ' , ; Future strategies differ at level 1 [22:16:45.336] | : . ' , all.equal() for FutureStrategyList ... done [22:16:45.336] | : . ' , plan(): Setting new future backend stack: [22:16:45.337] | : . ' , List of future strategies: [22:16:45.337] | : . ' , 1. sequential: [22:16:45.337] | : . ' , - args: function (..., envir = parent.frame(), workers = "") [22:16:45.337] | : . ' , - tweaked: FALSE [22:16:45.337] | : . ' , - call: plan(sequential) [22:16:45.338] | : . ' , List of 1 [22:16:45.338] | : . ' , $ :function (..., envir = parent.frame()) [22:16:45.338] | : . ' , ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:45.338] | : . ' , ..- attr(*, "init")= logi TRUE [22:16:45.338] | : . ' , ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:45.338] | : . ' , .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.338] | : . ' , ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.338] | : . ' , ..- attr(*, "call")= language plan(sequential) [22:16:45.338] | : . ' , - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:45.341] | : . ' , plan(): plan_cleanup(<'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [22:16:45.342] | : . ' , ; plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:45.343] | : . ' , ; ` Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' [22:16:45.343] | : . ' , ; plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:45.344] | : . ' , ; stopWorkers() for MultisessionFutureBackend ... [22:16:45.344] | : . ' , ; ` Interrupt active futures ... [22:16:45.345] | : . ' , ; ` | Number of futures: 0 [22:16:45.346] | : . ' , ; ` Interrupt active futures ... done [22:16:45.346] | : . ' , ; ` Clear future registry ... [22:16:45.347] | : . ' , ; ` Clear future registry ... done [22:16:45.348] | : . ' , ; ` Stop cluster workers ... [22:16:45.348] | : . ' , ; ` | Stopping existing cluster ... [22:16:45.349] | : . ' , ; ` | : Cluster to shut down: [22:16:45.350] | : . ' , ; ` | : Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:16:45.361] | : . ' , ; ` | : Stopped cluster: 'c(TRUE, TRUE)' [22:16:45.406] | : . ' , ; ` | Stopping existing cluster ... done [22:16:45.407] | : . ' , ; ` Stop cluster workers ... done [22:16:45.408] | : . ' , ; stopWorkers() for MultisessionFutureBackend ... done [22:16:45.408] | : . ' , ; Legacy shutdown of cluster workers ... [22:16:45.409] | : . ' , ; ` Stopping existing cluster ... [22:16:45.409] | : . ' , ; ` | No pre-existing cluster. Skipping [22:16:45.410] | : . ' , ; ` Stopping existing cluster ... done [22:16:45.410] | : . ' , ; Legacy shutdown of cluster workers ... done [22:16:45.411] | : . ' , plan(): plan_cleanup(<'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:45.411] | : . ' , plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [22:16:45.412] | : . ' , ; function (..., envir = parent.frame()) [22:16:45.412] | : . ' , ; - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:45.412] | : . ' , ; - attr(*, "init")= logi TRUE [22:16:45.412] | : . ' , ; - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:45.412] | : . ' , ; ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.412] | : . ' , ; - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:45.412] | : . ' , ; - attr(*, "call")= language plan(sequential) [22:16:45.415] | : . ' , ; init: TRUE [22:16:45.415] | : . ' , ; makeFutureBackend() ... [22:16:45.416] | : . ' , ; ` Backend function: <'NULL'> [22:16:45.416] | : . ' , ; ` Evaluator tweak arguments: [n=0] [22:16:45.417] | : . ' , ; ` list() [22:16:45.418] | : . ' , ; ` Evaluator formal arguments: [n=1] [22:16:45.418] | : . ' , ; ` Dotted pair list of 1 [22:16:45.418] | : . ' , ; ` $ envir: language parent.frame() [22:16:45.419] | : . ' , ; ` Arguments passed to the future-backend factory: [n=1] [22:16:45.420] | : . ' , ; ` List of 1 [22:16:45.420] | : . ' , ; ` $ envir: language parent.frame() [22:16:45.421] | : . ' , ; ` Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [22:16:45.422] | : . ' , ; makeFutureBackend() ... done [22:16:45.422] | : . ' , plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [22:16:45.423] | : . ' , nbrOfWorkers(NULL) ... [22:16:45.423] | : . ' , ; List of 4 [22:16:45.423] | : . ' , ; $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:45.423] | : . ' , ; $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:45.423] | : . ' , ; $ : language nbrOfWorkers() [22:16:45.423] | : . ' , ; $ : language nbrOfWorkers.NULL() [22:16:45.425] | : . ' , ; plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:45.426] | : . ' , ; plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:45.427] | : . ' , ; Number of workers: 1 [22:16:45.427] | : . ' , nbrOfWorkers(NULL) ... done [22:16:45.428] | : . ' , plan(): nbrOfWorkers() = 1 [22:16:45.428] | : . ' plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:16:45.429] | : . plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done > message("*** capture_journals() ... done") *** capture_journals() ... done > message("*** summary() of FutureJournal ...") *** summary() of FutureJournal ... > js <- do.call(rbind, js) > print(js) event category parent start at 1 create overhead 2026-03-13 22:16:44 0.00000000 secs 4 launch overhead 2026-03-13 22:16:44 0.03033805 secs 2 getWorker overhead launch 2026-03-13 22:16:44 0.04007506 secs 3 eraseWorker overhead launch 2026-03-13 22:16:44 0.05888891 secs 7 evaluate evaluation 2026-03-13 22:16:44 0.07091498 secs 5 receiveResult overhead gather 2026-03-13 22:16:44 0.21637297 secs 6 gather overhead 2026-03-13 22:16:44 0.24619794 secs 11 create overhead 2026-03-13 22:16:44 0.00000000 secs 41 launch overhead 2026-03-13 22:16:44 0.02395701 secs 21 getWorker overhead launch 2026-03-13 22:16:44 0.03216004 secs 31 eraseWorker overhead launch 2026-03-13 22:16:44 0.05670595 secs 71 evaluate evaluation 2026-03-13 22:16:44 0.06806684 secs 51 receiveResult overhead gather 2026-03-13 22:16:45 0.30353093 secs 61 gather overhead 2026-03-13 22:16:45 0.32511806 secs 12 create overhead 2026-03-13 22:16:44 0.00000000 secs 42 launch overhead 2026-03-13 22:16:44 0.02136111 secs 22 getWorker overhead launch 2026-03-13 22:16:44 0.02884507 secs 32 eraseWorker overhead launch 2026-03-13 22:16:45 0.14554811 secs 72 evaluate evaluation 2026-03-13 22:16:45 0.15533209 secs 52 receiveResult overhead gather 2026-03-13 22:16:45 0.31183910 secs 62 gather overhead 2026-03-13 22:16:45 0.33397222 secs duration future_label future_uuid 1 0.0296909809 secs a673d494c643b78e91ab20f02ea45217-1 4 0.0452940464 secs a673d494c643b78e91ab20f02ea45217-1 2 0.0163099766 secs a673d494c643b78e91ab20f02ea45217-1 3 0.0004701614 secs a673d494c643b78e91ab20f02ea45217-1 7 0.0793390274 secs a673d494c643b78e91ab20f02ea45217-1 5 0.0216670036 secs a673d494c643b78e91ab20f02ea45217-1 6 0.0026581287 secs a673d494c643b78e91ab20f02ea45217-1 11 0.0234680176 secs a673d494c643b78e91ab20f02ea45217-2 41 0.0510129929 secs a673d494c643b78e91ab20f02ea45217-2 21 0.0179297924 secs a673d494c643b78e91ab20f02ea45217-2 31 0.0005090237 secs a673d494c643b78e91ab20f02ea45217-2 71 0.0953152180 secs a673d494c643b78e91ab20f02ea45217-2 51 0.0168459415 secs a673d494c643b78e91ab20f02ea45217-2 61 0.0015289783 secs a673d494c643b78e91ab20f02ea45217-2 12 0.0209491253 secs a673d494c643b78e91ab20f02ea45217-3 42 0.1394250393 secs a673d494c643b78e91ab20f02ea45217-3 22 0.1142590046 secs a673d494c643b78e91ab20f02ea45217-3 32 0.0004239082 secs a673d494c643b78e91ab20f02ea45217-3 72 0.0372149944 secs a673d494c643b78e91ab20f02ea45217-3 52 0.0173199177 secs a673d494c643b78e91ab20f02ea45217-3 62 0.0015509129 secs a673d494c643b78e91ab20f02ea45217-3 session_uuid 1 a673d494c643b78e91ab20f02ea45217 4 a673d494c643b78e91ab20f02ea45217 2 a673d494c643b78e91ab20f02ea45217 3 a673d494c643b78e91ab20f02ea45217 7 c6c91120b4bb9305a21a1d0ef435f3aa 5 a673d494c643b78e91ab20f02ea45217 6 a673d494c643b78e91ab20f02ea45217 11 a673d494c643b78e91ab20f02ea45217 41 a673d494c643b78e91ab20f02ea45217 21 a673d494c643b78e91ab20f02ea45217 31 a673d494c643b78e91ab20f02ea45217 71 96078fdda703070070c2e16c3ccbedcb 51 a673d494c643b78e91ab20f02ea45217 61 a673d494c643b78e91ab20f02ea45217 12 a673d494c643b78e91ab20f02ea45217 42 a673d494c643b78e91ab20f02ea45217 22 a673d494c643b78e91ab20f02ea45217 32 a673d494c643b78e91ab20f02ea45217 72 c6c91120b4bb9305a21a1d0ef435f3aa 52 a673d494c643b78e91ab20f02ea45217 62 a673d494c643b78e91ab20f02ea45217 > stats <- summary(js) > print(stats) Number of futures: 3 summary evaluate evaluate_ratio overhead overhead_ratio 1 min 0.03721499 secs 0.1495443 0.07600999 secs 0.3054376 2 mean 0.07062308 secs 0.2325611 0.10519274 secs 0.3463986 3 median 0.07933903 secs 0.2428892 0.07764316 secs 0.2376974 4 max 0.09531522 secs 0.2840794 0.16192508 secs 0.4826048 5 total 0.21186924 secs 0.2325611 0.31557822 secs 0.3463986 duration walltime 1 0.2488561 secs 0.2488561 secs 2 0.3036754 secs 0.3036754 secs 3 0.3266470 secs 0.3266470 secs 4 0.3355231 secs 0.3355231 secs 5 0.9110262 secs 0.9110262 secs > message("*** summary() of FutureJournal ... done") *** summary() of FutureJournal ... done Failed to undo environment variables: - Expected environment variables: [n=218] '!ExitCode', 'ALLUSERSPROFILE', 'APPDATA', 'BIBINPUTS', 'BINDIR', 'BSTINPUTS', 'COMMONPROGRAMFILES', 'COMPUTERNAME', 'COMSPEC', 'CURL_CA_BUNDLE', 'CV_Instance001', 'CYGWIN', 'CommonProgramFiles(x86)', 'CommonProgramW6432', 'DriverData', 'HOME', 'HOMEDRIVE', 'HOMEPATH', 'JAGS_ROOT', 'JAVA_HOME', 'LANGUAGE', 'LC_COLLATE', 'LC_MONETARY', 'LC_TIME', 'LOCALAPPDATA', 'LOGONSERVER', 'LS_HOME', 'LS_LICENSE_PATH', 'MAKE', 'MAKEFLAGS', 'MAKELEVEL', 'MFLAGS', 'MSMPI_BENCHMARKS', 'MSMPI_BIN', 'MSYS2_ENV_CONV_EXCL', 'NUMBER_OF_PROCESSORS', 'OCL', 'OMP_THREAD_LIMIT', 'OS', 'PATH', 'PATHEXT', 'PROCESSOR_ARCHITECTURE', 'PROCESSOR_IDENTIFIER', 'PROCESSOR_LEVEL', 'PROCESSOR_REVISION', 'PROGRAMFILES', 'PROMPT', 'PSModulePath', 'PUBLIC', 'PWD', 'ProgramData', 'ProgramFiles(x86)', 'ProgramW6432', 'RTOOLS44_HOME', 'RTOOLS45_HOME', 'R_ARCH', 'R_BROWSER', 'R_BZIPCMD', 'R_CMD', 'R_COMPILED_BY', 'R_CRAN_WEB', 'R_CUSTOM_TOOLS_PATH', 'R_CUSTOM_TOOLS_SOFT', 'R_DOC_DIR', 'R_ENVIRON_USER', 'R_GSCMD', 'R_GZIPCMD', 'R_HOME', 'R_INCLUDE_DIR', 'R_INSTALL_TAR', 'R_LIBS', 'R_LIBS_SITE', 'R_LIBS_USER', 'R_MAX_NUM_DLLS', 'R_OSTYPE', 'R_PAPERSIZE', 'R_PAPERSIZE_USER', 'R_PARALLELLY_MAKENODEPSOCK_AUTOKILL', 'R_PARALLELLY_MAKENODEPSOCK_CONNECTTIMEOUT', 'R_PARALLELLY_MAKENODEPSOCK_RSCRIPT_LABEL', 'R_PARALLELLY_MAKENODEPSOCK_SESSIONINFO_PKGS', 'R_PARALLELLY_MAKENODEPSOCK_TIMEOUT', 'R_PARALLELLY_RANDOM_PORTS', 'R_PARALLEL_PORT', 'R_RD4PDF', 'R_RTOOLS45_PATH', 'R_SCRIPT_LEGACY', 'R_SHARE_DIR', 'R_TESTME_NAME', 'R_TESTME_PACKAGE', 'R_TESTME_PATH', 'R_TESTS', 'R_UNZIPCMD', 'R_USER', 'R_VERSION', 'R_ZIPCMD', 'SED', 'SHLVL', 'SYSTEMDRIVE', 'SYSTEMROOT', 'TAR', 'TAR_OPTIONS', 'TEMP', 'TERM', 'TETRAD_DIR', 'TEXINPUTS', 'TMP', 'TMPDIR', 'USERDOMAIN', 'USERDOMAIN_ROAMINGPROFILE', 'USERNAME', 'USERPROFILE', 'WINDIR', '_', '_R_CHECK_AUTOCONF_', '_R_CHECK_BOGUS_RETURN_', '_R_CHECK_BROWSER_NONINTERACTIVE_', '_R_CHECK_BUILD_VIGNETTES_SEPARATELY_', '_R_CHECK_CODETOOLS_PROFILE_', '_R_CHECK_CODE_ASSIGN_TO_GLOBALENV_', '_R_CHECK_CODE_ATTACH_', '_R_CHECK_CODE_CLASS_IS_STRING_', '_R_CHECK_CODE_DATA_INTO_GLOBALENV_', '_R_CHECK_CODE_USAGE_VIA_NAMESPACES_', '_R_CHECK_CODE_USAGE_WITHOUT_LOADING_', '_R_CHECK_CODE_USAGE_WITH_ONLY_BASE_ATTACHED_', '_R_CHECK_CODOC_VARIABLES_IN_USAGES_', '_R_CHECK_COMPACT_DATA2_', '_R_CHECK_COMPILATION_FLAGS_', '_R_CHECK_CONNECTIONS_LEFT_OPEN_', '_R_CHECK_CRAN_INCOMING_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_MAYBE_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_START_', '_R_CHECK_CRAN_INCOMING_CHECK_FILE_URIS_', '_R_CHECK_CRAN_INCOMING_CHECK_URLS_IN_PARALLEL_', '_R_CHECK_CRAN_INCOMING_NOTE_GNU_MAKE_', '_R_CHECK_CRAN_INCOMING_REMOTE_', '_R_CHECK_CRAN_INCOMING_USE_ASPELL_', '_R_CHECK_DATALIST_', '_R_CHECK_DEPRECATED_DEFUNCT_', '_R_CHECK_DOC_SIZES2_', '_R_CHECK_DOT_FIRSTLIB_', '_R_CHECK_DOT_INTERNAL_', '_R_CHECK_EXAMPLE_TIMING_THRESHOLD_', '_R_CHECK_EXECUTABLES_', '_R_CHECK_EXECUTABLES_EXCLUSIONS_', '_R_CHECK_FF_CALLS_', '_R_CHECK_FF_DUP_', '_R_CHECK_FORCE_SUGGESTS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_LEEWAY_', '_R_CHECK_HAVE_MYSQL_', '_R_CHECK_HAVE_ODBC_', '_R_CHECK_HAVE_PERL_', '_R_CHECK_HAVE_POSTGRES_', '_R_CHECK_INSTALL_DEPENDS_', '_R_CHECK_INTERNALS2_', '_R_CHECK_LENGTH_1_CONDITION_', '_R_CHECK_LICENSE_', '_R_CHECK_LIMIT_CORES_', '_R_CHECK_LOG_USE_INFO_', '_R_CHECK_MATRIX_DATA_', '_R_CHECK_MBCS_CONVERSION_FAILURE_', '_R_CHECK_NATIVE_ROUTINE_REGISTRATION_', '_R_CHECK_NEWS_IN_PLAIN_TEXT_', '_R_CHECK_NO_RECOMMENDED_', '_R_CHECK_NO_STOP_ON_TEST_ERROR_', '_R_CHECK_ORPHANED_', '_R_CHECK_OVERWRITE_REGISTERED_S3_METHODS_', '_R_CHECK_PACKAGES_USED_IGNORE_UNUSED_IMPORTS_', '_R_CHECK_PACKAGES_USED_IN_TESTS_USE_SUBDIRS_', '_R_CHECK_PACKAGE_DATASETS_SUPPRESS_NOTES_', '_R_CHECK_PACKAGE_NAME_', '_R_CHECK_PKG_SIZES_', '_R_CHECK_PKG_SIZES_THRESHOLD_', '_R_CHECK_PRAGMAS_', '_R_CHECK_RCPP_NOT_NEEDED_', '_R_CHECK_RD_BIBENTRIES_CITED_NOT_SHOWN_', '_R_CHECK_RD_EXAMPLES_T_AND_F_', '_R_CHECK_RD_LINE_WIDTHS_', '_R_CHECK_RD_MATH_RENDERING_', '_R_CHECK_RD_NOTE_LOST_BRACES_', '_R_CHECK_RD_VALIDATE_RD2HTML_', '_R_CHECK_REPLACING_IMPORTS_', '_R_CHECK_R_DEPENDS_', '_R_CHECK_S3_METHODS_SHOW_POSSIBLE_ISSUES_', '_R_CHECK_SCREEN_DEVICE_', '_R_CHECK_SERIALIZATION_', '_R_CHECK_SHLIB_OPENMP_FLAGS_', '_R_CHECK_SRC_MINUS_W_IMPLICIT_', '_R_CHECK_SUBDIRS_NOCASE_', '_R_CHECK_SUGGESTS_ONLY_', '_R_CHECK_SYSTEM_CLOCK_', '_R_CHECK_TESTS_NLINES_', '_R_CHECK_TEST_TIMING_', '_R_CHECK_TIMINGS_', '_R_CHECK_TOPLEVEL_FILES_', '_R_CHECK_UNDOC_USE_ALL_NAMES_', '_R_CHECK_UNSAFE_CALLS_', '_R_CHECK_URLS_RELATIVE_PATHS_', '_R_CHECK_URLS_SHOW_301_STATUS_', '_R_CHECK_VC_DIRS_', '_R_CHECK_VIGNETTES_NLINES_', '_R_CHECK_VIGNETTES_SKIP_RUN_MAYBE_', '_R_CHECK_VIGNETTE_TIMING_', '_R_CHECK_VIGNETTE_TITLES_', '_R_CHECK_WINDOWS_DEVICE_', '_R_CHECK_XREFS_NOTE_MISSING_PACKAGE_ANCHORS_', '_R_CHECK_XREFS_USE_ALIASES_FROM_CRAN_', '_R_CLASS_MATRIX_ARRAY_', '_R_DEPRECATED_IS_R_', '_R_S3_METHOD_LOOKUP_BASEENV_AFTER_GLOBALENV_', '_R_SHLIB_BUILD_OBJECTS_SYMBOL_TABLES_', '_R_USE_STRICT_R_HEADERS_', '__R_CHECK_DOC_FILES_NOTE_IF_ALL_INTERNAL__', 'maj.version', 'nextArg--timingsnextArg--install', 'tempdirname' - Environment variables still there: [n=0] - Environment variables missing: [n=1] 'MAKEFLAGS' Differences environment variable by environment variable: Skipping, because path appears not to be an 'R CMD check' folder: 'D:/temp/2026_03_13_22_15_17_12234' Test time: user.self=0.9s, sys.self=0.1s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'capture_journals' ... success > > proc.time() user system elapsed 1.15 0.15 2.06