R Under development (unstable) (2026-01-15 r89304 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) [12:32:38.877] Option 'future.startup.script': TRUE [12:32:38.879] Future startup scripts considered: '.future.R', '~/.future.R' [12:32:38.880] 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) [12:32:38.884] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:38.891] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [12:32:38.891] | : all.equal() for FutureStrategyList ... [12:32:38.891] | : . New stack: [12:32:38.892] | : . List of 1 [12:32:38.892] | : . $ :function (..., workers = 2) [12:32:38.892] | : . ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:38.892] | : . ..- attr(*, "init")= logi TRUE [12:32:38.892] | : . ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:38.892] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:38.892] | : . .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.892] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.892] | : . ..- attr(*, "untweakable")= chr "persistent" [12:32:38.892] | : . ..- attr(*, "tweaks")=List of 1 [12:32:38.892] | : . .. ..$ workers: num 2 [12:32:38.892] | : . ..- attr(*, "call")= language plan(multisession, workers = 2) [12:32:38.892] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:38.900] | : . Old stack: [12:32:38.900] | : . List of 1 [12:32:38.900] | : . $ :function (..., envir = parent.frame()) [12:32:38.900] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:38.900] | : . ..- attr(*, "init")= logi TRUE [12:32:38.900] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:38.900] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:38.900] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:38.900] | : . ..- attr(*, "call")= language plan(sequential) [12:32:38.900] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:38.906] | : . Not identical [12:32:38.907] | : . all.equal() for future ... [12:32:38.907] | : . ' List of 2 [12:32:38.907] | : . ' $ target :function (..., workers = 2) [12:32:38.907] | : . ' ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:38.907] | : . ' ..- attr(*, "init")= logi TRUE [12:32:38.907] | : . ' ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:38.907] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:38.907] | : . ' .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.907] | : . ' ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.907] | : . ' ..- attr(*, "untweakable")= chr "persistent" [12:32:38.907] | : . ' ..- attr(*, "tweaks")=List of 1 [12:32:38.907] | : . ' .. ..$ workers: num 2 [12:32:38.907] | : . ' ..- attr(*, "call")= language plan(multisession, workers = 2) [12:32:38.907] | : . ' $ current:function (..., envir = parent.frame()) [12:32:38.907] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:38.907] | : . ' ..- attr(*, "init")= logi TRUE [12:32:38.907] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:38.907] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:38.907] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:38.907] | : . ' ..- attr(*, "call")= language plan(sequential) [12:32:38.921] | : . ' Formals differ [12:32:38.922] | : . all.equal() for future ... done [12:32:38.922] | : . Future strategies differ at level 1 [12:32:38.922] | : all.equal() for FutureStrategyList ... done [12:32:38.923] | : plan(): Setting new future backend stack: [12:32:38.923] | : List of future strategies: [12:32:38.923] | : 1. multisession: [12:32:38.923] | : - args: function (..., workers = 2) [12:32:38.923] | : - tweaked: TRUE [12:32:38.923] | : - call: plan(multisession, workers = 2) [12:32:38.924] | : List of 1 [12:32:38.924] | : $ :function (..., workers = 2) [12:32:38.924] | : ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:38.924] | : ..- attr(*, "init")= logi TRUE [12:32:38.924] | : ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:38.924] | : interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:38.924] | : .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.924] | : ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.924] | : ..- attr(*, "untweakable")= chr "persistent" [12:32:38.924] | : ..- attr(*, "tweaks")=List of 1 [12:32:38.924] | : .. ..$ workers: num 2 [12:32:38.924] | : ..- attr(*, "call")= language plan(multisession, workers = 2) [12:32:38.924] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:38.937] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... [12:32:38.937] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:38.937] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [12:32:38.938] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:38.938] | : . Legacy shutdown of cluster workers ... [12:32:38.939] | : . ' Stopping existing cluster ... [12:32:38.939] | : . ' , No pre-existing cluster. Skipping [12:32:38.939] | : . ' Stopping existing cluster ... done [12:32:38.939] | : . Legacy shutdown of cluster workers ... done [12:32:38.940] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... done [12:32:38.940] | : plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... [12:32:38.940] | : . function (..., workers = 2) [12:32:38.940] | : . - attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:38.940] | : . - attr(*, "init")= logi TRUE [12:32:38.940] | : . - attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:38.940] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:38.940] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.940] | : . - attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:38.940] | : . - attr(*, "untweakable")= chr "persistent" [12:32:38.940] | : . - attr(*, "tweaks")=List of 1 [12:32:38.940] | : . ..$ workers: num 2 [12:32:38.940] | : . - attr(*, "call")= language plan(multisession, workers = 2) [12:32:38.974] | : . init: TRUE [12:32:38.975] | : . makeFutureBackend() ... [12:32:38.975] | : . ' Backend function: <'NULL'> [12:32:38.976] | : . ' Evaluator tweak arguments: [n=1] [12:32:38.976] | : . ' List of 1 [12:32:38.976] | : . ' $ workers: num 2 [12:32:38.978] | : . ' Evaluator formal arguments: [n=1] [12:32:38.979] | : . ' Dotted pair list of 1 [12:32:38.979] | : . ' $ workers: num 2 [12:32:38.981] | : . ' Arguments passed to the future-backend factory: [n=1] [12:32:38.981] | : . ' List of 1 [12:32:38.981] | : . ' $ workers: num 2 [12:32:38.983] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... [12:32:38.983] | : . ' , Arguments: [12:32:38.983] | : . ' , List of 5 [12:32:38.983] | : . ' , $ workers : num 2 [12:32:38.983] | : . ' , $ rscript_libs: chr [1:2] "D:/temp/2026_01_16_12_30_17_22682/Rtmp0sfQo7/RLIBS_edd421e5ccf" "D:/RCompile/recent/R/library" [12:32:38.983] | : . ' , $ interrupts : logi TRUE [12:32:38.983] | : . ' , $ gc : logi FALSE [12:32:38.983] | : . ' , $ earlySignal : logi FALSE [12:32:38.989] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... [12:32:38.989] | : . ' , ; workers: 2 [12:32:38.990] | : . ' , ; getCluster() ... [12:32:38.990] | : . ' , ; getCluster() ... done [12:32:38.990] | : . ' , ; Stopping existing cluster ... [12:32:38.991] | : . ' , ; ` No pre-existing cluster. Skipping [12:32:38.991] | : . ' , ; Stopping existing cluster ... done [12:32:38.991] | : . ' , ; Starting new cluster ... [12:32:38.992] | : . ' , ; ` makeCluster(workers, ...) ... [12:32:38.992] | : . ' , ; ` | Arguments: [12:32:38.992] | : . ' , ; ` | List of 2 [12:32:38.992] | : . ' , ; ` | $ : int 2 [12:32:38.992] | : . ' , ; ` | $ rscript_libs: chr [1:2] "D:/temp/2026_01_16_12_30_17_22682/Rtmp0sfQo7/RLIBS_edd421e5ccf" "D:/RCompile/recent/R/library" [12:32:38.995] | : . ' , ; ` | .makeCluster() ... [12:32:38.995] | : . ' , ; ` | : workers: '2' [12:32:38.996] | : . ' , ; ` | : R_FUTURE_PLAN: NA [12:32:38.996] | : . ' , ; ` | : parallelly::makeClusterPSOCK() arguments: [12:32:38.997] | : . ' , ; ` | : List of 2 [12:32:38.997] | : . ' , ; ` | : $ : int 2 [12:32:38.997] | : . ' , ; ` | : $ rscript_libs: chr [1:2] "D:/temp/2026_01_16_12_30_17_22682/Rtmp0sfQo7/RLIBS_edd421e5ccf" "D:/RCompile/recent/R/library" [12:32:40.110] | : . ' , ; ` | : Generated cluster UUID [12:32:40.110] | : . ' , ; ` | : Cluster UUID: 'ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.110] | : . ' , ; ` | : Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.115] | : . ' , ; ` | .makeCluster() ... done [12:32:40.115] | : . ' , ; ` | Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.118] | : . ' , ; ` makeCluster(workers, ...) ... done [12:32:40.118] | : . ' , ; ` Number of workers: 2 [12:32:40.119] | : . ' , ; ` Workers UUID: 'ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.119] | : . ' , ; Starting new cluster ... done [12:32:40.120] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... done [12:32:40.120] | : . ' , Backend: <'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [12:32:40.120] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... done [12:32:40.120] | : . makeFutureBackend() ... done [12:32:40.121] | : . nbrOfWorkers(NULL) ... [12:32:40.121] | : . ' List of 4 [12:32:40.121] | : . ' $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [12:32:40.121] | : . ' $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [12:32:40.121] | : . ' $ : language nbrOfWorkers() [12:32:40.121] | : . ' $ : language nbrOfWorkers.NULL() [12:32:40.124] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.125] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.125] | : . ' Number of workers: 2 [12:32:40.125] | : . nbrOfWorkers(NULL) ... done [12:32:40.126] | : . plan(): nbrOfWorkers() = 2 [12:32:40.126] | : plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... done [12:32:40.126] | 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) + }) [12:32:40.127] | future(..., label = NULL) ... [12:32:40.127] | : lazy: FALSE [12:32:40.127] | : stdout: TRUE [12:32:40.128] | : conditions: [n=1] 'condition' [12:32:40.129] | : getGlobalsAndPackages() ... [12:32:40.129] | : . Searching for globals ... [12:32:40.163] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [12:32:40.163] | : . Searching for globals ... done [12:32:40.164] | : . Resolving globals: FALSE [12:32:40.164] | : . Search for packages associated with the globals ... [12:32:40.165] | : . ' Packages associated with globals: [1] 'base' [12:32:40.166] | : . ' Packages: [1] 'base' [12:32:40.166] | : . Search for packages associated with the globals ... done [12:32:40.167] | : . Packages after dropping 'base': [0] [12:32:40.167] | : . globals: [2] 'slow_fcn', 'x' [12:32:40.167] | : . packages: [0] [12:32:40.167] | : getGlobalsAndPackages() ... done [12:32:40.170] | : run() for 'Future' () ... [12:32:40.170] | : . state: 'created' [12:32:40.170] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.171] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.171] | : . Using MultisessionFutureBackend ... [12:32:40.171] | : . ' Number of futures since start: 0 (0 created, 0 launched, 0 finished) [12:32:40.172] | : . ' Launching futures ... [12:32:40.172] | : . ' , launchFuture() for ClusterFutureBackend ... [12:32:40.173] | : . ' , ; Workers: [n=2] [12:32:40.173] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.176] | : . ' , ; FutureRegistry: 'workers-ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.177] | : . ' , ; requestWorker() ... [12:32:40.177] | : . ' , ; ` requestNode() ... [12:32:40.177] | : . ' , ; ` | Number of workers: 2 [12:32:40.178] | : . ' , ; ` | Polling for a free worker ... [12:32:40.178] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.178] | : . ' , ; ` | : . Created empty registry 'workers-ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.179] | : . ' , ; ` | : . Listing all futures [12:32:40.179] | : . ' , ; ` | : . Number of registered futures: 0 [12:32:40.179] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.180] | : . ' , ; ` | : Total time: 0 [12:32:40.180] | : . ' , ; ` | Polling for a free worker ... done [12:32:40.180] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.181] | : . ' , ; ` | : Listing all futures [12:32:40.181] | : . ' , ; ` | : Number of registered futures: 0 [12:32:40.181] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.181] | : . ' , ; ` | avail: [n=2] '1', '2' [12:32:40.182] | : . ' , ; ` | Index of first available worker: 1 [12:32:40.182] | : . ' , ; ` | Validate that the worker is functional ... [12:32:40.183] | : . ' , ; ` | : Worker is functional [12:32:40.184] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.184] | : . ' , ; ` | : . Listing all futures [12:32:40.185] | : . ' , ; ` | : . Number of registered futures: 0 [12:32:40.185] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.186] | : . ' , ; ` | Validate that the worker is functional ... done [12:32:40.186] | : . ' , ; ` requestNode() ... done [12:32:40.189] | : . ' , ; ` cluster node index: 1 [12:32:40.189] | : . ' , ; requestWorker() ... done [12:32:40.189] | : . ' , ; eraseGlobalEnvironment() ... [12:32:40.192] | : . ' , ; eraseGlobalEnvironment() ... done [12:32:40.192] | : . ' , ; launchFuture() ... [12:32:40.192] | : . ' , ; ` cluster node index: 1 [12:32:40.193] | : . ' , ; ` getFutureData() ... [12:32:40.193] | : . ' , ; ` | getFutureCore() ... [12:32:40.193] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [12:32:40.194] | : . ' , ; ` | getFutureCore() ... done [12:32:40.194] | : . ' , ; ` | getFutureCapture() ... [12:32:40.194] | : . ' , ; ` | getFutureCapture() ... done [12:32:40.195] | : . ' , ; ` | getFutureContext() ... [12:32:40.195] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.195] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [12:32:40.196] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.196] | : . ' , ; ` | : Packages needed by future backend (n = 0): [12:32:40.196] | : . ' , ; ` | getFutureContext() ... done [12:32:40.197] | : . ' , ; ` getFutureData() ... done [12:32:40.198] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... [12:32:40.198] | : . ' , ; ` | Appended future to position #1 [12:32:40.199] | : . ' , ; ` | Number of registered futures: 1 [12:32:40.199] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... done [12:32:40.199] | : . ' , ; launchFuture() ... done [12:32:40.200] | : . ' , ; MultisessionFuture started [12:32:40.200] | : . ' , launchFuture() for ClusterFutureBackend ... done [12:32:40.200] | : . ' Launching futures ... done [12:32:40.201] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [12:32:40.201] | : . Using MultisessionFutureBackend ... done [12:32:40.201] | : run() for 'Future' () ... done [12:32:40.203] | future(..., label = NULL) ... done [12:32:40.206] | future(..., label = NULL) ... [12:32:40.206] | : lazy: FALSE [12:32:40.206] | : stdout: TRUE [12:32:40.206] | : conditions: [n=1] 'condition' [12:32:40.207] | : getGlobalsAndPackages() ... [12:32:40.207] | : . Searching for globals ... [12:32:40.233] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [12:32:40.233] | : . Searching for globals ... done [12:32:40.234] | : . Resolving globals: FALSE [12:32:40.234] | : . Search for packages associated with the globals ... [12:32:40.235] | : . ' Packages associated with globals: [1] 'base' [12:32:40.235] | : . ' Packages: [1] 'base' [12:32:40.236] | : . Search for packages associated with the globals ... done [12:32:40.236] | : . Packages after dropping 'base': [0] [12:32:40.236] | : . globals: [2] 'slow_fcn', 'x' [12:32:40.237] | : . packages: [0] [12:32:40.237] | : getGlobalsAndPackages() ... done [12:32:40.238] | : run() for 'Future' () ... [12:32:40.239] | : . state: 'created' [12:32:40.239] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.239] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.240] | : . Using MultisessionFutureBackend ... [12:32:40.240] | : . ' Number of futures since start: 1 (1 created, 1 launched, 0 finished) [12:32:40.240] | : . ' Launching futures ... [12:32:40.241] | : . ' , launchFuture() for ClusterFutureBackend ... [12:32:40.241] | : . ' , ; Workers: [n=2] [12:32:40.241] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.245] | : . ' , ; FutureRegistry: 'workers-ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.245] | : . ' , ; requestWorker() ... [12:32:40.246] | : . ' , ; ` requestNode() ... [12:32:40.246] | : . ' , ; ` | Number of workers: 2 [12:32:40.246] | : . ' , ; ` | Polling for a free worker ... [12:32:40.246] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.252] | : . ' , ; ` | : . Listing all futures [12:32:40.252] | : . ' , ; ` | : . Number of registered futures: 1 [12:32:40.252] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.252] | : . ' , ; ` | : Total time: 0 [12:32:40.253] | : . ' , ; ` | Polling for a free worker ... done [12:32:40.253] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.253] | : . ' , ; ` | : Listing all futures [12:32:40.253] | : . ' , ; ` | : Number of registered futures: 1 [12:32:40.254] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.254] | : . ' , ; ` | avail: [n=2] '2' [12:32:40.254] | : . ' , ; ` | Index of first available worker: 2 [12:32:40.255] | : . ' , ; ` | Validate that the worker is functional ... [12:32:40.255] | : . ' , ; ` | : Worker is functional [12:32:40.256] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.256] | : . ' , ; ` | : . Listing all futures [12:32:40.256] | : . ' , ; ` | : . Number of registered futures: 1 [12:32:40.256] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.257] | : . ' , ; ` | Validate that the worker is functional ... done [12:32:40.257] | : . ' , ; ` requestNode() ... done [12:32:40.258] | : . ' , ; ` cluster node index: 2 [12:32:40.259] | : . ' , ; requestWorker() ... done [12:32:40.259] | : . ' , ; eraseGlobalEnvironment() ... [12:32:40.261] | : . ' , ; eraseGlobalEnvironment() ... done [12:32:40.261] | : . ' , ; launchFuture() ... [12:32:40.261] | : . ' , ; ` cluster node index: 2 [12:32:40.262] | : . ' , ; ` getFutureData() ... [12:32:40.262] | : . ' , ; ` | getFutureCore() ... [12:32:40.262] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [12:32:40.262] | : . ' , ; ` | getFutureCore() ... done [12:32:40.263] | : . ' , ; ` | getFutureCapture() ... [12:32:40.263] | : . ' , ; ` | getFutureCapture() ... done [12:32:40.263] | : . ' , ; ` | getFutureContext() ... [12:32:40.263] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.264] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [12:32:40.264] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.264] | : . ' , ; ` | : Packages needed by future backend (n = 0): [12:32:40.264] | : . ' , ; ` | getFutureContext() ... done [12:32:40.265] | : . ' , ; ` getFutureData() ... done [12:32:40.265] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... [12:32:40.269] | : . ' , ; ` | Appended future to position #2 [12:32:40.270] | : . ' , ; ` | Number of registered futures: 2 [12:32:40.270] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... done [12:32:40.270] | : . ' , ; launchFuture() ... done [12:32:40.270] | : . ' , ; MultisessionFuture started [12:32:40.271] | : . ' , launchFuture() for ClusterFutureBackend ... done [12:32:40.271] | : . ' Launching futures ... done [12:32:40.271] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [12:32:40.272] | : . Using MultisessionFutureBackend ... done [12:32:40.272] | : run() for 'Future' () ... done [12:32:40.273] | future(..., label = NULL) ... done [12:32:40.273] | future(..., label = NULL) ... [12:32:40.274] | : lazy: FALSE [12:32:40.274] | : stdout: TRUE [12:32:40.274] | : conditions: [n=1] 'condition' [12:32:40.275] | : getGlobalsAndPackages() ... [12:32:40.275] | : . Searching for globals ... [12:32:40.296] | : . ' globals found: [8] 'slow_fcn', 'x', '{', 'Sys.sleep', '*', '(', '+', '/' [12:32:40.296] | : . Searching for globals ... done [12:32:40.297] | : . Resolving globals: FALSE [12:32:40.297] | : . Search for packages associated with the globals ... [12:32:40.297] | : . ' Packages associated with globals: [1] 'base' [12:32:40.298] | : . ' Packages: [1] 'base' [12:32:40.298] | : . Search for packages associated with the globals ... done [12:32:40.299] | : . Packages after dropping 'base': [0] [12:32:40.299] | : . globals: [2] 'slow_fcn', 'x' [12:32:40.299] | : . packages: [0] [12:32:40.300] | : getGlobalsAndPackages() ... done [12:32:40.301] | : run() for 'Future' () ... [12:32:40.301] | : . state: 'created' [12:32:40.301] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.302] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.302] | : . Using MultisessionFutureBackend ... [12:32:40.302] | : . ' Number of futures since start: 2 (2 created, 2 launched, 0 finished) [12:32:40.303] | : . ' Launching futures ... [12:32:40.303] | : . ' , launchFuture() for ClusterFutureBackend ... [12:32:40.303] | : . ' , ; Workers: [n=2] [12:32:40.303] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.306] | : . ' , ; FutureRegistry: 'workers-ce721a5ccb5c3cf90ca284a13e8516d7' [12:32:40.307] | : . ' , ; requestWorker() ... [12:32:40.307] | : . ' , ; ` requestNode() ... [12:32:40.307] | : . ' , ; ` | Number of workers: 2 [12:32:40.307] | : . ' , ; ` | Polling for a free worker ... [12:32:40.308] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.308] | : . ' , ; ` | : . Listing all futures [12:32:40.308] | : . ' , ; ` | : . Number of registered futures: 2 [12:32:40.309] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.309] | : . ' , ; ` | : Poll #1 (0): usedNodes() = 2, workers = 2 [12:32:40.335] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'collect-first', earlySignal = 1) ... [12:32:40.335] | : . ' , ; ` | : . collectValues('workers-ce721a5ccb5c3cf90ca284a13e8516d7', firstOnly = TRUE) ... [12:32:40.336] | : . ' , ; ` | : . ' resolved() ... [12:32:40.337] | : . ' , ; ` | : . ' , resolved() for MultisessionFuture () ... [12:32:40.338] | : . ' , ; ` | : . ' , ; Cluster node socket connection: index=4, id=276 [12:32:40.339] | : . ' , ; ` | : . ' , ; assertValidConnection() ... [12:32:40.339] | : . ' , ; ` | : . ' , ; ` cluster node index: 1 [12:32:40.340] | : . ' , ; ` | : . ' , ; assertValidConnection() ... done [12:32:40.341] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() for ClusterFuture ... [12:32:40.342] | : . ' , ; ` | : . ' , ; ` cluster node index: 1 [12:32:40.342] | : . ' , ; ` | : . ' , ; ` [1] TRUE [12:32:40.343] | : . ' , ; ` | : . ' , ; ` Received data: [12:32:40.343] | : . ' , ; ` | : . ' , ; ` List of 5 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` $ type : chr "VALUE" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` $ value :List of 16 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ value : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ visible : logi FALSE [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ stdout : chr "" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ conditions : list() [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ rng : logi FALSE [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ seed : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ misuseGlobalEnv : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ misuseConnections :List of 3 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ added : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ removed : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ replaced: NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ misuseDevices : NULL [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ misuseDefaultDevice: list() [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ started : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ finished : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ uuid : chr [1:2] "a19befcf1e4d64d756099c078121aec8" "1" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ session_uuid : chr "961e6c1887b40d89d7dbfdcf46a5bbe0" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..- attr(*, "source")=List of 5 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ host : Named chr "CRANWIN3" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ pid : int 109544 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ time : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ random: int 2147483647 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ r_info :List of 4 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. .. ..$ : int [1:3] 4 6 0 [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ os : chr "windows" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ os_name : chr "Windows" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` .. ..$ captures_utf8: logi TRUE [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..$ version : chr "1.8" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..- attr(*, "class")= chr "FutureResult" [12:32:40.343] | : . ' , ; ` | : . ' , ; ` $ success: logi TRUE [12:32:40.343] | : . ' , ; ` | : . ' , ; ` $ time : 'proc_time' Named num [1:5] 0.06 0.01 0.11 NA NA [12:32:40.343] | : . ' , ; ` | : . ' , ; ` ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [12:32:40.343] | : . ' , ; ` | : . ' , ; ` $ tag : NULL [12:32:40.369] | : . ' , ; ` | : . ' , ; ` Received FutureResult [12:32:40.371] | : . ' , ; ` | : . ' , ; ` FutureResult: [12:32:40.371] | : . ' , ; ` | : . ' , ; ` value: 'NULL' [12:32:40.371] | : . ' , ; ` | : . ' , ; ` visible: FALSE [12:32:40.371] | : . ' , ; ` | : . ' , ; ` stdout: character [12:32:40.371] | : . ' , ; ` | : . ' , ; ` conditions: [n = 0] [12:32:40.371] | : . ' , ; ` | : . ' , ; ` RNG used: FALSE [12:32:40.371] | : . ' , ; ` | : . ' , ; ` duration: 0.1088841 secs (started 2026-01-16 12:32:40.199916) [12:32:40.371] | : . ' , ; ` | : . ' , ; ` version: 1.8 [12:32:40.373] | : . ' , ; ` | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... [12:32:40.373] | : . ' , ; ` | : . ' , ; ` | Removed future from position #1 [12:32:40.374] | : . ' , ; ` | : . ' , ; ` | Number of registered futures: 1 [12:32:40.374] | : . ' , ; ` | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... done [12:32:40.375] | : . ' , ; ` | : . ' , ; ` Erased future from future backend [12:32:40.376] | : . ' , ; ` | : . ' , ; ` result() for ClusterFuture ... [12:32:40.376] | : . ' , ; ` | : . ' , ; ` | result already collected: FutureResult [12:32:40.377] | : . ' , ; ` | : . ' , ; ` result() for ClusterFuture ... done [12:32:40.383] | : . ' , ; ` | : . ' , ; ` signalConditions() ... [12:32:40.383] | : . ' , ; ` | : . ' , ; ` | include = 'immediateCondition' [12:32:40.383] | : . ' , ; ` | : . ' , ; ` | exclude = [12:32:40.384] | : . ' , ; ` | : . ' , ; ` | resignal = FALSE [12:32:40.384] | : . ' , ; ` | : . ' , ; ` | Number of conditions: 0 [12:32:40.384] | : . ' , ; ` | : . ' , ; ` | int 0 [12:32:40.385] | : . ' , ; ` | : . ' , ; ` signalConditions() ... done [12:32:40.386] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() for ClusterFuture ... done [12:32:40.386] | : . ' , ; ` | : . ' , ; receiveMessageFromWorker() returned object of class FutureResult; resolved [12:32:40.386] | : . ' , ; ` | : . ' , resolved() for MultisessionFuture () ... done [12:32:40.387] | : . ' , ; ` | : . ' , assertFutureResult() ... [12:32:40.387] | : . ' , ; ` | : . ' , ; Future uuid: a19befcf1e4d64d756099c078121aec8-1 [12:32:40.387] | : . ' , ; ` | : . ' , ; identical; success [12:32:40.388] | : . ' , ; ` | : . ' , assertFutureResult() ... done [12:32:40.388] | : . ' , ; ` | : . ' resolved() ... done [12:32:40.390] | : . ' , ; ` | : . ' Future at position #1 is resolved ... [12:32:40.391] | : . ' , ; ` | : . ' , value() for MultisessionFuture () ... [12:32:40.391] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [12:32:40.392] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [12:32:40.392] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [12:32:40.392] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [12:32:40.393] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [12:32:40.393] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [12:32:40.394] | : . ' , ; ` | : . ' , ; signalConditions() ... [12:32:40.394] | : . ' , ; ` | : . ' , ; ` include = 'immediateCondition' [12:32:40.394] | : . ' , ; ` | : . ' , ; ` exclude = [12:32:40.395] | : . ' , ; ` | : . ' , ; ` resignal = FALSE [12:32:40.395] | : . ' , ; ` | : . ' , ; ` Number of conditions: 0 [12:32:40.395] | : . ' , ; ` | : . ' , ; ` int 0 [12:32:40.396] | : . ' , ; ` | : . ' , ; signalConditions() ... done [12:32:40.397] | : . ' , ; ` | : . ' , ; check for misuse ... [12:32:40.397] | : . ' , ; ` | : . ' , ; check for misuse ... done [12:32:40.397] | : . ' , ; ` | : . ' , value() for MultisessionFuture () ... done [12:32:40.398] | : . ' , ; ` | : . ' Future at position #1 is resolved ... done [12:32:40.398] | : . ' , ; ` | : . collectValues('workers-ce721a5ccb5c3cf90ca284a13e8516d7', firstOnly = TRUE) ... done [12:32:40.399] | : . ' , ; ` | : . Remove collected futures ... [12:32:40.399] | : . ' , ; ` | : . ' Indices of futures to drop: [n=1] '1' [12:32:40.399] | : . ' , ; ` | : . Remove collected futures ... done [12:32:40.400] | : . ' , ; ` | : FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'collect-first', earlySignal = 1) ... done [12:32:40.400] | : . ' , ; ` | : Early signaling of 2 future candidates ... [12:32:40.400] | : . ' , ; ` | : Number of futures with early signaling requested: 0 [12:32:40.401] | : . ' , ; ` | : Early signaling of 2 future candidates ... done [12:32:40.401] | : . ' , ; ` | : Number of registered futures: 2 [12:32:40.401] | : . ' , ; ` | Polling for a free worker ... done [12:32:40.402] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.402] | : . ' , ; ` | : Listing all futures [12:32:40.402] | : . ' , ; ` | : Number of registered futures: 1 [12:32:40.403] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.403] | : . ' , ; ` | Total time: 0.0938329696655273 [12:32:40.403] | : . ' , ; ` requestNode() ... done [12:32:40.404] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.404] | : . ' , ; ` | Listing all futures [12:32:40.404] | : . ' , ; ` | Number of registered futures: 1 [12:32:40.405] | : . ' , ; ` FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.405] | : . ' , ; ` avail: [n=2] '1' [12:32:40.405] | : . ' , ; ` Index of first available worker: 1 [12:32:40.406] | : . ' , ; ` Validate that the worker is functional ... [12:32:40.407] | : . ' , ; ` | Worker is functional [12:32:40.407] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... [12:32:40.407] | : . ' , ; ` | : Listing all futures [12:32:40.408] | : . ' , ; ` | : Number of registered futures: 1 [12:32:40.408] | : . ' , ; ` | FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'list', earlySignal = 0) ... done [12:32:40.408] | : . ' , ; ` Validate that the worker is functional ... done [12:32:40.409] | : . ' , ; requestWorker() ... done [12:32:40.410] | : . ' , ; cluster node index: 1 [12:32:40.411] | : . ' , launchFuture() for ClusterFutureBackend ... done [12:32:40.411] | : . ' , eraseGlobalEnvironment() ... [12:32:40.413] | : . ' , eraseGlobalEnvironment() ... done [12:32:40.414] | : . ' , launchFuture() ... [12:32:40.414] | : . ' , ; cluster node index: 1 [12:32:40.414] | : . ' , ; getFutureData() ... [12:32:40.415] | : . ' , ; ` getFutureCore() ... [12:32:40.415] | : . ' , ; ` | Packages needed by the future expression (n = 0): [12:32:40.415] | : . ' , ; ` getFutureCore() ... done [12:32:40.416] | : . ' , ; ` getFutureCapture() ... [12:32:40.416] | : . ' , ; ` getFutureCapture() ... done [12:32:40.416] | : . ' , ; ` getFutureContext() ... [12:32:40.417] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.417] | : . ' , ; ` | : Getting stack without first backend: [n=0] [12:32:40.417] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.418] | : . ' , ; ` | Packages needed by future backend (n = 0): [12:32:40.418] | : . ' , ; ` getFutureContext() ... done [12:32:40.419] | : . ' , ; getFutureData() ... done [12:32:40.419] | : . ' , ; FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... [12:32:40.420] | : . ' , ; ` Appended future to position #2 [12:32:40.420] | : . ' , ; ` Number of registered futures: 2 [12:32:40.421] | : . ' , ; FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'add', earlySignal = 0) ... done [12:32:40.421] | : . ' , launchFuture() ... done [12:32:40.421] | : . ' , MultisessionFuture started [12:32:40.421] | : . ' Launching futures ... done [12:32:40.422] | : . Using MultisessionFutureBackend ... done [12:32:40.422] | : . Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [12:32:40.423] | : run() for 'Future' () ... done [12:32:40.423] | future(..., label = NULL) ... done [12:32:40.425] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.426] value() for list ... [12:32:40.426] | recursive: 0 [12:32:40.427] | reduce: FALSE [12:32:40.428] | length: 3 [12:32:40.428] | Number of remaining objects: 3 [12:32:40.429] | checking value #1: [12:32:40.433] | 'obj' is MultisessionFuture [12:32:40.433] | resolved() ... [12:32:40.434] | resolved() ... done [12:32:40.435] | MultisessionFuture #1 [12:32:40.436] | signalConditionsASAP(MultisessionFuture, pos=1) ... [12:32:40.436] | : nx: 3 [12:32:40.436] | : relay: TRUE [12:32:40.437] | : stdout: TRUE [12:32:40.437] | : signal: TRUE [12:32:40.437] | : resignal: FALSE [12:32:40.438] | : force: TRUE [12:32:40.438] | : relayed: [n=3] FALSE, FALSE, FALSE [12:32:40.438] | : queued futures: [n=3] FALSE, FALSE, FALSE [12:32:40.439] | : until=1 [12:32:40.439] | : relaying element #1 [12:32:40.439] | : value() for MultisessionFuture () ... [12:32:40.440] | : . result() for ClusterFuture ... [12:32:40.440] | : . ' result already collected: FutureResult [12:32:40.440] | : . result() for ClusterFuture ... done [12:32:40.441] | : . result() for ClusterFuture ... [12:32:40.441] | : . ' result already collected: FutureResult [12:32:40.442] | : . result() for ClusterFuture ... done [12:32:40.442] | : . signalConditions() ... [12:32:40.442] | : . ' include = 'immediateCondition' [12:32:40.443] | : . ' exclude = [12:32:40.443] | : . ' resignal = FALSE [12:32:40.443] | : . ' Number of conditions: 0 [12:32:40.444] | : . ' int 0 [12:32:40.445] | : . signalConditions() ... done [12:32:40.445] | : . relay stdout ... [12:32:40.445] | : . relay stdout ... done [12:32:40.446] | : . check for misuse ... [12:32:40.446] | : . check for misuse ... done [12:32:40.446] | : value() for MultisessionFuture () ... done [12:32:40.447] | : result() for ClusterFuture ... [12:32:40.447] | : . result already collected: FutureResult [12:32:40.447] | : result() for ClusterFuture ... done [12:32:40.448] | : signalConditions() ... [12:32:40.448] | : . include = 'immediateCondition' [12:32:40.449] | : . exclude = [12:32:40.449] | : . resignal = FALSE [12:32:40.449] | : . Number of conditions: 0 [12:32:40.450] | : . int 0 [12:32:40.451] | : signalConditions() ... done [12:32:40.451] | : result() for ClusterFuture ... [12:32:40.451] | : . result already collected: FutureResult [12:32:40.452] | : result() for ClusterFuture ... done [12:32:40.452] | : signalConditions() ... [12:32:40.452] | : . include = 'condition' [12:32:40.453] | : . exclude = 'error', 'immediateCondition' [12:32:40.453] | : . resignal = TRUE [12:32:40.453] | : . Number of conditions: 0 [12:32:40.454] | : . int 0 [12:32:40.455] | : signalConditions() ... done [12:32:40.455] | : relayed: [n=3] TRUE, FALSE, FALSE [12:32:40.455] | : queued futures: [n=3] TRUE, FALSE, FALSE [12:32:40.456] | signalConditionsASAP(MultisessionFuture, pos=1) ... done [12:32:40.456] | value(, ...) ... [12:32:40.456] | value(, ...) ... done [12:32:40.457] | value() for MultisessionFuture () ... [12:32:40.457] | : result() for ClusterFuture ... [12:32:40.457] | : . result already collected: FutureResult [12:32:40.458] | : result() for ClusterFuture ... done [12:32:40.458] | : result() for ClusterFuture ... [12:32:40.458] | : . result already collected: FutureResult [12:32:40.459] | : result() for ClusterFuture ... done [12:32:40.459] | : signalConditions() ... [12:32:40.460] | : . include = 'immediateCondition' [12:32:40.460] | : . exclude = [12:32:40.460] | : . resignal = FALSE [12:32:40.460] | : . Number of conditions: 0 [12:32:40.461] | : . int 0 [12:32:40.462] | : signalConditions() ... done [12:32:40.462] | : check for misuse ... [12:32:40.463] | : check for misuse ... done [12:32:40.463] | value() for MultisessionFuture () ... done [12:32:40.463] | value: [12:32:40.464] | signalConditionsASAP(MultisessionFuture, pos=1) ... [12:32:40.464] | : nx: 3 [12:32:40.464] | : relay: TRUE [12:32:40.464] | : stdout: TRUE [12:32:40.465] | : signal: TRUE [12:32:40.465] | : resignal: FALSE [12:32:40.465] | : force: TRUE [12:32:40.466] | : relayed: [n=3] TRUE, FALSE, FALSE [12:32:40.466] | : queued futures: [n=3] TRUE, FALSE, FALSE [12:32:40.466] | : until=2 [12:32:40.467] | : relaying element #2 [12:32:40.467] | : relayed: [n=3] TRUE, FALSE, FALSE [12:32:40.467] | : queued futures: [n=3] TRUE, FALSE, FALSE [12:32:40.468] | signalConditionsASAP(MultisessionFuture, pos=1) ... done [12:32:40.468] | resolve() for MultisessionFuture ... [12:32:40.470] | : length: 2 (resolved future 1) [12:32:40.470] | resolve() for MultisessionFuture ... done [12:32:40.471] | checking value #2: [12:32:40.471] | 'obj' is MultisessionFuture [12:32:40.471] | resolved() ... [12:32:40.472] | : resolved() for MultisessionFuture () ... [12:32:40.473] | : . Cluster node socket connection: index=5, id=277 [12:32:40.473] | : . assertValidConnection() ... [12:32:40.473] | : . ' cluster node index: 2 [12:32:40.474] | : . assertValidConnection() ... done [12:32:40.475] | : . receiveMessageFromWorker() for ClusterFuture ... [12:32:40.475] | : . ' cluster node index: 2 [12:32:40.475] | : . ' [1] TRUE [12:32:40.476] | : . ' Received data: [12:32:40.476] | : . ' List of 5 [12:32:40.476] | : . ' $ type : chr "VALUE" [12:32:40.476] | : . ' $ value :List of 16 [12:32:40.476] | : . ' ..$ value : NULL [12:32:40.476] | : . ' ..$ visible : logi FALSE [12:32:40.476] | : . ' ..$ stdout : chr "" [12:32:40.476] | : . ' ..$ conditions : list() [12:32:40.476] | : . ' ..$ rng : logi FALSE [12:32:40.476] | : . ' ..$ seed : NULL [12:32:40.476] | : . ' ..$ misuseGlobalEnv : NULL [12:32:40.476] | : . ' ..$ misuseConnections :List of 3 [12:32:40.476] | : . ' .. ..$ added : NULL [12:32:40.476] | : . ' .. ..$ removed : NULL [12:32:40.476] | : . ' .. ..$ replaced: NULL [12:32:40.476] | : . ' ..$ misuseDevices : NULL [12:32:40.476] | : . ' ..$ misuseDefaultDevice: list() [12:32:40.476] | : . ' ..$ started : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.476] | : . ' ..$ finished : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.476] | : . ' ..$ uuid : chr [1:2] "a19befcf1e4d64d756099c078121aec8" "2" [12:32:40.476] | : . ' ..$ session_uuid : chr "5af5c023770203df31c1a5c7a9029975" [12:32:40.476] | : . ' .. ..- attr(*, "source")=List of 5 [12:32:40.476] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [12:32:40.476] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [12:32:40.476] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [12:32:40.476] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [12:32:40.476] | : . ' .. .. ..$ pid : int 71700 [12:32:40.476] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.476] | : . ' .. .. ..$ random: int 2147483647 [12:32:40.476] | : . ' ..$ r_info :List of 4 [12:32:40.476] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [12:32:40.476] | : . ' .. .. ..$ : int [1:3] 4 6 0 [12:32:40.476] | : . ' .. ..$ os : chr "windows" [12:32:40.476] | : . ' .. ..$ os_name : chr "Windows" [12:32:40.476] | : . ' .. ..$ captures_utf8: logi TRUE [12:32:40.476] | : . ' ..$ version : chr "1.8" [12:32:40.476] | : . ' ..- attr(*, "class")= chr "FutureResult" [12:32:40.476] | : . ' $ success: logi TRUE [12:32:40.476] | : . ' $ time : 'proc_time' Named num [1:5] 0.08 0 0.11 NA NA [12:32:40.476] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [12:32:40.476] | : . ' $ tag : NULL [12:32:40.503] | : . ' Received FutureResult [12:32:40.504] | : . ' FutureResult: [12:32:40.504] | : . ' value: 'NULL' [12:32:40.504] | : . ' visible: FALSE [12:32:40.504] | : . ' stdout: character [12:32:40.504] | : . ' conditions: [n = 0] [12:32:40.504] | : . ' RNG used: FALSE [12:32:40.504] | : . ' duration: 0.1037579 secs (started 2026-01-16 12:32:40.267429) [12:32:40.504] | : . ' version: 1.8 [12:32:40.505] | : . ' FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... [12:32:40.506] | : . ' , Removed future from position #1 [12:32:40.506] | : . ' , Number of registered futures: 1 [12:32:40.507] | : . ' FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... done [12:32:40.507] | : . ' Erased future from future backend [12:32:40.507] | : . ' result() for ClusterFuture ... [12:32:40.508] | : . ' , result already collected: FutureResult [12:32:40.508] | : . ' result() for ClusterFuture ... done [12:32:40.513] | : . ' signalConditions() ... [12:32:40.514] | : . ' , include = 'immediateCondition' [12:32:40.514] | : . ' , exclude = [12:32:40.514] | : . ' , resignal = FALSE [12:32:40.515] | : . ' , Number of conditions: 0 [12:32:40.515] | : . ' , int 0 [12:32:40.516] | : . ' signalConditions() ... done [12:32:40.516] | : . receiveMessageFromWorker() for ClusterFuture ... done [12:32:40.517] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [12:32:40.517] | : resolved() for MultisessionFuture () ... done [12:32:40.517] | : assertFutureResult() ... [12:32:40.518] | : . Future uuid: a19befcf1e4d64d756099c078121aec8-2 [12:32:40.518] | : . identical; success [12:32:40.518] | : assertFutureResult() ... done [12:32:40.519] | resolved() ... done [12:32:40.520] | MultisessionFuture #2 [12:32:40.521] | signalConditionsASAP(MultisessionFuture, pos=2) ... [12:32:40.521] | : nx: 3 [12:32:40.521] | : relay: TRUE [12:32:40.522] | : stdout: TRUE [12:32:40.522] | : signal: TRUE [12:32:40.522] | : resignal: FALSE [12:32:40.523] | : force: TRUE [12:32:40.523] | : relayed: [n=3] TRUE, FALSE, FALSE [12:32:40.523] | : queued futures: [n=3] TRUE, FALSE, FALSE [12:32:40.524] | : until=2 [12:32:40.524] | : relaying element #2 [12:32:40.524] | : value() for MultisessionFuture () ... [12:32:40.525] | : . result() for ClusterFuture ... [12:32:40.525] | : . ' result already collected: FutureResult [12:32:40.526] | : . result() for ClusterFuture ... done [12:32:40.526] | : . result() for ClusterFuture ... [12:32:40.526] | : . ' result already collected: FutureResult [12:32:40.527] | : . result() for ClusterFuture ... done [12:32:40.527] | : . signalConditions() ... [12:32:40.527] | : . ' include = 'immediateCondition' [12:32:40.528] | : . ' exclude = [12:32:40.528] | : . ' resignal = FALSE [12:32:40.528] | : . ' Number of conditions: 0 [12:32:40.529] | : . ' int 0 [12:32:40.530] | : . signalConditions() ... done [12:32:40.530] | : . relay stdout ... [12:32:40.531] | : . relay stdout ... done [12:32:40.531] | : . check for misuse ... [12:32:40.531] | : . check for misuse ... done [12:32:40.532] | : value() for MultisessionFuture () ... done [12:32:40.532] | : result() for ClusterFuture ... [12:32:40.532] | : . result already collected: FutureResult [12:32:40.533] | : result() for ClusterFuture ... done [12:32:40.533] | : signalConditions() ... [12:32:40.533] | : . include = 'immediateCondition' [12:32:40.534] | : . exclude = [12:32:40.534] | : . resignal = FALSE [12:32:40.534] | : . Number of conditions: 0 [12:32:40.535] | : . int 0 [12:32:40.536] | : signalConditions() ... done [12:32:40.536] | : result() for ClusterFuture ... [12:32:40.536] | : . result already collected: FutureResult [12:32:40.537] | : result() for ClusterFuture ... done [12:32:40.537] | : signalConditions() ... [12:32:40.537] | : . include = 'condition' [12:32:40.538] | : . exclude = 'error', 'immediateCondition' [12:32:40.538] | : . resignal = TRUE [12:32:40.538] | : . Number of conditions: 0 [12:32:40.539] | : . int 0 [12:32:40.540] | : signalConditions() ... done [12:32:40.540] | : relayed: [n=3] TRUE, TRUE, FALSE [12:32:40.540] | : queued futures: [n=3] TRUE, TRUE, FALSE [12:32:40.541] | signalConditionsASAP(MultisessionFuture, pos=2) ... done [12:32:40.541] | value(, ...) ... [12:32:40.541] | value(, ...) ... done [12:32:40.542] | value() for MultisessionFuture () ... [12:32:40.542] | : result() for ClusterFuture ... [12:32:40.542] | : . result already collected: FutureResult [12:32:40.543] | : result() for ClusterFuture ... done [12:32:40.543] | : result() for ClusterFuture ... [12:32:40.544] | : . result already collected: FutureResult [12:32:40.544] | : result() for ClusterFuture ... done [12:32:40.544] | : signalConditions() ... [12:32:40.545] | : . include = 'immediateCondition' [12:32:40.545] | : . exclude = [12:32:40.545] | : . resignal = FALSE [12:32:40.546] | : . Number of conditions: 0 [12:32:40.546] | : . int 0 [12:32:40.547] | : signalConditions() ... done [12:32:40.547] | : check for misuse ... [12:32:40.548] | : check for misuse ... done [12:32:40.548] | value() for MultisessionFuture () ... done [12:32:40.548] | value: [12:32:40.549] | signalConditionsASAP(MultisessionFuture, pos=2) ... [12:32:40.549] | : nx: 3 [12:32:40.549] | : relay: TRUE [12:32:40.550] | : stdout: TRUE [12:32:40.550] | : signal: TRUE [12:32:40.550] | : resignal: FALSE [12:32:40.551] | : force: TRUE [12:32:40.551] | : relayed: [n=3] TRUE, TRUE, FALSE [12:32:40.551] | : queued futures: [n=3] TRUE, TRUE, FALSE [12:32:40.552] | : until=3 [12:32:40.552] | : relaying element #3 [12:32:40.552] | : relayed: [n=3] TRUE, TRUE, FALSE [12:32:40.553] | : queued futures: [n=3] TRUE, TRUE, FALSE [12:32:40.553] | signalConditionsASAP(MultisessionFuture, pos=2) ... done [12:32:40.553] | resolve() for MultisessionFuture ... [12:32:40.555] | : length: 1 (resolved future 2) [12:32:40.555] | resolve() for MultisessionFuture ... done [12:32:40.556] | checking value #3: [12:32:40.556] | 'obj' is MultisessionFuture [12:32:40.556] | resolved() ... [12:32:40.557] | : resolved() for MultisessionFuture () ... [12:32:40.557] | : . Cluster node socket connection: index=4, id=276 [12:32:40.558] | : . assertValidConnection() ... [12:32:40.558] | : . ' cluster node index: 1 [12:32:40.559] | : . assertValidConnection() ... done [12:32:40.559] | : . receiveMessageFromWorker() for ClusterFuture ... [12:32:40.560] | : . ' cluster node index: 1 [12:32:40.560] | : . ' [1] TRUE [12:32:40.561] | : . ' Received data: [12:32:40.561] | : . ' List of 5 [12:32:40.561] | : . ' $ type : chr "VALUE" [12:32:40.561] | : . ' $ value :List of 16 [12:32:40.561] | : . ' ..$ value : NULL [12:32:40.561] | : . ' ..$ visible : logi FALSE [12:32:40.561] | : . ' ..$ stdout : chr "" [12:32:40.561] | : . ' ..$ conditions : list() [12:32:40.561] | : . ' ..$ rng : logi FALSE [12:32:40.561] | : . ' ..$ seed : NULL [12:32:40.561] | : . ' ..$ misuseGlobalEnv : NULL [12:32:40.561] | : . ' ..$ misuseConnections :List of 3 [12:32:40.561] | : . ' .. ..$ added : NULL [12:32:40.561] | : . ' .. ..$ removed : NULL [12:32:40.561] | : . ' .. ..$ replaced: NULL [12:32:40.561] | : . ' ..$ misuseDevices : NULL [12:32:40.561] | : . ' ..$ misuseDefaultDevice: list() [12:32:40.561] | : . ' ..$ started : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.561] | : . ' ..$ finished : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.561] | : . ' ..$ uuid : chr [1:2] "a19befcf1e4d64d756099c078121aec8" "3" [12:32:40.561] | : . ' ..$ session_uuid : chr "961e6c1887b40d89d7dbfdcf46a5bbe0" [12:32:40.561] | : . ' .. ..- attr(*, "source")=List of 5 [12:32:40.561] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [12:32:40.561] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [12:32:40.561] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [12:32:40.561] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [12:32:40.561] | : . ' .. .. ..$ pid : int 109544 [12:32:40.561] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-01-16 12:32:40" [12:32:40.561] | : . ' .. .. ..$ random: int 2147483647 [12:32:40.561] | : . ' ..$ r_info :List of 4 [12:32:40.561] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [12:32:40.561] | : . ' .. .. ..$ : int [1:3] 4 6 0 [12:32:40.561] | : . ' .. ..$ os : chr "windows" [12:32:40.561] | : . ' .. ..$ os_name : chr "Windows" [12:32:40.561] | : . ' .. ..$ captures_utf8: logi TRUE [12:32:40.561] | : . ' ..$ version : chr "1.8" [12:32:40.561] | : . ' ..- attr(*, "class")= chr "FutureResult" [12:32:40.561] | : . ' $ success: logi TRUE [12:32:40.561] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0.03 NA NA [12:32:40.561] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [12:32:40.561] | : . ' $ tag : NULL [12:32:40.587] | : . ' Received FutureResult [12:32:40.589] | : . ' FutureResult: [12:32:40.589] | : . ' value: 'NULL' [12:32:40.589] | : . ' visible: FALSE [12:32:40.589] | : . ' stdout: character [12:32:40.589] | : . ' conditions: [n = 0] [12:32:40.589] | : . ' RNG used: FALSE [12:32:40.589] | : . ' duration: 0.02697206 secs (started 2026-01-16 12:32:40.420109) [12:32:40.589] | : . ' version: 1.8 [12:32:40.590] | : . ' FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... [12:32:40.591] | : . ' , Removed future from position #1 [12:32:40.591] | : . ' , Number of registered futures: 0 [12:32:40.592] | : . ' FutureRegistry('workers-ce721a5ccb5c3cf90ca284a13e8516d7', action = 'remove', earlySignal = 0) ... done [12:32:40.592] | : . ' Erased future from future backend [12:32:40.593] | : . ' result() for ClusterFuture ... [12:32:40.593] | : . ' , result already collected: FutureResult [12:32:40.593] | : . ' result() for ClusterFuture ... done [12:32:40.599] | : . ' signalConditions() ... [12:32:40.599] | : . ' , include = 'immediateCondition' [12:32:40.600] | : . ' , exclude = [12:32:40.600] | : . ' , resignal = FALSE [12:32:40.600] | : . ' , Number of conditions: 0 [12:32:40.601] | : . ' , int 0 [12:32:40.602] | : . ' signalConditions() ... done [12:32:40.602] | : . receiveMessageFromWorker() for ClusterFuture ... done [12:32:40.602] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [12:32:40.603] | : resolved() for MultisessionFuture () ... done [12:32:40.603] | : assertFutureResult() ... [12:32:40.603] | : . Future uuid: a19befcf1e4d64d756099c078121aec8-3 [12:32:40.604] | : . identical; success [12:32:40.604] | : assertFutureResult() ... done [12:32:40.604] | resolved() ... done [12:32:40.606] | MultisessionFuture #3 [12:32:40.606] | signalConditionsASAP(MultisessionFuture, pos=3) ... [12:32:40.606] | : nx: 3 [12:32:40.607] | : relay: TRUE [12:32:40.607] | : stdout: TRUE [12:32:40.607] | : signal: TRUE [12:32:40.607] | : resignal: FALSE [12:32:40.608] | : force: TRUE [12:32:40.608] | : relayed: [n=3] TRUE, TRUE, FALSE [12:32:40.608] | : queued futures: [n=3] TRUE, TRUE, FALSE [12:32:40.608] | : until=3 [12:32:40.609] | : relaying element #3 [12:32:40.609] | : value() for MultisessionFuture () ... [12:32:40.609] | : . result() for ClusterFuture ... [12:32:40.609] | : . ' result already collected: FutureResult [12:32:40.609] | : . result() for ClusterFuture ... done [12:32:40.610] | : . result() for ClusterFuture ... [12:32:40.610] | : . ' result already collected: FutureResult [12:32:40.610] | : . result() for ClusterFuture ... done [12:32:40.610] | : . signalConditions() ... [12:32:40.611] | : . ' include = 'immediateCondition' [12:32:40.611] | : . ' exclude = [12:32:40.611] | : . ' resignal = FALSE [12:32:40.611] | : . ' Number of conditions: 0 [12:32:40.611] | : . ' int 0 [12:32:40.612] | : . signalConditions() ... done [12:32:40.612] | : . relay stdout ... [12:32:40.613] | : . relay stdout ... done [12:32:40.613] | : . check for misuse ... [12:32:40.613] | : . check for misuse ... done [12:32:40.613] | : value() for MultisessionFuture () ... done [12:32:40.613] | : result() for ClusterFuture ... [12:32:40.614] | : . result already collected: FutureResult [12:32:40.614] | : result() for ClusterFuture ... done [12:32:40.614] | : signalConditions() ... [12:32:40.614] | : . include = 'immediateCondition' [12:32:40.614] | : . exclude = [12:32:40.615] | : . resignal = FALSE [12:32:40.615] | : . Number of conditions: 0 [12:32:40.615] | : . int 0 [12:32:40.616] | : signalConditions() ... done [12:32:40.616] | : result() for ClusterFuture ... [12:32:40.616] | : . result already collected: FutureResult [12:32:40.617] | : result() for ClusterFuture ... done [12:32:40.617] | : signalConditions() ... [12:32:40.617] | : . include = 'condition' [12:32:40.618] | : . exclude = 'error', 'immediateCondition' [12:32:40.618] | : . resignal = TRUE [12:32:40.618] | : . Number of conditions: 0 [12:32:40.619] | : . int 0 [12:32:40.620] | : signalConditions() ... done [12:32:40.620] | : relayed: [n=3] TRUE, TRUE, TRUE [12:32:40.620] | : queued futures: [n=3] TRUE, TRUE, TRUE [12:32:40.621] | signalConditionsASAP(MultisessionFuture, pos=3) ... done [12:32:40.621] | value(, ...) ... [12:32:40.621] | value(, ...) ... done [12:32:40.621] | value() for MultisessionFuture () ... [12:32:40.622] | : result() for ClusterFuture ... [12:32:40.622] | : . result already collected: FutureResult [12:32:40.622] | : result() for ClusterFuture ... done [12:32:40.623] | : result() for ClusterFuture ... [12:32:40.623] | : . result already collected: FutureResult [12:32:40.624] | : result() for ClusterFuture ... done [12:32:40.624] | : signalConditions() ... [12:32:40.624] | : . include = 'immediateCondition' [12:32:40.625] | : . exclude = [12:32:40.625] | : . resignal = FALSE [12:32:40.625] | : . Number of conditions: 0 [12:32:40.626] | : . int 0 [12:32:40.627] | : signalConditions() ... done [12:32:40.627] | : check for misuse ... [12:32:40.627] | : check for misuse ... done [12:32:40.628] | value() for MultisessionFuture () ... done [12:32:40.628] | value: [12:32:40.628] | signalConditionsASAP(MultisessionFuture, pos=3) ... [12:32:40.629] | : nx: 3 [12:32:40.629] | : relay: TRUE [12:32:40.629] | : stdout: TRUE [12:32:40.630] | : signal: TRUE [12:32:40.630] | : resignal: FALSE [12:32:40.630] | : force: TRUE [12:32:40.631] | : relayed: [n=3] TRUE, TRUE, TRUE [12:32:40.631] | : queued futures: [n=3] TRUE, TRUE, TRUE [12:32:40.631] | : until=3 [12:32:40.632] | : relayed: [n=3] TRUE, TRUE, TRUE [12:32:40.632] | : queued futures: [n=3] TRUE, TRUE, TRUE [12:32:40.632] | signalConditionsASAP(MultisessionFuture, pos=3) ... done [12:32:40.633] | resolve() for MultisessionFuture ... [12:32:40.635] | : length: 0 (resolved future 3) [12:32:40.635] | resolve() for MultisessionFuture ... done [12:32:40.635] | Relaying remaining futures ... [12:32:40.636] | : signalConditionsASAP(NULL, pos=0) ... [12:32:40.636] | : . nx: 3 [12:32:40.636] | : . relay: TRUE [12:32:40.637] | : . stdout: TRUE [12:32:40.637] | : . signal: TRUE [12:32:40.637] | : . resignal: FALSE [12:32:40.638] | : . force: TRUE [12:32:40.638] | : . relayed: [n=3] TRUE, TRUE, TRUE [12:32:40.638] | : . queued futures: [n=3] TRUE, TRUE, TRUE - flush all [12:32:40.639] | : . relayed: [n=3] TRUE, TRUE, TRUE [12:32:40.639] | : . queued futures: [n=3] TRUE, TRUE, TRUE [12:32:40.640] | : signalConditionsASAP(NULL, pos=0) ... done [12:32:40.640] | Relaying remaining futures ... done [12:32:40.640] value() for list ... done > print(js) [[1]] event category parent start at 1 create overhead 2026-01-16 12:32:40 0.00000000 secs 4 launch overhead 2026-01-16 12:32:40 0.04123306 secs 2 getWorker overhead launch 2026-01-16 12:32:40 0.04891014 secs 3 eraseWorker overhead launch 2026-01-16 12:32:40 0.06162310 secs 7 evaluate evaluation 2026-01-16 12:32:40 0.07158399 secs 5 receiveResult overhead gather 2026-01-16 12:32:40 0.21420622 secs 6 gather overhead 2026-01-16 12:32:40 0.24776912 secs duration future_label future_uuid 1 0.040354013 secs a19befcf1e4d64d756099c078121aec8-1 4 0.032586098 secs a19befcf1e4d64d756099c078121aec8-1 2 0.010431051 secs a19befcf1e4d64d756099c078121aec8-1 3 0.001135111 secs a19befcf1e4d64d756099c078121aec8-1 7 0.108884096 secs a19befcf1e4d64d756099c078121aec8-1 5 0.027892828 secs a19befcf1e4d64d756099c078121aec8-1 6 0.001333952 secs a19befcf1e4d64d756099c078121aec8-1 session_uuid 1 a19befcf1e4d64d756099c078121aec8 4 a19befcf1e4d64d756099c078121aec8 2 a19befcf1e4d64d756099c078121aec8 3 a19befcf1e4d64d756099c078121aec8 7 961e6c1887b40d89d7dbfdcf46a5bbe0 5 a19befcf1e4d64d756099c078121aec8 6 a19befcf1e4d64d756099c078121aec8 [[2]] event category parent start at 1 create overhead 2026-01-16 12:32:40 0.00000000 secs 4 launch overhead 2026-01-16 12:32:40 0.03162980 secs 2 getWorker overhead launch 2026-01-16 12:32:40 0.03843880 secs 3 eraseWorker overhead launch 2026-01-16 12:32:40 0.05245280 secs 7 evaluate evaluation 2026-01-16 12:32:40 0.06019902 secs 5 receiveResult overhead gather 2026-01-16 12:32:40 0.26852298 secs 6 gather overhead 2026-01-16 12:32:40 0.30070996 secs duration future_label future_uuid 1 0.0308938026 secs a19befcf1e4d64d756099c078121aec8-2 4 0.0337991714 secs a19befcf1e4d64d756099c078121aec8-2 2 0.0121111870 secs a19befcf1e4d64d756099c078121aec8-2 3 0.0005600452 secs a19befcf1e4d64d756099c078121aec8-2 7 0.1037578583 secs a19befcf1e4d64d756099c078121aec8-2 5 0.0278189182 secs a19befcf1e4d64d756099c078121aec8-2 6 0.0011620522 secs a19befcf1e4d64d756099c078121aec8-2 session_uuid 1 a19befcf1e4d64d756099c078121aec8 4 a19befcf1e4d64d756099c078121aec8 2 a19befcf1e4d64d756099c078121aec8 3 a19befcf1e4d64d756099c078121aec8 7 5af5c023770203df31c1a5c7a9029975 5 a19befcf1e4d64d756099c078121aec8 6 a19befcf1e4d64d756099c078121aec8 [[3]] event category parent start at 1 create overhead 2026-01-16 12:32:40 0.00000000 secs 4 launch overhead 2026-01-16 12:32:40 0.02619791 secs 2 getWorker overhead launch 2026-01-16 12:32:40 0.03205490 secs 3 eraseWorker overhead launch 2026-01-16 12:32:40 0.13699007 secs 7 evaluate evaluation 2026-01-16 12:32:40 0.14510703 secs 5 receiveResult overhead gather 2026-01-16 12:32:40 0.28563094 secs 6 gather overhead 2026-01-16 12:32:40 0.31799507 secs duration future_label future_uuid 1 0.0256209373 secs a19befcf1e4d64d756099c078121aec8-3 4 0.1225910187 secs a19befcf1e4d64d756099c078121aec8-3 2 0.1025490761 secs a19befcf1e4d64d756099c078121aec8-3 3 0.0006349087 secs a19befcf1e4d64d756099c078121aec8-3 7 0.0269720554 secs a19befcf1e4d64d756099c078121aec8-3 5 0.0278489590 secs a19befcf1e4d64d756099c078121aec8-3 6 0.0006949902 secs a19befcf1e4d64d756099c078121aec8-3 session_uuid 1 a19befcf1e4d64d756099c078121aec8 4 a19befcf1e4d64d756099c078121aec8 2 a19befcf1e4d64d756099c078121aec8 3 a19befcf1e4d64d756099c078121aec8 7 961e6c1887b40d89d7dbfdcf46a5bbe0 5 a19befcf1e4d64d756099c078121aec8 6 a19befcf1e4d64d756099c078121aec8 > stopifnot(is.list(js), all(vapply(js, FUN = is.data.frame, + FUN.VALUE = NA))) > plan(sequential) [12:32:40.652] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.653] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [12:32:40.653] | : all.equal() for FutureStrategyList ... [12:32:40.654] | : . New stack: [12:32:40.654] | : . List of 1 [12:32:40.654] | : . $ :function (..., envir = parent.frame()) [12:32:40.654] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:40.654] | : . ..- attr(*, "init")= logi TRUE [12:32:40.654] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:40.654] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.654] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.654] | : . ..- attr(*, "call")= language plan(sequential) [12:32:40.654] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:40.660] | : . Old stack: [12:32:40.660] | : . List of 1 [12:32:40.660] | : . $ :function (..., workers = 2) [12:32:40.660] | : . ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:40.660] | : . ..- attr(*, "init")= chr "done" [12:32:40.660] | : . ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:40.660] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:40.660] | : . .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.660] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.660] | : . ..- attr(*, "untweakable")= chr "persistent" [12:32:40.660] | : . ..- attr(*, "tweaks")=List of 1 [12:32:40.660] | : . .. ..$ workers: num 2 [12:32:40.660] | : . ..- attr(*, "call")= language plan(multisession, workers = 2) [12:32:40.660] | : . ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [12:32:40.660] | : . .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:40.660] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:40.660] | : . .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.660] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:40.670] | : . Not identical [12:32:40.671] | : . all.equal() for future ... [12:32:40.671] | : . ' List of 2 [12:32:40.671] | : . ' $ target :function (..., envir = parent.frame()) [12:32:40.671] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:40.671] | : . ' ..- attr(*, "init")= logi TRUE [12:32:40.671] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:40.671] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.671] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.671] | : . ' ..- attr(*, "call")= language plan(sequential) [12:32:40.671] | : . ' $ current:function (..., workers = 2) [12:32:40.671] | : . ' ..- attr(*, "class")= chr [1:6] "tweaked" "multisession" "cluster" "multiprocess" ... [12:32:40.671] | : . ' ..- attr(*, "init")= chr "done" [12:32:40.671] | : . ' ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:40.671] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:40.671] | : . ' .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.671] | : . ' ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.671] | : . ' ..- attr(*, "untweakable")= chr "persistent" [12:32:40.671] | : . ' ..- attr(*, "tweaks")=List of 1 [12:32:40.671] | : . ' .. ..$ workers: num 2 [12:32:40.671] | : . ' ..- attr(*, "call")= language plan(multisession, workers = 2) [12:32:40.671] | : . ' ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [12:32:40.671] | : . ' .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [12:32:40.671] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [12:32:40.671] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [12:32:40.682] | : . ' Formals differ [12:32:40.683] | : . all.equal() for future ... done [12:32:40.683] | : . Future strategies differ at level 1 [12:32:40.683] | : all.equal() for FutureStrategyList ... done [12:32:40.684] | : plan(): Setting new future backend stack: [12:32:40.684] | : List of future strategies: [12:32:40.684] | : 1. sequential: [12:32:40.684] | : - args: function (..., envir = parent.frame(), workers = "") [12:32:40.684] | : - tweaked: FALSE [12:32:40.684] | : - call: plan(sequential) [12:32:40.685] | : List of 1 [12:32:40.685] | : $ :function (..., envir = parent.frame()) [12:32:40.685] | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:40.685] | : ..- attr(*, "init")= logi TRUE [12:32:40.685] | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:40.685] | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.685] | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.685] | : ..- attr(*, "call")= language plan(sequential) [12:32:40.685] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [12:32:40.688] | : plan(): plan_cleanup(<'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [12:32:40.689] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.689] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' [12:32:40.689] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.689] | : . stopWorkers() for MultisessionFutureBackend ... [12:32:40.690] | : . ' Interrupt active futures ... [12:32:40.691] | : . ' , Number of futures: 0 [12:32:40.691] | : . ' Interrupt active futures ... done [12:32:40.691] | : . ' Clear future registry ... [12:32:40.692] | : . ' Clear future registry ... done [12:32:40.692] | : . ' Stop cluster workers ... [12:32:40.692] | : . ' , Stopping existing cluster ... [12:32:40.692] | : . ' , ; Cluster to shut down: [12:32:40.693] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-01-15 r89304 ucrt), platform x86_64-w64-mingw32) [12:32:40.705] | : . ' , ; Stopped cluster: 'c(TRUE, TRUE)' [12:32:40.751] | : . ' , Stopping existing cluster ... done [12:32:40.751] | : . ' Stop cluster workers ... done [12:32:40.751] | : . stopWorkers() for MultisessionFutureBackend ... done [12:32:40.751] | : . Legacy shutdown of cluster workers ... [12:32:40.752] | : . ' Stopping existing cluster ... [12:32:40.752] | : . ' , No pre-existing cluster. Skipping [12:32:40.752] | : . ' Stopping existing cluster ... done [12:32:40.752] | : . Legacy shutdown of cluster workers ... done [12:32:40.753] | : plan(): plan_cleanup(<'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [12:32:40.753] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [12:32:40.753] | : . function (..., envir = parent.frame()) [12:32:40.753] | : . - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [12:32:40.753] | : . - attr(*, "init")= logi TRUE [12:32:40.753] | : . - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [12:32:40.753] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.753] | : . - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [12:32:40.753] | : . - attr(*, "call")= language plan(sequential) [12:32:40.757] | : . init: TRUE [12:32:40.757] | : . makeFutureBackend() ... [12:32:40.757] | : . ' Backend function: <'NULL'> [12:32:40.757] | : . ' Evaluator tweak arguments: [n=0] [12:32:40.758] | : . ' list() [12:32:40.758] | : . ' Evaluator formal arguments: [n=1] [12:32:40.758] | : . ' Dotted pair list of 1 [12:32:40.758] | : . ' $ envir: language parent.frame() [12:32:40.759] | : . ' Arguments passed to the future-backend factory: [n=1] [12:32:40.760] | : . ' List of 1 [12:32:40.760] | : . ' $ envir: language parent.frame() [12:32:40.761] | : . ' Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [12:32:40.761] | : . makeFutureBackend() ... done [12:32:40.761] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [12:32:40.762] | : nbrOfWorkers(NULL) ... [12:32:40.762] | : . List of 4 [12:32:40.762] | : . $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [12:32:40.762] | : . $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [12:32:40.762] | : . $ : language nbrOfWorkers() [12:32:40.762] | : . $ : language nbrOfWorkers.NULL() [12:32:40.764] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [12:32:40.764] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [12:32:40.765] | : . Number of workers: 1 [12:32:40.765] | : nbrOfWorkers(NULL) ... done [12:32:40.765] | : plan(): nbrOfWorkers() = 1 [12:32:40.765] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [12:32:40.766] 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-01-16 12:32:40 0.00000000 secs 4 launch overhead 2026-01-16 12:32:40 0.04123306 secs 2 getWorker overhead launch 2026-01-16 12:32:40 0.04891014 secs 3 eraseWorker overhead launch 2026-01-16 12:32:40 0.06162310 secs 7 evaluate evaluation 2026-01-16 12:32:40 0.07158399 secs 5 receiveResult overhead gather 2026-01-16 12:32:40 0.21420622 secs 6 gather overhead 2026-01-16 12:32:40 0.24776912 secs 11 create overhead 2026-01-16 12:32:40 0.00000000 secs 41 launch overhead 2026-01-16 12:32:40 0.03162980 secs 21 getWorker overhead launch 2026-01-16 12:32:40 0.03843880 secs 31 eraseWorker overhead launch 2026-01-16 12:32:40 0.05245280 secs 71 evaluate evaluation 2026-01-16 12:32:40 0.06019902 secs 51 receiveResult overhead gather 2026-01-16 12:32:40 0.26852298 secs 61 gather overhead 2026-01-16 12:32:40 0.30070996 secs 12 create overhead 2026-01-16 12:32:40 0.00000000 secs 42 launch overhead 2026-01-16 12:32:40 0.02619791 secs 22 getWorker overhead launch 2026-01-16 12:32:40 0.03205490 secs 32 eraseWorker overhead launch 2026-01-16 12:32:40 0.13699007 secs 72 evaluate evaluation 2026-01-16 12:32:40 0.14510703 secs 52 receiveResult overhead gather 2026-01-16 12:32:40 0.28563094 secs 62 gather overhead 2026-01-16 12:32:40 0.31799507 secs duration future_label future_uuid 1 0.0403540134 secs a19befcf1e4d64d756099c078121aec8-1 4 0.0325860977 secs a19befcf1e4d64d756099c078121aec8-1 2 0.0104310513 secs a19befcf1e4d64d756099c078121aec8-1 3 0.0011351109 secs a19befcf1e4d64d756099c078121aec8-1 7 0.1088840961 secs a19befcf1e4d64d756099c078121aec8-1 5 0.0278928280 secs a19befcf1e4d64d756099c078121aec8-1 6 0.0013339520 secs a19befcf1e4d64d756099c078121aec8-1 11 0.0308938026 secs a19befcf1e4d64d756099c078121aec8-2 41 0.0337991714 secs a19befcf1e4d64d756099c078121aec8-2 21 0.0121111870 secs a19befcf1e4d64d756099c078121aec8-2 31 0.0005600452 secs a19befcf1e4d64d756099c078121aec8-2 71 0.1037578583 secs a19befcf1e4d64d756099c078121aec8-2 51 0.0278189182 secs a19befcf1e4d64d756099c078121aec8-2 61 0.0011620522 secs a19befcf1e4d64d756099c078121aec8-2 12 0.0256209373 secs a19befcf1e4d64d756099c078121aec8-3 42 0.1225910187 secs a19befcf1e4d64d756099c078121aec8-3 22 0.1025490761 secs a19befcf1e4d64d756099c078121aec8-3 32 0.0006349087 secs a19befcf1e4d64d756099c078121aec8-3 72 0.0269720554 secs a19befcf1e4d64d756099c078121aec8-3 52 0.0278489590 secs a19befcf1e4d64d756099c078121aec8-3 62 0.0006949902 secs a19befcf1e4d64d756099c078121aec8-3 session_uuid 1 a19befcf1e4d64d756099c078121aec8 4 a19befcf1e4d64d756099c078121aec8 2 a19befcf1e4d64d756099c078121aec8 3 a19befcf1e4d64d756099c078121aec8 7 961e6c1887b40d89d7dbfdcf46a5bbe0 5 a19befcf1e4d64d756099c078121aec8 6 a19befcf1e4d64d756099c078121aec8 11 a19befcf1e4d64d756099c078121aec8 41 a19befcf1e4d64d756099c078121aec8 21 a19befcf1e4d64d756099c078121aec8 31 a19befcf1e4d64d756099c078121aec8 71 5af5c023770203df31c1a5c7a9029975 51 a19befcf1e4d64d756099c078121aec8 61 a19befcf1e4d64d756099c078121aec8 12 a19befcf1e4d64d756099c078121aec8 42 a19befcf1e4d64d756099c078121aec8 22 a19befcf1e4d64d756099c078121aec8 32 a19befcf1e4d64d756099c078121aec8 72 961e6c1887b40d89d7dbfdcf46a5bbe0 52 a19befcf1e4d64d756099c078121aec8 62 a19befcf1e4d64d756099c078121aec8 > stats <- summary(js) > print(stats) Number of futures: 3 summary evaluate evaluate_ratio overhead overhead_ratio 1 min 0.02697206 secs 0.1082767 0.06585503 secs 0.2643686 2 mean 0.07987134 secs 0.2755244 0.09634535 secs 0.3323532 3 median 0.10375786 secs 0.3437147 0.07427406 secs 0.2460449 4 max 0.10888410 secs 0.3416614 0.14890695 secs 0.4672469 5 total 0.23961401 secs 0.2755244 0.28903604 secs 0.3323532 duration walltime 1 0.2491031 secs 0.2491031 secs 2 0.2898884 secs 0.2898884 secs 3 0.3018720 secs 0.3018720 secs 4 0.3186901 secs 0.3186901 secs 5 0.8696651 secs 0.8696651 secs > message("*** summary() of FutureJournal ... done") *** summary() of FutureJournal ... done Failed to undo environment variables: - Expected environment variables: [n=217] '!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_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_01_16_12_30_17_22682' Test time: user.self=0.9s, sys.self=0.08s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'capture_journals' ... success > > proc.time() user system elapsed 1.37 0.18 2.57