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-cluster-connection-clashes.R > ## Don't edit - it was autogenerated by inst/testme/deploy.R > future:::testme("cluster-connection-clashes") Test 'cluster-connection-clashes' ... chr "none" > library(future) [22:16:47.279] Option 'future.startup.script': TRUE [22:16:47.281] Future startup scripts considered: '.future.R', '~/.future.R' [22:16:47.282] Future startup scripts found: > message("all.equal() for connection ...") all.equal() for connection ... > con1 <- rawConnection(raw()) > close(con1) > con2 <- rawConnection(raw()) > close(con2) > stopifnot(!isTRUE(all.equal(con1, con2))) > message("all.equal() for connection ... done") all.equal() for connection ... done > message("all.equal() for cluster backend ...") all.equal() for cluster backend ... > for (kk in 1:3) { + message("Iteration #", kk) + cl <- parallel::makeCluster(1) + plan(cluster, workers = cl) + fs <- lapply(1:2, FU .... [TRUNCATED] Iteration #1 [22:16:47.501] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.503] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:47.503] | : all.equal() for FutureStrategyList ... [22:16:47.504] | : . New stack: [22:16:47.505] | : . List of 1 [22:16:47.505] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:47.505] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:47.505] | : . ..- attr(*, "init")= logi TRUE [22:16:47.505] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:47.505] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:47.505] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.505] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.505] | : . ..- attr(*, "tweaks")=List of 1 [22:16:47.505] | : . .. ..$ workers:List of 1 [22:16:47.505] | : . .. .. ..$ :List of 3 [22:16:47.505] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:47.505] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:47.505] | : . .. .. .. ..$ host: chr "localhost" [22:16:47.505] | : . .. .. .. ..$ rank: num 0 [22:16:47.505] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.505] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.505] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:47.505] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:47.513] | : . Old stack: [22:16:47.513] | : . List of 1 [22:16:47.513] | : . $ :function (..., envir = parent.frame()) [22:16:47.513] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:47.513] | : . ..- attr(*, "init")= logi TRUE [22:16:47.513] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:47.513] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:47.513] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:47.513] | : . ..- attr(*, "call")= language plan(sequential) [22:16:47.513] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:47.517] | : . Not identical [22:16:47.517] | : . all.equal() for future ... [22:16:47.518] | : . ' List of 2 [22:16:47.518] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:47.518] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:47.518] | : . ' ..- attr(*, "init")= logi TRUE [22:16:47.518] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:47.518] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:47.518] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.518] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.518] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:47.518] | : . ' .. ..$ workers:List of 1 [22:16:47.518] | : . ' .. .. ..$ :List of 3 [22:16:47.518] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:47.518] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:47.518] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:47.518] | : . ' .. .. .. ..$ rank: num 0 [22:16:47.518] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.518] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.518] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:47.518] | : . ' $ current:function (..., envir = parent.frame()) [22:16:47.518] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:47.518] | : . ' ..- attr(*, "init")= logi TRUE [22:16:47.518] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:47.518] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:47.518] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:47.518] | : . ' ..- attr(*, "call")= language plan(sequential) [22:16:47.529] | : . ' Formals differ [22:16:47.529] | : . all.equal() for future ... done [22:16:47.530] | : . Future strategies differ at level 1 [22:16:47.530] | : all.equal() for FutureStrategyList ... done [22:16:47.531] | : plan(): Setting new future backend stack: [22:16:47.531] | : List of future strategies: [22:16:47.531] | : 1. cluster: [22:16:47.531] | : - args: function (..., workers = "") [22:16:47.531] | : - tweaked: TRUE [22:16:47.531] | : - call: plan(cluster, workers = cl) [22:16:47.532] | : List of 1 [22:16:47.532] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:47.532] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:47.532] | : ..- attr(*, "init")= logi TRUE [22:16:47.532] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:47.532] | : earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:47.532] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.532] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.532] | : ..- attr(*, "tweaks")=List of 1 [22:16:47.532] | : .. ..$ workers:List of 1 [22:16:47.532] | : .. .. ..$ :List of 3 [22:16:47.532] | : .. .. .. ..$ con : 'sockconn' int 4 [22:16:47.532] | : .. .. .. .. ..- attr(*, "conn_id")= [22:16:47.532] | : .. .. .. ..$ host: chr "localhost" [22:16:47.532] | : .. .. .. ..$ rank: num 0 [22:16:47.532] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.532] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.532] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:47.532] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:47.539] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... [22:16:47.539] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.540] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [22:16:47.540] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.541] | : . Legacy shutdown of cluster workers ... [22:16:47.542] | : . ' Stopping existing cluster ... [22:16:47.542] | : . ' , No pre-existing cluster. Skipping [22:16:47.543] | : . ' Stopping existing cluster ... done [22:16:47.543] | : . Legacy shutdown of cluster workers ... done [22:16:47.544] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:47.544] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [22:16:47.545] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:47.545] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:47.545] | : . - attr(*, "init")= logi TRUE [22:16:47.545] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:47.545] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:47.545] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.545] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:47.545] | : . - attr(*, "tweaks")=List of 1 [22:16:47.545] | : . ..$ workers:List of 1 [22:16:47.545] | : . .. ..$ :List of 3 [22:16:47.545] | : . .. .. ..$ con : 'sockconn' int 4 [22:16:47.545] | : . .. .. .. ..- attr(*, "conn_id")= [22:16:47.545] | : . .. .. ..$ host: chr "localhost" [22:16:47.545] | : . .. .. ..$ rank: num 0 [22:16:47.545] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.545] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.545] | : . - attr(*, "call")= language plan(cluster, workers = cl) [22:16:47.552] | : . init: TRUE [22:16:47.552] | : . makeFutureBackend() ... [22:16:47.553] | : . ' Backend function: <'NULL'> [22:16:47.553] | : . ' Evaluator tweak arguments: [n=1] [22:16:47.554] | : . ' List of 1 [22:16:47.554] | : . ' $ workers:List of 1 [22:16:47.554] | : . ' ..$ :List of 3 [22:16:47.554] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:47.554] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:47.554] | : . ' .. ..$ host: chr "localhost" [22:16:47.554] | : . ' .. ..$ rank: num 0 [22:16:47.554] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.554] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.557] | : . ' Evaluator formal arguments: [n=1] [22:16:47.558] | : . ' Dotted pair list of 1 [22:16:47.558] | : . ' $ workers:List of 1 [22:16:47.558] | : . ' ..$ :List of 3 [22:16:47.558] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:47.558] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:47.558] | : . ' .. ..$ host: chr "localhost" [22:16:47.558] | : . ' .. ..$ rank: num 0 [22:16:47.558] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.558] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.561] | : . ' Arguments passed to the future-backend factory: [n=1] [22:16:47.562] | : . ' List of 1 [22:16:47.562] | : . ' $ workers:List of 1 [22:16:47.562] | : . ' ..$ :List of 3 [22:16:47.562] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:47.562] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:47.562] | : . ' .. ..$ host: chr "localhost" [22:16:47.562] | : . ' .. ..$ rank: num 0 [22:16:47.562] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:47.562] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:47.565] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... [22:16:47.566] | : . ' , Stopping existing cluster ... [22:16:47.566] | : . ' , ; No pre-existing cluster. Skipping [22:16:47.567] | : . ' , Stopping existing cluster ... done [22:16:47.567] | : . ' , Number of workers: 1 [22:16:47.568] | : . ' , Generated workers UUID [22:16:47.568] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [22:16:47.569] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... done [22:16:47.570] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [22:16:47.570] | : . makeFutureBackend() ... done [22:16:47.571] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [22:16:47.571] | : nbrOfWorkers(NULL) ... [22:16:47.572] | : . List of 4 [22:16:47.572] | : . $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:47.572] | : . $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:47.572] | : . $ : language nbrOfWorkers() [22:16:47.572] | : . $ : language nbrOfWorkers.NULL() [22:16:47.574] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.574] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.575] | : . Number of workers: 1 [22:16:47.576] | : nbrOfWorkers(NULL) ... done [22:16:47.576] | : plan(): nbrOfWorkers() = 1 [22:16:47.577] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:16:47.578] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.578] future(..., label = NULL) ... [22:16:47.579] | lazy: FALSE [22:16:47.579] | stdout: TRUE [22:16:47.580] | conditions: [n=1] 'condition' [22:16:47.581] | getGlobalsAndPackages() ... [22:16:47.581] | : Searching for globals ... [22:16:47.588] | : . globals found: [3] '[[', 'X', 'i' [22:16:47.589] | : Searching for globals ... done [22:16:47.589] | : Resolving globals: FALSE [22:16:47.590] | : Search for packages associated with the globals ... [22:16:47.591] | : . Packages associated with globals: [1] 'base' [22:16:47.592] | : . Packages: [1] 'base' [22:16:47.592] | : Search for packages associated with the globals ... done [22:16:47.593] | : Packages after dropping 'base': [0] [22:16:47.593] | : globals: [2] 'X', 'i' [22:16:47.594] | : packages: [0] [22:16:47.594] | getGlobalsAndPackages() ... done [22:16:47.595] | run() for 'Future' () ... [22:16:47.596] | : state: 'created' [22:16:47.596] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.597] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.597] | : Using ClusterFutureBackend ... [22:16:47.598] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:16:47.598] | : . Launching futures ... [22:16:47.599] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:47.599] | : . ' , Workers: [n=1] [22:16:47.600] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:47.601] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:47.601] | : . ' , requestWorker() ... [22:16:47.602] | : . ' , ; requestNode() ... [22:16:47.602] | : . ' , ; ` Number of workers: 1 [22:16:47.603] | : . ' , ; ` Polling for a free worker ... [22:16:47.603] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.604] | : . ' , ; ` | : Created empty registry 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:47.604] | : . ' , ; ` | : Listing all futures [22:16:47.605] | : . ' , ; ` | : Number of registered futures: 0 [22:16:47.605] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.606] | : . ' , ; ` | Total time: 0 [22:16:47.606] | : . ' , ; ` Polling for a free worker ... done [22:16:47.607] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.607] | : . ' , ; ` | Listing all futures [22:16:47.607] | : . ' , ; ` | Number of registered futures: 0 [22:16:47.608] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.609] | : . ' , ; ` avail: [n=1] '1' [22:16:47.609] | : . ' , ; ` Index of first available worker: 1 [22:16:47.610] | : . ' , ; ` Validate that the worker is functional ... [22:16:47.610] | : . ' , ; ` | Connection is valid [22:16:47.611] | : . ' , ; ` | node of a socket cluster on host 'localhost' with pid 58416 [22:16:47.612] | : . ' , ; ` | Worker is functional [22:16:47.613] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.614] | : . ' , ; ` | : Listing all futures [22:16:47.614] | : . ' , ; ` | : Number of registered futures: 0 [22:16:47.614] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.615] | : . ' , ; ` Validate that the worker is functional ... done [22:16:47.615] | : . ' , ; requestNode() ... done [22:16:47.616] | : . ' , ; cluster node index: 1 [22:16:47.616] | : . ' , requestWorker() ... done [22:16:47.617] | : . ' , eraseGlobalEnvironment() ... [22:16:47.728] | : . ' , eraseGlobalEnvironment() ... done [22:16:47.728] | : . ' , launchFuture() ... [22:16:47.729] | : . ' , ; cluster node index: 1 [22:16:47.729] | : . ' , ; getFutureData() ... [22:16:47.730] | : . ' , ; ` getFutureCore() ... [22:16:47.730] | : . ' , ; ` | Packages needed by the future expression (n = 0): [22:16:47.731] | : . ' , ; ` getFutureCore() ... done [22:16:47.731] | : . ' , ; ` getFutureCapture() ... [22:16:47.732] | : . ' , ; ` getFutureCapture() ... done [22:16:47.733] | : . ' , ; ` getFutureContext() ... [22:16:47.733] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.734] | : . ' , ; ` | : Getting stack without first backend: [n=0] [22:16:47.735] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.736] | : . ' , ; ` | Packages needed by future backend (n = 0): [22:16:47.736] | : . ' , ; ` getFutureContext() ... done [22:16:47.737] | : . ' , ; getFutureData() ... done [22:16:47.738] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:47.739] | : . ' , ; ` Appended future to position #1 [22:16:47.739] | : . ' , ; ` Number of registered futures: 1 [22:16:47.740] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:47.740] | : . ' , launchFuture() ... done [22:16:47.741] | : . ' , ClusterFuture started [22:16:47.741] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:47.742] | : . Launching futures ... done [22:16:47.742] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:47.743] | : Using ClusterFutureBackend ... done [22:16:47.743] | run() for 'Future' () ... done [22:16:47.744] future(..., label = NULL) ... done [22:16:47.744] future(..., label = NULL) ... [22:16:47.745] | lazy: FALSE [22:16:47.745] | stdout: TRUE [22:16:47.746] | conditions: [n=1] 'condition' [22:16:47.746] | getGlobalsAndPackages() ... [22:16:47.747] | : Searching for globals ... [22:16:47.755] | : . globals found: [3] '[[', 'X', 'i' [22:16:47.756] | : Searching for globals ... done [22:16:47.756] | : Resolving globals: FALSE [22:16:47.757] | : Search for packages associated with the globals ... [22:16:47.758] | : . Packages associated with globals: [1] 'base' [22:16:47.758] | : . Packages: [1] 'base' [22:16:47.759] | : Search for packages associated with the globals ... done [22:16:47.760] | : Packages after dropping 'base': [0] [22:16:47.760] | : globals: [2] 'X', 'i' [22:16:47.761] | : packages: [0] [22:16:47.761] | getGlobalsAndPackages() ... done [22:16:47.762] | run() for 'Future' () ... [22:16:47.763] | : state: 'created' [22:16:47.763] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.764] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.764] | : Using ClusterFutureBackend ... [22:16:47.765] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [22:16:47.765] | : . Launching futures ... [22:16:47.766] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:47.767] | : . ' , Workers: [n=1] [22:16:47.767] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:47.768] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:47.768] | : . ' , requestWorker() ... [22:16:47.769] | : . ' , ; requestNode() ... [22:16:47.770] | : . ' , ; ` Number of workers: 1 [22:16:47.770] | : . ' , ; ` Polling for a free worker ... [22:16:47.771] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.771] | : . ' , ; ` | : Listing all futures [22:16:47.772] | : . ' , ; ` | : Number of registered futures: 1 [22:16:47.772] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.773] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [22:16:47.793] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [22:16:47.794] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [22:16:47.794] | : . ' , ; ` | : . resolved() ... [22:16:47.795] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... [22:16:47.796] | : . ' , ; ` | : . ' , Cluster node socket connection: index=4, id=139 [22:16:47.797] | : . ' , ; ` | : . ' , assertValidConnection() ... [22:16:47.797] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:47.798] | : . ' , ; ` | : . ' , assertValidConnection() ... done [22:16:47.807] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... [22:16:47.807] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:47.808] | : . ' , ; ` | : . ' , ; [1] TRUE [22:16:47.833] | : . ' , ; ` | : . ' , ; Received data: [22:16:47.834] | : . ' , ; ` | : . ' , ; List of 5 [22:16:47.834] | : . ' , ; ` | : . ' , ; $ type : chr "VALUE" [22:16:47.834] | : . ' , ; ` | : . ' , ; $ value :List of 16 [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ value : int 1 [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ visible : logi TRUE [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ stdout : chr "" [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ conditions : list() [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ rng : logi FALSE [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ seed : NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ misuseGlobalEnv : NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ misuseConnections :List of 3 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ added : NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ removed : NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ replaced: NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ misuseDevices : NULL [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ misuseDefaultDevice: list() [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "1" [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ session_uuid : chr "21274b507921707ba28affcd650a2a86" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..- attr(*, "source")=List of 5 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ host : Named chr "CRANWIN3" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ pid : int 58416 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ random: int 2147483647 [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ r_info :List of 4 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. .. ..$ : int [1:3] 4 6 0 [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ os : chr "windows" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ os_name : chr "Windows" [22:16:47.834] | : . ' , ; ` | : . ' , ; .. ..$ captures_utf8: logi TRUE [22:16:47.834] | : . ' , ; ` | : . ' , ; ..$ version : chr "1.8" [22:16:47.834] | : . ' , ; ` | : . ' , ; ..- attr(*, "class")= chr "FutureResult" [22:16:47.834] | : . ' , ; ` | : . ' , ; $ success: logi TRUE [22:16:47.834] | : . ' , ; ` | : . ' , ; $ time : 'proc_time' Named num [1:5] 0.06 0 0.07 NA NA [22:16:47.834] | : . ' , ; ` | : . ' , ; ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:47.834] | : . ' , ; ` | : . ' , ; $ tag : NULL [22:16:47.849] | : . ' , ; ` | : . ' , ; Received FutureResult [22:16:47.850] | : . ' , ; ` | : . ' , ; FutureResult: [22:16:47.850] | : . ' , ; ` | : . ' , ; value: 'integer' [22:16:47.850] | : . ' , ; ` | : . ' , ; visible: TRUE [22:16:47.850] | : . ' , ; ` | : . ' , ; stdout: character [22:16:47.850] | : . ' , ; ` | : . ' , ; conditions: [n = 0] [22:16:47.850] | : . ' , ; ` | : . ' , ; RNG used: FALSE [22:16:47.850] | : . ' , ; ` | : . ' , ; duration: 0.06546307 secs (started 2026-03-13 22:16:47.740059) [22:16:47.850] | : . ' , ; ` | : . ' , ; version: 1.8 [22:16:47.852] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:47.875] | : . ' , ; ` | : . ' , ; ` Removed future from position #1 [22:16:47.876] | : . ' , ; ` | : . ' , ; ` Number of registered futures: 0 [22:16:47.876] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:47.877] | : . ' , ; ` | : . ' , ; Erased future from future backend [22:16:47.878] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [22:16:47.878] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [22:16:47.879] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [22:16:47.879] | : . ' , ; ` | : . ' , ; signalConditions() ... [22:16:47.880] | : . ' , ; ` | : . ' , ; ` include = 'immediateCondition' [22:16:47.880] | : . ' , ; ` | : . ' , ; ` exclude = [22:16:47.881] | : . ' , ; ` | : . ' , ; ` resignal = FALSE [22:16:47.881] | : . ' , ; ` | : . ' , ; ` Number of conditions: 0 [22:16:47.882] | : . ' , ; ` | : . ' , ; ` int 0 [22:16:47.883] | : . ' , ; ` | : . ' , ; signalConditions() ... done [22:16:47.883] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... done [22:16:47.884] | : . ' , ; ` | : . ' , receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:47.884] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... done [22:16:47.885] | : . ' , ; ` | : . ' assertFutureResult() ... [22:16:47.885] | : . ' , ; ` | : . ' , Future uuid: a0e2c2057a439d13cf08c3e3f94af757-1 [22:16:47.886] | : . ' , ; ` | : . ' , identical; success [22:16:47.886] | : . ' , ; ` | : . ' assertFutureResult() ... done [22:16:47.887] | : . ' , ; ` | : . resolved() ... done [22:16:47.887] | : . ' , ; ` | : . Future at position #1 is resolved ... [22:16:47.888] | : . ' , ; ` | : . ' value() for ClusterFuture () ... [22:16:47.889] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:47.889] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:47.890] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:47.890] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:47.891] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:47.891] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:47.892] | : . ' , ; ` | : . ' , signalConditions() ... [22:16:47.892] | : . ' , ; ` | : . ' , ; include = 'immediateCondition' [22:16:47.893] | : . ' , ; ` | : . ' , ; exclude = [22:16:47.893] | : . ' , ; ` | : . ' , ; resignal = FALSE [22:16:47.894] | : . ' , ; ` | : . ' , ; Number of conditions: 0 [22:16:47.894] | : . ' , ; ` | : . ' , ; int 0 [22:16:47.895] | : . ' , ; ` | : . ' , signalConditions() ... done [22:16:47.896] | : . ' , ; ` | : . ' , check for misuse ... [22:16:47.896] | : . ' , ; ` | : . ' , check for misuse ... done [22:16:47.897] | : . ' , ; ` | : . ' value() for ClusterFuture () ... done [22:16:47.897] | : . ' , ; ` | : . Future at position #1 is resolved ... done [22:16:47.898] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [22:16:47.898] | : . ' , ; ` | : Remove collected futures ... [22:16:47.899] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [22:16:47.899] | : . ' , ; ` | : Remove collected futures ... done [22:16:47.900] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [22:16:47.900] | : . ' , ; ` | Early signaling of 1 future candidates ... [22:16:47.901] | : . ' , ; ` | Number of futures with early signaling requested: 0 [22:16:47.901] | : . ' , ; ` | Early signaling of 1 future candidates ... done [22:16:47.902] | : . ' , ; ` | Number of registered futures: 1 [22:16:47.902] | : . ' , ; ` Polling for a free worker ... done [22:16:47.903] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.904] | : . ' , ; ` | Listing all futures [22:16:47.904] | : . ' , ; ` | Number of registered futures: 0 [22:16:47.905] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.905] | : . ' , ; ` Total time: 0.132287979125977 [22:16:47.906] | : . ' , ; requestNode() ... done [22:16:47.906] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.907] | : . ' , ; ` Listing all futures [22:16:47.907] | : . ' , ; ` Number of registered futures: 0 [22:16:47.907] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.908] | : . ' , ; avail: [n=1] '1' [22:16:47.908] | : . ' , ; Index of first available worker: 1 [22:16:47.909] | : . ' , ; Validate that the worker is functional ... [22:16:47.909] | : . ' , ; ` Connection is valid [22:16:47.910] | : . ' , ; ` node of a socket cluster on host 'localhost' with pid 58416 [22:16:47.911] | : . ' , ; ` Worker is functional [22:16:47.912] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:47.912] | : . ' , ; ` | Listing all futures [22:16:47.913] | : . ' , ; ` | Number of registered futures: 0 [22:16:47.913] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:47.914] | : . ' , ; Validate that the worker is functional ... done [22:16:47.914] | : . ' , requestWorker() ... done [22:16:47.915] | : . ' , cluster node index: 1 [22:16:47.915] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:47.916] | : . ' eraseGlobalEnvironment() ... [22:16:47.917] | : . ' eraseGlobalEnvironment() ... done [22:16:47.917] | : . ' launchFuture() ... [22:16:47.918] | : . ' , cluster node index: 1 [22:16:47.918] | : . ' , getFutureData() ... [22:16:47.919] | : . ' , ; getFutureCore() ... [22:16:47.919] | : . ' , ; ` Packages needed by the future expression (n = 0): [22:16:47.920] | : . ' , ; getFutureCore() ... done [22:16:47.920] | : . ' , ; getFutureCapture() ... [22:16:47.921] | : . ' , ; getFutureCapture() ... done [22:16:47.921] | : . ' , ; getFutureContext() ... [22:16:47.922] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:47.922] | : . ' , ; ` | Getting stack without first backend: [n=0] [22:16:47.923] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:47.923] | : . ' , ; ` Packages needed by future backend (n = 0): [22:16:47.924] | : . ' , ; getFutureContext() ... done [22:16:47.924] | : . ' , getFutureData() ... done [22:16:47.925] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:47.926] | : . ' , ; Appended future to position #1 [22:16:47.926] | : . ' , ; Number of registered futures: 1 [22:16:47.927] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:47.927] | : . ' launchFuture() ... done [22:16:47.928] | : . ' ClusterFuture started [22:16:47.928] | : . Launching futures ... done [22:16:47.929] | : Using ClusterFutureBackend ... done [22:16:47.929] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:47.930] | run() for 'Future' () ... done [22:16:47.930] future(..., label = NULL) ... done [22:16:47.932] value() for list ... [22:16:47.932] | recursive: 0 [22:16:47.933] | reduce: FALSE [22:16:47.933] | length: 2 [22:16:47.934] | Number of remaining objects: 2 [22:16:47.935] | checking value #1: [22:16:47.935] | 'obj' is ClusterFuture [22:16:47.936] | resolved() ... [22:16:47.936] | resolved() ... done [22:16:47.937] | ClusterFuture #1 [22:16:47.937] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:47.938] | : nx: 2 [22:16:47.938] | : relay: TRUE [22:16:47.939] | : stdout: TRUE [22:16:47.939] | : signal: TRUE [22:16:47.940] | : resignal: FALSE [22:16:47.940] | : force: TRUE [22:16:47.941] | : relayed: [n=2] FALSE, FALSE [22:16:47.941] | : queued futures: [n=2] FALSE, FALSE [22:16:47.942] | : until=1 [22:16:47.942] | : relaying element #1 [22:16:47.943] | : value() for ClusterFuture () ... [22:16:47.943] | : . result() for ClusterFuture ... [22:16:47.944] | : . ' result already collected: FutureResult [22:16:47.944] | : . result() for ClusterFuture ... done [22:16:47.945] | : . result() for ClusterFuture ... [22:16:47.945] | : . ' result already collected: FutureResult [22:16:47.946] | : . result() for ClusterFuture ... done [22:16:47.946] | : . signalConditions() ... [22:16:47.947] | : . ' include = 'immediateCondition' [22:16:47.947] | : . ' exclude = [22:16:47.948] | : . ' resignal = FALSE [22:16:47.948] | : . ' Number of conditions: 0 [22:16:47.949] | : . ' int 0 [22:16:47.950] | : . signalConditions() ... done [22:16:47.950] | : . relay stdout ... [22:16:47.951] | : . relay stdout ... done [22:16:47.951] | : . check for misuse ... [22:16:47.952] | : . check for misuse ... done [22:16:47.952] | : value() for ClusterFuture () ... done [22:16:47.953] | : result() for ClusterFuture ... [22:16:47.953] | : . result already collected: FutureResult [22:16:47.954] | : result() for ClusterFuture ... done [22:16:47.954] | : signalConditions() ... [22:16:47.955] | : . include = 'immediateCondition' [22:16:47.955] | : . exclude = [22:16:47.956] | : . resignal = FALSE [22:16:47.956] | : . Number of conditions: 0 [22:16:47.957] | : . int 0 [22:16:47.958] | : signalConditions() ... done [22:16:47.958] | : result() for ClusterFuture ... [22:16:47.959] | : . result already collected: FutureResult [22:16:47.960] | : result() for ClusterFuture ... done [22:16:47.960] | : signalConditions() ... [22:16:47.961] | : . include = 'condition' [22:16:47.961] | : . exclude = 'error', 'immediateCondition' [22:16:47.962] | : . resignal = TRUE [22:16:47.962] | : . Number of conditions: 0 [22:16:47.963] | : . int 0 [22:16:47.964] | : signalConditions() ... done [22:16:47.964] | : relayed: [n=2] TRUE, FALSE [22:16:47.965] | : queued futures: [n=2] TRUE, FALSE [22:16:47.965] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:47.966] | value(, ...) ... [22:16:47.966] | : value() for ClusterFuture () ... [22:16:47.967] | : . result() for ClusterFuture ... [22:16:47.967] | : . ' result already collected: FutureResult [22:16:47.968] | : . result() for ClusterFuture ... done [22:16:47.968] | : . result() for ClusterFuture ... [22:16:47.969] | : . ' result already collected: FutureResult [22:16:47.969] | : . result() for ClusterFuture ... done [22:16:47.970] | : . signalConditions() ... [22:16:47.970] | : . ' include = 'immediateCondition' [22:16:47.971] | : . ' exclude = [22:16:47.972] | : . ' resignal = FALSE [22:16:47.972] | : . ' Number of conditions: 0 [22:16:47.973] | : . ' int 0 [22:16:47.973] | : . signalConditions() ... done [22:16:47.974] | : . check for misuse ... [22:16:47.975] | : . check for misuse ... done [22:16:47.975] | : value() for ClusterFuture () ... done [22:16:47.976] | : value: [22:16:47.976] | value(, ...) ... done [22:16:47.977] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:47.977] | : nx: 2 [22:16:47.978] | : relay: TRUE [22:16:47.978] | : stdout: TRUE [22:16:47.979] | : signal: TRUE [22:16:47.979] | : resignal: FALSE [22:16:47.980] | : force: TRUE [22:16:47.980] | : relayed: [n=2] TRUE, FALSE [22:16:47.981] | : queued futures: [n=2] TRUE, FALSE [22:16:47.981] | : until=2 [22:16:47.982] | : relaying element #2 [22:16:47.982] | : relayed: [n=2] TRUE, FALSE [22:16:47.983] | : queued futures: [n=2] TRUE, FALSE [22:16:47.983] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:47.984] | resolve() for ClusterFuture ... [22:16:47.984] | resolve() for ClusterFuture ... done [22:16:47.985] | length: 1 (resolved future 1) [22:16:47.986] | checking value #2: [22:16:47.986] | 'obj' is ClusterFuture [22:16:47.987] | resolved() ... [22:16:47.987] | : resolved() for ClusterFuture () ... [22:16:47.988] | : . Cluster node socket connection: index=4, id=139 [22:16:47.988] | : . assertValidConnection() ... [22:16:47.989] | : . ' cluster node index: 1 [22:16:47.990] | : . assertValidConnection() ... done [22:16:47.990] | : . receiveMessageFromWorker() for ClusterFuture ... [22:16:47.991] | : . ' cluster node index: 1 [22:16:47.991] | : . ' [1] TRUE [22:16:47.992] | : . ' Received data: [22:16:47.993] | : . ' List of 5 [22:16:47.993] | : . ' $ type : chr "VALUE" [22:16:47.993] | : . ' $ value :List of 16 [22:16:47.993] | : . ' ..$ value : int 2 [22:16:47.993] | : . ' ..$ visible : logi TRUE [22:16:47.993] | : . ' ..$ stdout : chr "" [22:16:47.993] | : . ' ..$ conditions : list() [22:16:47.993] | : . ' ..$ rng : logi FALSE [22:16:47.993] | : . ' ..$ seed : NULL [22:16:47.993] | : . ' ..$ misuseGlobalEnv : NULL [22:16:47.993] | : . ' ..$ misuseConnections :List of 3 [22:16:47.993] | : . ' .. ..$ added : NULL [22:16:47.993] | : . ' .. ..$ removed : NULL [22:16:47.993] | : . ' .. ..$ replaced: NULL [22:16:47.993] | : . ' ..$ misuseDevices : NULL [22:16:47.993] | : . ' ..$ misuseDefaultDevice: list() [22:16:47.993] | : . ' ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.993] | : . ' ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.993] | : . ' ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "2" [22:16:47.993] | : . ' ..$ session_uuid : chr "21274b507921707ba28affcd650a2a86" [22:16:47.993] | : . ' .. ..- attr(*, "source")=List of 5 [22:16:47.993] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [22:16:47.993] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:47.993] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:47.993] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:47.993] | : . ' .. .. ..$ pid : int 58416 [22:16:47.993] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:47" [22:16:47.993] | : . ' .. .. ..$ random: int 2147483647 [22:16:47.993] | : . ' ..$ r_info :List of 4 [22:16:47.993] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:47.993] | : . ' .. .. ..$ : int [1:3] 4 6 0 [22:16:47.993] | : . ' .. ..$ os : chr "windows" [22:16:47.993] | : . ' .. ..$ os_name : chr "Windows" [22:16:47.993] | : . ' .. ..$ captures_utf8: logi TRUE [22:16:47.993] | : . ' ..$ version : chr "1.8" [22:16:47.993] | : . ' ..- attr(*, "class")= chr "FutureResult" [22:16:47.993] | : . ' $ success: logi TRUE [22:16:47.993] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [22:16:47.993] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:47.993] | : . ' $ tag : NULL [22:16:48.010] | : . ' Received FutureResult [22:16:48.011] | : . ' FutureResult: [22:16:48.011] | : . ' value: 'integer' [22:16:48.011] | : . ' visible: TRUE [22:16:48.011] | : . ' stdout: character [22:16:48.011] | : . ' conditions: [n = 0] [22:16:48.011] | : . ' RNG used: FALSE [22:16:48.011] | : . ' duration: 0.003607035 secs (started 2026-03-13 22:16:47.925839) [22:16:48.011] | : . ' version: 1.8 [22:16:48.012] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:48.013] | : . ' , Removed future from position #1 [22:16:48.013] | : . ' , Number of registered futures: 0 [22:16:48.014] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:48.014] | : . ' Erased future from future backend [22:16:48.015] | : . ' result() for ClusterFuture ... [22:16:48.015] | : . ' , result already collected: FutureResult [22:16:48.016] | : . ' result() for ClusterFuture ... done [22:16:48.016] | : . ' signalConditions() ... [22:16:48.017] | : . ' , include = 'immediateCondition' [22:16:48.017] | : . ' , exclude = [22:16:48.018] | : . ' , resignal = FALSE [22:16:48.018] | : . ' , Number of conditions: 0 [22:16:48.019] | : . ' , int 0 [22:16:48.020] | : . ' signalConditions() ... done [22:16:48.020] | : . receiveMessageFromWorker() for ClusterFuture ... done [22:16:48.021] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:48.021] | : resolved() for ClusterFuture () ... done [22:16:48.022] | : assertFutureResult() ... [22:16:48.022] | : . Future uuid: a0e2c2057a439d13cf08c3e3f94af757-2 [22:16:48.023] | : . identical; success [22:16:48.023] | : assertFutureResult() ... done [22:16:48.024] | resolved() ... done [22:16:48.024] | ClusterFuture #2 [22:16:48.025] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:48.025] | : nx: 2 [22:16:48.026] | : relay: TRUE [22:16:48.026] | : stdout: TRUE [22:16:48.027] | : signal: TRUE [22:16:48.027] | : resignal: FALSE [22:16:48.028] | : force: TRUE [22:16:48.028] | : relayed: [n=2] TRUE, FALSE [22:16:48.029] | : queued futures: [n=2] TRUE, FALSE [22:16:48.029] | : until=2 [22:16:48.030] | : relaying element #2 [22:16:48.030] | : value() for ClusterFuture () ... [22:16:48.031] | : . result() for ClusterFuture ... [22:16:48.031] | : . ' result already collected: FutureResult [22:16:48.032] | : . result() for ClusterFuture ... done [22:16:48.032] | : . result() for ClusterFuture ... [22:16:48.033] | : . ' result already collected: FutureResult [22:16:48.033] | : . result() for ClusterFuture ... done [22:16:48.034] | : . signalConditions() ... [22:16:48.034] | : . ' include = 'immediateCondition' [22:16:48.035] | : . ' exclude = [22:16:48.035] | : . ' resignal = FALSE [22:16:48.036] | : . ' Number of conditions: 0 [22:16:48.036] | : . ' int 0 [22:16:48.037] | : . signalConditions() ... done [22:16:48.038] | : . relay stdout ... [22:16:48.038] | : . relay stdout ... done [22:16:48.039] | : . check for misuse ... [22:16:48.039] | : . check for misuse ... done [22:16:48.040] | : value() for ClusterFuture () ... done [22:16:48.041] | : result() for ClusterFuture ... [22:16:48.041] | : . result already collected: FutureResult [22:16:48.042] | : result() for ClusterFuture ... done [22:16:48.042] | : signalConditions() ... [22:16:48.043] | : . include = 'immediateCondition' [22:16:48.043] | : . exclude = [22:16:48.044] | : . resignal = FALSE [22:16:48.044] | : . Number of conditions: 0 [22:16:48.045] | : . int 0 [22:16:48.046] | : signalConditions() ... done [22:16:48.046] | : result() for ClusterFuture ... [22:16:48.047] | : . result already collected: FutureResult [22:16:48.047] | : result() for ClusterFuture ... done [22:16:48.048] | : signalConditions() ... [22:16:48.048] | : . include = 'condition' [22:16:48.049] | : . exclude = 'error', 'immediateCondition' [22:16:48.049] | : . resignal = TRUE [22:16:48.050] | : . Number of conditions: 0 [22:16:48.050] | : . int 0 [22:16:48.051] | : signalConditions() ... done [22:16:48.052] | : relayed: [n=2] TRUE, TRUE [22:16:48.052] | : queued futures: [n=2] TRUE, TRUE [22:16:48.053] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:48.053] | value(, ...) ... [22:16:48.054] | : value() for ClusterFuture () ... [22:16:48.055] | : . result() for ClusterFuture ... [22:16:48.055] | : . ' result already collected: FutureResult [22:16:48.056] | : . result() for ClusterFuture ... done [22:16:48.056] | : . result() for ClusterFuture ... [22:16:48.057] | : . ' result already collected: FutureResult [22:16:48.057] | : . result() for ClusterFuture ... done [22:16:48.058] | : . signalConditions() ... [22:16:48.058] | : . ' include = 'immediateCondition' [22:16:48.059] | : . ' exclude = [22:16:48.060] | : . ' resignal = FALSE [22:16:48.060] | : . ' Number of conditions: 0 [22:16:48.061] | : . ' int 0 [22:16:48.062] | : . signalConditions() ... done [22:16:48.062] | : . check for misuse ... [22:16:48.063] | : . check for misuse ... done [22:16:48.063] | : value() for ClusterFuture () ... done [22:16:48.064] | : value: [22:16:48.064] | value(, ...) ... done [22:16:48.065] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:48.065] | : nx: 2 [22:16:48.066] | : relay: TRUE [22:16:48.066] | : stdout: TRUE [22:16:48.067] | : signal: TRUE [22:16:48.067] | : resignal: FALSE [22:16:48.068] | : force: TRUE [22:16:48.068] | : relayed: [n=2] TRUE, TRUE [22:16:48.069] | : queued futures: [n=2] TRUE, TRUE [22:16:48.069] | : until=2 [22:16:48.070] | : relayed: [n=2] TRUE, TRUE [22:16:48.070] | : queued futures: [n=2] TRUE, TRUE [22:16:48.071] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:48.071] | resolve() for ClusterFuture ... [22:16:48.072] | resolve() for ClusterFuture ... done [22:16:48.072] | length: 0 (resolved future 2) [22:16:48.073] | Relaying remaining futures ... [22:16:48.073] | : signalConditionsASAP(NULL, pos=0) ... [22:16:48.074] | : . nx: 2 [22:16:48.075] | : . relay: TRUE [22:16:48.075] | : . stdout: TRUE [22:16:48.076] | : . signal: TRUE [22:16:48.076] | : . resignal: FALSE [22:16:48.076] | : . force: TRUE [22:16:48.077] | : . relayed: [n=2] TRUE, TRUE [22:16:48.077] | : . queued futures: [n=2] TRUE, TRUE - flush all [22:16:48.078] | : . relayed: [n=2] TRUE, TRUE [22:16:48.079] | : . queued futures: [n=2] TRUE, TRUE [22:16:48.079] | : signalConditionsASAP(NULL, pos=0) ... done [22:16:48.080] | Relaying remaining futures ... done [22:16:48.080] value() for list ... done Iteration #2 [22:16:48.256] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.257] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:48.258] | : all.equal() for FutureStrategyList ... [22:16:48.258] | : . New stack: [22:16:48.259] | : . List of 1 [22:16:48.259] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.259] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.259] | : . ..- attr(*, "init")= logi TRUE [22:16:48.259] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.259] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.259] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.259] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.259] | : . ..- attr(*, "tweaks")=List of 1 [22:16:48.259] | : . .. ..$ workers:List of 1 [22:16:48.259] | : . .. .. ..$ :List of 3 [22:16:48.259] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.259] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.259] | : . .. .. .. ..$ host: chr "localhost" [22:16:48.259] | : . .. .. .. ..$ rank: num 0 [22:16:48.259] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.259] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.259] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.259] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:48.267] | : . Old stack: [22:16:48.267] | : . List of 1 [22:16:48.267] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.267] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.267] | : . ..- attr(*, "init")= chr "done" [22:16:48.267] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.267] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.267] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.267] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.267] | : . ..- attr(*, "tweaks")=List of 1 [22:16:48.267] | : . .. ..$ workers:List of 1 [22:16:48.267] | : . .. .. ..$ :List of 3 [22:16:48.267] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.267] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.267] | : . .. .. .. ..$ host: chr "localhost" [22:16:48.267] | : . .. .. .. ..$ rank: num 0 [22:16:48.267] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.267] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.267] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.267] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:48.267] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.267] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.267] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.267] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:48.276] | : . Not identical [22:16:48.277] | : . all.equal() for future ... [22:16:48.277] | : . ' List of 2 [22:16:48.277] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.277] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.277] | : . ' ..- attr(*, "init")= logi TRUE [22:16:48.277] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.277] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.277] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.277] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.277] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:48.277] | : . ' .. ..$ workers:List of 1 [22:16:48.277] | : . ' .. .. ..$ :List of 3 [22:16:48.277] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.277] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.277] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:48.277] | : . ' .. .. .. ..$ rank: num 0 [22:16:48.277] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.277] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.277] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.277] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.277] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.277] | : . ' ..- attr(*, "init")= chr "done" [22:16:48.277] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.277] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.277] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.277] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.277] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:48.277] | : . ' .. ..$ workers:List of 1 [22:16:48.277] | : . ' .. .. ..$ :List of 3 [22:16:48.277] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.277] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.277] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:48.277] | : . ' .. .. .. ..$ rank: num 0 [22:16:48.277] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.277] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.277] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.277] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:48.277] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.277] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.277] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.294] | : . ' Formals differ [22:16:48.294] | : . all.equal() for future ... done [22:16:48.295] | : . Future strategies differ at level 1 [22:16:48.295] | : all.equal() for FutureStrategyList ... done [22:16:48.296] | : plan(): Setting new future backend stack: [22:16:48.296] | : List of future strategies: [22:16:48.296] | : 1. cluster: [22:16:48.296] | : - args: function (..., workers = "") [22:16:48.296] | : - tweaked: TRUE [22:16:48.296] | : - call: plan(cluster, workers = cl) [22:16:48.298] | : List of 1 [22:16:48.298] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.298] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.298] | : ..- attr(*, "init")= logi TRUE [22:16:48.298] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.298] | : earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.298] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.298] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.298] | : ..- attr(*, "tweaks")=List of 1 [22:16:48.298] | : .. ..$ workers:List of 1 [22:16:48.298] | : .. .. ..$ :List of 3 [22:16:48.298] | : .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.298] | : .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.298] | : .. .. .. ..$ host: chr "localhost" [22:16:48.298] | : .. .. .. ..$ rank: num 0 [22:16:48.298] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.298] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.298] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.298] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:48.304] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [22:16:48.305] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.305] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [22:16:48.306] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.306] | : . stopWorkers() for ClusterFutureBackend ... [22:16:48.307] | : . ' Interrupt active futures ... [22:16:48.308] | : . ' , Number of futures: 0 [22:16:48.309] | : . ' Interrupt active futures ... done [22:16:48.309] | : . ' Clear future registry ... [22:16:48.310] | : . ' Clear future registry ... done [22:16:48.311] | : . ' Stop cluster workers ... [22:16:48.311] | : . ' , Stopping existing cluster ... [22:16:48.312] | : . ' , ; No pre-existing cluster. Skipping [22:16:48.312] | : . ' , Stopping existing cluster ... done [22:16:48.313] | : . ' Stop cluster workers ... done [22:16:48.313] | : . stopWorkers() for ClusterFutureBackend ... done [22:16:48.314] | : . Legacy shutdown of cluster workers ... [22:16:48.314] | : . ' Stopping existing cluster ... [22:16:48.315] | : . ' , No pre-existing cluster. Skipping [22:16:48.315] | : . ' Stopping existing cluster ... done [22:16:48.316] | : . Legacy shutdown of cluster workers ... done [22:16:48.316] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:48.317] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [22:16:48.317] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.317] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.317] | : . - attr(*, "init")= logi TRUE [22:16:48.317] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.317] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.317] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.317] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.317] | : . - attr(*, "tweaks")=List of 1 [22:16:48.317] | : . ..$ workers:List of 1 [22:16:48.317] | : . .. ..$ :List of 3 [22:16:48.317] | : . .. .. ..$ con : 'sockconn' int 4 [22:16:48.317] | : . .. .. .. ..- attr(*, "conn_id")= [22:16:48.317] | : . .. .. ..$ host: chr "localhost" [22:16:48.317] | : . .. .. ..$ rank: num 0 [22:16:48.317] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.317] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.317] | : . - attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.323] | : . init: TRUE [22:16:48.324] | : . makeFutureBackend() ... [22:16:48.325] | : . ' Backend function: <'NULL'> [22:16:48.325] | : . ' Evaluator tweak arguments: [n=1] [22:16:48.326] | : . ' List of 1 [22:16:48.326] | : . ' $ workers:List of 1 [22:16:48.326] | : . ' ..$ :List of 3 [22:16:48.326] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:48.326] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:48.326] | : . ' .. ..$ host: chr "localhost" [22:16:48.326] | : . ' .. ..$ rank: num 0 [22:16:48.326] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.326] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.329] | : . ' Evaluator formal arguments: [n=1] [22:16:48.330] | : . ' Dotted pair list of 1 [22:16:48.330] | : . ' $ workers:List of 1 [22:16:48.330] | : . ' ..$ :List of 3 [22:16:48.330] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:48.330] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:48.330] | : . ' .. ..$ host: chr "localhost" [22:16:48.330] | : . ' .. ..$ rank: num 0 [22:16:48.330] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.330] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.333] | : . ' Arguments passed to the future-backend factory: [n=1] [22:16:48.334] | : . ' List of 1 [22:16:48.334] | : . ' $ workers:List of 1 [22:16:48.334] | : . ' ..$ :List of 3 [22:16:48.334] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:48.334] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:48.334] | : . ' .. ..$ host: chr "localhost" [22:16:48.334] | : . ' .. ..$ rank: num 0 [22:16:48.334] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.334] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.367] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... [22:16:48.367] | : . ' , Stopping existing cluster ... [22:16:48.368] | : . ' , ; No pre-existing cluster. Skipping [22:16:48.368] | : . ' , Stopping existing cluster ... done [22:16:48.369] | : . ' , Number of workers: 1 [22:16:48.369] | : . ' , Generated workers UUID [22:16:48.370] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [22:16:48.370] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... done [22:16:48.371] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [22:16:48.371] | : . makeFutureBackend() ... done [22:16:48.372] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [22:16:48.372] | : nbrOfWorkers(NULL) ... [22:16:48.373] | : . List of 4 [22:16:48.373] | : . $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:48.373] | : . $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:48.373] | : . $ : language nbrOfWorkers() [22:16:48.373] | : . $ : language nbrOfWorkers.NULL() [22:16:48.375] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.376] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.376] | : . Number of workers: 1 [22:16:48.377] | : nbrOfWorkers(NULL) ... done [22:16:48.377] | : plan(): nbrOfWorkers() = 1 [22:16:48.378] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:16:48.378] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.379] future(..., label = NULL) ... [22:16:48.379] | lazy: FALSE [22:16:48.380] | stdout: TRUE [22:16:48.380] | conditions: [n=1] 'condition' [22:16:48.381] | getGlobalsAndPackages() ... [22:16:48.381] | : Searching for globals ... [22:16:48.386] | : . globals found: [3] '[[', 'X', 'i' [22:16:48.386] | : Searching for globals ... done [22:16:48.387] | : Resolving globals: FALSE [22:16:48.388] | : Search for packages associated with the globals ... [22:16:48.388] | : . Packages associated with globals: [1] 'base' [22:16:48.389] | : . Packages: [1] 'base' [22:16:48.390] | : Search for packages associated with the globals ... done [22:16:48.390] | : Packages after dropping 'base': [0] [22:16:48.391] | : globals: [2] 'X', 'i' [22:16:48.391] | : packages: [0] [22:16:48.392] | getGlobalsAndPackages() ... done [22:16:48.392] | run() for 'Future' () ... [22:16:48.393] | : state: 'created' [22:16:48.393] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.394] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.394] | : Using ClusterFutureBackend ... [22:16:48.395] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:16:48.395] | : . Launching futures ... [22:16:48.396] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:48.396] | : . ' , Workers: [n=1] [22:16:48.397] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:48.398] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:48.398] | : . ' , requestWorker() ... [22:16:48.399] | : . ' , ; requestNode() ... [22:16:48.399] | : . ' , ; ` Number of workers: 1 [22:16:48.400] | : . ' , ; ` Polling for a free worker ... [22:16:48.400] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.401] | : . ' , ; ` | : Listing all futures [22:16:48.401] | : . ' , ; ` | : Number of registered futures: 0 [22:16:48.402] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.402] | : . ' , ; ` | Total time: 0 [22:16:48.403] | : . ' , ; ` Polling for a free worker ... done [22:16:48.403] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.404] | : . ' , ; ` | Listing all futures [22:16:48.404] | : . ' , ; ` | Number of registered futures: 0 [22:16:48.405] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.405] | : . ' , ; ` avail: [n=1] '1' [22:16:48.406] | : . ' , ; ` Index of first available worker: 1 [22:16:48.406] | : . ' , ; ` Validate that the worker is functional ... [22:16:48.407] | : . ' , ; ` | Connection is valid [22:16:48.407] | : . ' , ; ` | node of a socket cluster on host 'localhost' with pid 57832 [22:16:48.408] | : . ' , ; ` | Worker is functional [22:16:48.409] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.410] | : . ' , ; ` | : Listing all futures [22:16:48.410] | : . ' , ; ` | : Number of registered futures: 0 [22:16:48.410] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.411] | : . ' , ; ` Validate that the worker is functional ... done [22:16:48.411] | : . ' , ; requestNode() ... done [22:16:48.412] | : . ' , ; cluster node index: 1 [22:16:48.414] | : . ' , requestWorker() ... done [22:16:48.415] | : . ' , eraseGlobalEnvironment() ... [22:16:48.508] | : . ' , eraseGlobalEnvironment() ... done [22:16:48.509] | : . ' , launchFuture() ... [22:16:48.509] | : . ' , ; cluster node index: 1 [22:16:48.510] | : . ' , ; getFutureData() ... [22:16:48.510] | : . ' , ; ` getFutureCore() ... [22:16:48.511] | : . ' , ; ` | Packages needed by the future expression (n = 0): [22:16:48.511] | : . ' , ; ` getFutureCore() ... done [22:16:48.512] | : . ' , ; ` getFutureCapture() ... [22:16:48.512] | : . ' , ; ` getFutureCapture() ... done [22:16:48.513] | : . ' , ; ` getFutureContext() ... [22:16:48.513] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.514] | : . ' , ; ` | : Getting stack without first backend: [n=0] [22:16:48.514] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.515] | : . ' , ; ` | Packages needed by future backend (n = 0): [22:16:48.515] | : . ' , ; ` getFutureContext() ... done [22:16:48.516] | : . ' , ; getFutureData() ... done [22:16:48.517] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:48.517] | : . ' , ; ` Appended future to position #1 [22:16:48.518] | : . ' , ; ` Number of registered futures: 1 [22:16:48.518] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:48.519] | : . ' , launchFuture() ... done [22:16:48.519] | : . ' , ClusterFuture started [22:16:48.520] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:48.520] | : . Launching futures ... done [22:16:48.521] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:48.521] | : Using ClusterFutureBackend ... done [22:16:48.522] | run() for 'Future' () ... done [22:16:48.522] future(..., label = NULL) ... done [22:16:48.523] future(..., label = NULL) ... [22:16:48.523] | lazy: FALSE [22:16:48.524] | stdout: TRUE [22:16:48.524] | conditions: [n=1] 'condition' [22:16:48.525] | getGlobalsAndPackages() ... [22:16:48.525] | : Searching for globals ... [22:16:48.530] | : . globals found: [3] '[[', 'X', 'i' [22:16:48.531] | : Searching for globals ... done [22:16:48.531] | : Resolving globals: FALSE [22:16:48.532] | : Search for packages associated with the globals ... [22:16:48.533] | : . Packages associated with globals: [1] 'base' [22:16:48.533] | : . Packages: [1] 'base' [22:16:48.534] | : Search for packages associated with the globals ... done [22:16:48.534] | : Packages after dropping 'base': [0] [22:16:48.535] | : globals: [2] 'X', 'i' [22:16:48.535] | : packages: [0] [22:16:48.536] | getGlobalsAndPackages() ... done [22:16:48.537] | run() for 'Future' () ... [22:16:48.537] | : state: 'created' [22:16:48.538] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.538] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.539] | : Using ClusterFutureBackend ... [22:16:48.539] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [22:16:48.540] | : . Launching futures ... [22:16:48.540] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:48.541] | : . ' , Workers: [n=1] [22:16:48.541] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:48.542] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:48.542] | : . ' , requestWorker() ... [22:16:48.543] | : . ' , ; requestNode() ... [22:16:48.544] | : . ' , ; ` Number of workers: 1 [22:16:48.544] | : . ' , ; ` Polling for a free worker ... [22:16:48.545] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.545] | : . ' , ; ` | : Listing all futures [22:16:48.546] | : . ' , ; ` | : Number of registered futures: 1 [22:16:48.546] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.547] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [22:16:48.559] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [22:16:48.590] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [22:16:48.591] | : . ' , ; ` | : . resolved() ... [22:16:48.592] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... [22:16:48.592] | : . ' , ; ` | : . ' , Cluster node socket connection: index=4, id=182 [22:16:48.593] | : . ' , ; ` | : . ' , assertValidConnection() ... [22:16:48.593] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:48.594] | : . ' , ; ` | : . ' , assertValidConnection() ... done [22:16:48.595] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... [22:16:48.595] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:48.596] | : . ' , ; ` | : . ' , ; [1] TRUE [22:16:48.596] | : . ' , ; ` | : . ' , ; Received data: [22:16:48.597] | : . ' , ; ` | : . ' , ; List of 5 [22:16:48.597] | : . ' , ; ` | : . ' , ; $ type : chr "VALUE" [22:16:48.597] | : . ' , ; ` | : . ' , ; $ value :List of 16 [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ value : int 1 [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ visible : logi TRUE [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ stdout : chr "" [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ conditions : list() [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ rng : logi FALSE [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ seed : NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ misuseGlobalEnv : NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ misuseConnections :List of 3 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ added : NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ removed : NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ replaced: NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ misuseDevices : NULL [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ misuseDefaultDevice: list() [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "3" [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ session_uuid : chr "e0beb35a3c4d2f5df772ffba08919813" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..- attr(*, "source")=List of 5 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ host : Named chr "CRANWIN3" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ pid : int 57832 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ random: int 2147483647 [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ r_info :List of 4 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. .. ..$ : int [1:3] 4 6 0 [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ os : chr "windows" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ os_name : chr "Windows" [22:16:48.597] | : . ' , ; ` | : . ' , ; .. ..$ captures_utf8: logi TRUE [22:16:48.597] | : . ' , ; ` | : . ' , ; ..$ version : chr "1.8" [22:16:48.597] | : . ' , ; ` | : . ' , ; ..- attr(*, "class")= chr "FutureResult" [22:16:48.597] | : . ' , ; ` | : . ' , ; $ success: logi TRUE [22:16:48.597] | : . ' , ; ` | : . ' , ; $ time : 'proc_time' Named num [1:5] 0.06 0 0.06 NA NA [22:16:48.597] | : . ' , ; ` | : . ' , ; ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:48.597] | : . ' , ; ` | : . ' , ; $ tag : NULL [22:16:48.612] | : . ' , ; ` | : . ' , ; Received FutureResult [22:16:48.613] | : . ' , ; ` | : . ' , ; FutureResult: [22:16:48.613] | : . ' , ; ` | : . ' , ; value: 'integer' [22:16:48.613] | : . ' , ; ` | : . ' , ; visible: TRUE [22:16:48.613] | : . ' , ; ` | : . ' , ; stdout: character [22:16:48.613] | : . ' , ; ` | : . ' , ; conditions: [n = 0] [22:16:48.613] | : . ' , ; ` | : . ' , ; RNG used: FALSE [22:16:48.613] | : . ' , ; ` | : . ' , ; duration: 0.05574703 secs (started 2026-03-13 22:16:48.518483) [22:16:48.613] | : . ' , ; ` | : . ' , ; version: 1.8 [22:16:48.614] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:48.615] | : . ' , ; ` | : . ' , ; ` Removed future from position #1 [22:16:48.615] | : . ' , ; ` | : . ' , ; ` Number of registered futures: 0 [22:16:48.616] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:48.616] | : . ' , ; ` | : . ' , ; Erased future from future backend [22:16:48.617] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [22:16:48.617] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [22:16:48.618] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [22:16:48.618] | : . ' , ; ` | : . ' , ; signalConditions() ... [22:16:48.619] | : . ' , ; ` | : . ' , ; ` include = 'immediateCondition' [22:16:48.619] | : . ' , ; ` | : . ' , ; ` exclude = [22:16:48.620] | : . ' , ; ` | : . ' , ; ` resignal = FALSE [22:16:48.620] | : . ' , ; ` | : . ' , ; ` Number of conditions: 0 [22:16:48.621] | : . ' , ; ` | : . ' , ; ` int 0 [22:16:48.622] | : . ' , ; ` | : . ' , ; signalConditions() ... done [22:16:48.622] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... done [22:16:48.623] | : . ' , ; ` | : . ' , receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:48.623] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... done [22:16:48.624] | : . ' , ; ` | : . ' assertFutureResult() ... [22:16:48.624] | : . ' , ; ` | : . ' , Future uuid: a0e2c2057a439d13cf08c3e3f94af757-3 [22:16:48.625] | : . ' , ; ` | : . ' , identical; success [22:16:48.625] | : . ' , ; ` | : . ' assertFutureResult() ... done [22:16:48.626] | : . ' , ; ` | : . resolved() ... done [22:16:48.626] | : . ' , ; ` | : . Future at position #1 is resolved ... [22:16:48.627] | : . ' , ; ` | : . ' value() for ClusterFuture () ... [22:16:48.627] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:48.628] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:48.628] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:48.629] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:48.629] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:48.630] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:48.630] | : . ' , ; ` | : . ' , signalConditions() ... [22:16:48.631] | : . ' , ; ` | : . ' , ; include = 'immediateCondition' [22:16:48.631] | : . ' , ; ` | : . ' , ; exclude = [22:16:48.632] | : . ' , ; ` | : . ' , ; resignal = FALSE [22:16:48.632] | : . ' , ; ` | : . ' , ; Number of conditions: 0 [22:16:48.633] | : . ' , ; ` | : . ' , ; int 0 [22:16:48.633] | : . ' , ; ` | : . ' , signalConditions() ... done [22:16:48.634] | : . ' , ; ` | : . ' , check for misuse ... [22:16:48.635] | : . ' , ; ` | : . ' , check for misuse ... done [22:16:48.635] | : . ' , ; ` | : . ' value() for ClusterFuture () ... done [22:16:48.636] | : . ' , ; ` | : . Future at position #1 is resolved ... done [22:16:48.636] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [22:16:48.637] | : . ' , ; ` | : Remove collected futures ... [22:16:48.637] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [22:16:48.638] | : . ' , ; ` | : Remove collected futures ... done [22:16:48.638] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [22:16:48.639] | : . ' , ; ` | Early signaling of 1 future candidates ... [22:16:48.639] | : . ' , ; ` | Number of futures with early signaling requested: 0 [22:16:48.640] | : . ' , ; ` | Early signaling of 1 future candidates ... done [22:16:48.640] | : . ' , ; ` | Number of registered futures: 1 [22:16:48.641] | : . ' , ; ` Polling for a free worker ... done [22:16:48.642] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.642] | : . ' , ; ` | Listing all futures [22:16:48.643] | : . ' , ; ` | Number of registered futures: 0 [22:16:48.643] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.644] | : . ' , ; ` Total time: 0.0969300270080566 [22:16:48.644] | : . ' , ; requestNode() ... done [22:16:48.645] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.645] | : . ' , ; ` Listing all futures [22:16:48.646] | : . ' , ; ` Number of registered futures: 0 [22:16:48.646] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.647] | : . ' , ; avail: [n=1] '1' [22:16:48.647] | : . ' , ; Index of first available worker: 1 [22:16:48.648] | : . ' , ; Validate that the worker is functional ... [22:16:48.648] | : . ' , ; ` Connection is valid [22:16:48.649] | : . ' , ; ` node of a socket cluster on host 'localhost' with pid 57832 [22:16:48.650] | : . ' , ; ` Worker is functional [22:16:48.650] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:48.651] | : . ' , ; ` | Listing all futures [22:16:48.651] | : . ' , ; ` | Number of registered futures: 0 [22:16:48.652] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:48.652] | : . ' , ; Validate that the worker is functional ... done [22:16:48.653] | : . ' , requestWorker() ... done [22:16:48.653] | : . ' , cluster node index: 1 [22:16:48.654] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:48.654] | : . ' eraseGlobalEnvironment() ... [22:16:48.655] | : . ' eraseGlobalEnvironment() ... done [22:16:48.656] | : . ' launchFuture() ... [22:16:48.656] | : . ' , cluster node index: 1 [22:16:48.657] | : . ' , getFutureData() ... [22:16:48.657] | : . ' , ; getFutureCore() ... [22:16:48.658] | : . ' , ; ` Packages needed by the future expression (n = 0): [22:16:48.658] | : . ' , ; getFutureCore() ... done [22:16:48.659] | : . ' , ; getFutureCapture() ... [22:16:48.659] | : . ' , ; getFutureCapture() ... done [22:16:48.660] | : . ' , ; getFutureContext() ... [22:16:48.660] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.661] | : . ' , ; ` | Getting stack without first backend: [n=0] [22:16:48.661] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:48.662] | : . ' , ; ` Packages needed by future backend (n = 0): [22:16:48.662] | : . ' , ; getFutureContext() ... done [22:16:48.663] | : . ' , getFutureData() ... done [22:16:48.664] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:48.664] | : . ' , ; Appended future to position #1 [22:16:48.665] | : . ' , ; Number of registered futures: 1 [22:16:48.665] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:48.666] | : . ' launchFuture() ... done [22:16:48.666] | : . ' ClusterFuture started [22:16:48.667] | : . Launching futures ... done [22:16:48.667] | : Using ClusterFutureBackend ... done [22:16:48.668] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:48.668] | run() for 'Future' () ... done [22:16:48.669] future(..., label = NULL) ... done [22:16:48.670] value() for list ... [22:16:48.670] | recursive: 0 [22:16:48.671] | reduce: FALSE [22:16:48.671] | length: 2 [22:16:48.672] | Number of remaining objects: 2 [22:16:48.673] | checking value #1: [22:16:48.673] | 'obj' is ClusterFuture [22:16:48.673] | resolved() ... [22:16:48.674] | resolved() ... done [22:16:48.674] | ClusterFuture #1 [22:16:48.675] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:48.675] | : nx: 2 [22:16:48.676] | : relay: TRUE [22:16:48.676] | : stdout: TRUE [22:16:48.677] | : signal: TRUE [22:16:48.677] | : resignal: FALSE [22:16:48.678] | : force: TRUE [22:16:48.678] | : relayed: [n=2] FALSE, FALSE [22:16:48.679] | : queued futures: [n=2] FALSE, FALSE [22:16:48.679] | : until=1 [22:16:48.680] | : relaying element #1 [22:16:48.680] | : value() for ClusterFuture () ... [22:16:48.683] | : . result() for ClusterFuture ... [22:16:48.683] | : . ' result already collected: FutureResult [22:16:48.684] | : . result() for ClusterFuture ... done [22:16:48.684] | : . result() for ClusterFuture ... [22:16:48.685] | : . ' result already collected: FutureResult [22:16:48.685] | : . result() for ClusterFuture ... done [22:16:48.686] | : . signalConditions() ... [22:16:48.686] | : . ' include = 'immediateCondition' [22:16:48.687] | : . ' exclude = [22:16:48.687] | : . ' resignal = FALSE [22:16:48.688] | : . ' Number of conditions: 0 [22:16:48.689] | : . ' int 0 [22:16:48.689] | : . signalConditions() ... done [22:16:48.690] | : . relay stdout ... [22:16:48.691] | : . relay stdout ... done [22:16:48.691] | : . check for misuse ... [22:16:48.692] | : . check for misuse ... done [22:16:48.692] | : value() for ClusterFuture () ... done [22:16:48.693] | : result() for ClusterFuture ... [22:16:48.693] | : . result already collected: FutureResult [22:16:48.694] | : result() for ClusterFuture ... done [22:16:48.694] | : signalConditions() ... [22:16:48.695] | : . include = 'immediateCondition' [22:16:48.695] | : . exclude = [22:16:48.696] | : . resignal = FALSE [22:16:48.696] | : . Number of conditions: 0 [22:16:48.696] | : . int 0 [22:16:48.697] | : signalConditions() ... done [22:16:48.698] | : result() for ClusterFuture ... [22:16:48.698] | : . result already collected: FutureResult [22:16:48.699] | : result() for ClusterFuture ... done [22:16:48.699] | : signalConditions() ... [22:16:48.700] | : . include = 'condition' [22:16:48.700] | : . exclude = 'error', 'immediateCondition' [22:16:48.701] | : . resignal = TRUE [22:16:48.701] | : . Number of conditions: 0 [22:16:48.702] | : . int 0 [22:16:48.703] | : signalConditions() ... done [22:16:48.703] | : relayed: [n=2] TRUE, FALSE [22:16:48.704] | : queued futures: [n=2] TRUE, FALSE [22:16:48.704] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:48.705] | value(, ...) ... [22:16:48.705] | : value() for ClusterFuture () ... [22:16:48.706] | : . result() for ClusterFuture ... [22:16:48.706] | : . ' result already collected: FutureResult [22:16:48.707] | : . result() for ClusterFuture ... done [22:16:48.708] | : . result() for ClusterFuture ... [22:16:48.708] | : . ' result already collected: FutureResult [22:16:48.709] | : . result() for ClusterFuture ... done [22:16:48.709] | : . signalConditions() ... [22:16:48.710] | : . ' include = 'immediateCondition' [22:16:48.710] | : . ' exclude = [22:16:48.711] | : . ' resignal = FALSE [22:16:48.711] | : . ' Number of conditions: 0 [22:16:48.712] | : . ' int 0 [22:16:48.713] | : . signalConditions() ... done [22:16:48.713] | : . check for misuse ... [22:16:48.714] | : . check for misuse ... done [22:16:48.714] | : value() for ClusterFuture () ... done [22:16:48.715] | : value: [22:16:48.715] | value(, ...) ... done [22:16:48.716] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:48.716] | : nx: 2 [22:16:48.717] | : relay: TRUE [22:16:48.717] | : stdout: TRUE [22:16:48.718] | : signal: TRUE [22:16:48.718] | : resignal: FALSE [22:16:48.719] | : force: TRUE [22:16:48.719] | : relayed: [n=2] TRUE, FALSE [22:16:48.720] | : queued futures: [n=2] TRUE, FALSE [22:16:48.720] | : until=2 [22:16:48.721] | : relaying element #2 [22:16:48.721] | : relayed: [n=2] TRUE, FALSE [22:16:48.722] | : queued futures: [n=2] TRUE, FALSE [22:16:48.722] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:48.723] | resolve() for ClusterFuture ... [22:16:48.723] | resolve() for ClusterFuture ... done [22:16:48.724] | length: 1 (resolved future 1) [22:16:48.724] | checking value #2: [22:16:48.725] | 'obj' is ClusterFuture [22:16:48.725] | resolved() ... [22:16:48.726] | : resolved() for ClusterFuture () ... [22:16:48.727] | : . Cluster node socket connection: index=4, id=182 [22:16:48.727] | : . assertValidConnection() ... [22:16:48.728] | : . ' cluster node index: 1 [22:16:48.729] | : . assertValidConnection() ... done [22:16:48.729] | : . receiveMessageFromWorker() for ClusterFuture ... [22:16:48.730] | : . ' cluster node index: 1 [22:16:48.730] | : . ' [1] TRUE [22:16:48.731] | : . ' Received data: [22:16:48.732] | : . ' List of 5 [22:16:48.732] | : . ' $ type : chr "VALUE" [22:16:48.732] | : . ' $ value :List of 16 [22:16:48.732] | : . ' ..$ value : int 2 [22:16:48.732] | : . ' ..$ visible : logi TRUE [22:16:48.732] | : . ' ..$ stdout : chr "" [22:16:48.732] | : . ' ..$ conditions : list() [22:16:48.732] | : . ' ..$ rng : logi FALSE [22:16:48.732] | : . ' ..$ seed : NULL [22:16:48.732] | : . ' ..$ misuseGlobalEnv : NULL [22:16:48.732] | : . ' ..$ misuseConnections :List of 3 [22:16:48.732] | : . ' .. ..$ added : NULL [22:16:48.732] | : . ' .. ..$ removed : NULL [22:16:48.732] | : . ' .. ..$ replaced: NULL [22:16:48.732] | : . ' ..$ misuseDevices : NULL [22:16:48.732] | : . ' ..$ misuseDefaultDevice: list() [22:16:48.732] | : . ' ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.732] | : . ' ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.732] | : . ' ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "4" [22:16:48.732] | : . ' ..$ session_uuid : chr "e0beb35a3c4d2f5df772ffba08919813" [22:16:48.732] | : . ' .. ..- attr(*, "source")=List of 5 [22:16:48.732] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [22:16:48.732] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:48.732] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:48.732] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:48.732] | : . ' .. .. ..$ pid : int 57832 [22:16:48.732] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:48" [22:16:48.732] | : . ' .. .. ..$ random: int 2147483647 [22:16:48.732] | : . ' ..$ r_info :List of 4 [22:16:48.732] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:48.732] | : . ' .. .. ..$ : int [1:3] 4 6 0 [22:16:48.732] | : . ' .. ..$ os : chr "windows" [22:16:48.732] | : . ' .. ..$ os_name : chr "Windows" [22:16:48.732] | : . ' .. ..$ captures_utf8: logi TRUE [22:16:48.732] | : . ' ..$ version : chr "1.8" [22:16:48.732] | : . ' ..- attr(*, "class")= chr "FutureResult" [22:16:48.732] | : . ' $ success: logi TRUE [22:16:48.732] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [22:16:48.732] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:48.732] | : . ' $ tag : NULL [22:16:48.747] | : . ' Received FutureResult [22:16:48.748] | : . ' FutureResult: [22:16:48.748] | : . ' value: 'integer' [22:16:48.748] | : . ' visible: TRUE [22:16:48.748] | : . ' stdout: character [22:16:48.748] | : . ' conditions: [n = 0] [22:16:48.748] | : . ' RNG used: FALSE [22:16:48.748] | : . ' duration: 0.003018141 secs (started 2026-03-13 22:16:48.66424) [22:16:48.748] | : . ' version: 1.8 [22:16:48.749] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:48.750] | : . ' , Removed future from position #1 [22:16:48.750] | : . ' , Number of registered futures: 0 [22:16:48.751] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:48.751] | : . ' Erased future from future backend [22:16:48.752] | : . ' result() for ClusterFuture ... [22:16:48.753] | : . ' , result already collected: FutureResult [22:16:48.753] | : . ' result() for ClusterFuture ... done [22:16:48.754] | : . ' signalConditions() ... [22:16:48.754] | : . ' , include = 'immediateCondition' [22:16:48.754] | : . ' , exclude = [22:16:48.755] | : . ' , resignal = FALSE [22:16:48.756] | : . ' , Number of conditions: 0 [22:16:48.756] | : . ' , int 0 [22:16:48.757] | : . ' signalConditions() ... done [22:16:48.758] | : . receiveMessageFromWorker() for ClusterFuture ... done [22:16:48.758] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:48.759] | : resolved() for ClusterFuture () ... done [22:16:48.759] | : assertFutureResult() ... [22:16:48.760] | : . Future uuid: a0e2c2057a439d13cf08c3e3f94af757-4 [22:16:48.760] | : . identical; success [22:16:48.760] | : assertFutureResult() ... done [22:16:48.761] | resolved() ... done [22:16:48.761] | ClusterFuture #2 [22:16:48.762] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:48.763] | : nx: 2 [22:16:48.763] | : relay: TRUE [22:16:48.764] | : stdout: TRUE [22:16:48.764] | : signal: TRUE [22:16:48.765] | : resignal: FALSE [22:16:48.765] | : force: TRUE [22:16:48.766] | : relayed: [n=2] TRUE, FALSE [22:16:48.766] | : queued futures: [n=2] TRUE, FALSE [22:16:48.767] | : until=2 [22:16:48.767] | : relaying element #2 [22:16:48.768] | : value() for ClusterFuture () ... [22:16:48.768] | : . result() for ClusterFuture ... [22:16:48.769] | : . ' result already collected: FutureResult [22:16:48.769] | : . result() for ClusterFuture ... done [22:16:48.770] | : . result() for ClusterFuture ... [22:16:48.770] | : . ' result already collected: FutureResult [22:16:48.771] | : . result() for ClusterFuture ... done [22:16:48.771] | : . signalConditions() ... [22:16:48.772] | : . ' include = 'immediateCondition' [22:16:48.772] | : . ' exclude = [22:16:48.773] | : . ' resignal = FALSE [22:16:48.773] | : . ' Number of conditions: 0 [22:16:48.773] | : . ' int 0 [22:16:48.774] | : . signalConditions() ... done [22:16:48.775] | : . relay stdout ... [22:16:48.775] | : . relay stdout ... done [22:16:48.776] | : . check for misuse ... [22:16:48.776] | : . check for misuse ... done [22:16:48.777] | : value() for ClusterFuture () ... done [22:16:48.777] | : result() for ClusterFuture ... [22:16:48.778] | : . result already collected: FutureResult [22:16:48.778] | : result() for ClusterFuture ... done [22:16:48.779] | : signalConditions() ... [22:16:48.779] | : . include = 'immediateCondition' [22:16:48.780] | : . exclude = [22:16:48.780] | : . resignal = FALSE [22:16:48.781] | : . Number of conditions: 0 [22:16:48.781] | : . int 0 [22:16:48.782] | : signalConditions() ... done [22:16:48.783] | : result() for ClusterFuture ... [22:16:48.783] | : . result already collected: FutureResult [22:16:48.784] | : result() for ClusterFuture ... done [22:16:48.784] | : signalConditions() ... [22:16:48.785] | : . include = 'condition' [22:16:48.785] | : . exclude = 'error', 'immediateCondition' [22:16:48.786] | : . resignal = TRUE [22:16:48.786] | : . Number of conditions: 0 [22:16:48.786] | : . int 0 [22:16:48.787] | : signalConditions() ... done [22:16:48.788] | : relayed: [n=2] TRUE, TRUE [22:16:48.788] | : queued futures: [n=2] TRUE, TRUE [22:16:48.789] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:48.789] | value(, ...) ... [22:16:48.790] | : value() for ClusterFuture () ... [22:16:48.790] | : . result() for ClusterFuture ... [22:16:48.791] | : . ' result already collected: FutureResult [22:16:48.791] | : . result() for ClusterFuture ... done [22:16:48.792] | : . result() for ClusterFuture ... [22:16:48.792] | : . ' result already collected: FutureResult [22:16:48.793] | : . result() for ClusterFuture ... done [22:16:48.793] | : . signalConditions() ... [22:16:48.794] | : . ' include = 'immediateCondition' [22:16:48.794] | : . ' exclude = [22:16:48.795] | : . ' resignal = FALSE [22:16:48.795] | : . ' Number of conditions: 0 [22:16:48.796] | : . ' int 0 [22:16:48.797] | : . signalConditions() ... done [22:16:48.797] | : . check for misuse ... [22:16:48.798] | : . check for misuse ... done [22:16:48.798] | : value() for ClusterFuture () ... done [22:16:48.799] | : value: [22:16:48.799] | value(, ...) ... done [22:16:48.800] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:48.800] | : nx: 2 [22:16:48.801] | : relay: TRUE [22:16:48.801] | : stdout: TRUE [22:16:48.802] | : signal: TRUE [22:16:48.802] | : resignal: FALSE [22:16:48.803] | : force: TRUE [22:16:48.803] | : relayed: [n=2] TRUE, TRUE [22:16:48.803] | : queued futures: [n=2] TRUE, TRUE [22:16:48.804] | : until=2 [22:16:48.804] | : relayed: [n=2] TRUE, TRUE [22:16:48.805] | : queued futures: [n=2] TRUE, TRUE [22:16:48.805] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:48.806] | resolve() for ClusterFuture ... [22:16:48.806] | resolve() for ClusterFuture ... done [22:16:48.807] | length: 0 (resolved future 2) [22:16:48.807] | Relaying remaining futures ... [22:16:48.808] | : signalConditionsASAP(NULL, pos=0) ... [22:16:48.808] | : . nx: 2 [22:16:48.809] | : . relay: TRUE [22:16:48.809] | : . stdout: TRUE [22:16:48.810] | : . signal: TRUE [22:16:48.810] | : . resignal: FALSE [22:16:48.811] | : . force: TRUE [22:16:48.811] | : . relayed: [n=2] TRUE, TRUE [22:16:48.812] | : . queued futures: [n=2] TRUE, TRUE - flush all [22:16:48.812] | : . relayed: [n=2] TRUE, TRUE [22:16:48.813] | : . queued futures: [n=2] TRUE, TRUE [22:16:48.813] | : signalConditionsASAP(NULL, pos=0) ... done [22:16:48.814] | Relaying remaining futures ... done [22:16:48.814] value() for list ... done Iteration #3 [22:16:48.991] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:48.992] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:48.992] | : all.equal() for FutureStrategyList ... [22:16:48.993] | : . New stack: [22:16:48.993] | : . List of 1 [22:16:48.993] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:48.993] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:48.993] | : . ..- attr(*, "init")= logi TRUE [22:16:48.993] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:48.993] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:48.993] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.993] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:48.993] | : . ..- attr(*, "tweaks")=List of 1 [22:16:48.993] | : . .. ..$ workers:List of 1 [22:16:48.993] | : . .. .. ..$ :List of 3 [22:16:48.993] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:48.993] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:48.993] | : . .. .. .. ..$ host: chr "localhost" [22:16:48.993] | : . .. .. .. ..$ rank: num 0 [22:16:48.993] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:48.993] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:48.993] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:48.993] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.007] | : . Old stack: [22:16:49.008] | : . List of 1 [22:16:49.008] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.008] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.008] | : . ..- attr(*, "init")= chr "done" [22:16:49.008] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.008] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.008] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.008] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.008] | : . ..- attr(*, "tweaks")=List of 1 [22:16:49.008] | : . .. ..$ workers:List of 1 [22:16:49.008] | : . .. .. ..$ :List of 3 [22:16:49.008] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.008] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.008] | : . .. .. .. ..$ host: chr "localhost" [22:16:49.008] | : . .. .. .. ..$ rank: num 0 [22:16:49.008] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.008] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.008] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.008] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:49.008] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.008] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.008] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.008] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.023] | : . Not identical [22:16:49.024] | : . all.equal() for future ... [22:16:49.025] | : . ' List of 2 [22:16:49.025] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.025] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.025] | : . ' ..- attr(*, "init")= logi TRUE [22:16:49.025] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.025] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.025] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.025] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.025] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:49.025] | : . ' .. ..$ workers:List of 1 [22:16:49.025] | : . ' .. .. ..$ :List of 3 [22:16:49.025] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.025] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.025] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:49.025] | : . ' .. .. .. ..$ rank: num 0 [22:16:49.025] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.025] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.025] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.025] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.025] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.025] | : . ' ..- attr(*, "init")= chr "done" [22:16:49.025] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.025] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.025] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.025] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.025] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:49.025] | : . ' .. ..$ workers:List of 1 [22:16:49.025] | : . ' .. .. ..$ :List of 3 [22:16:49.025] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.025] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.025] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:49.025] | : . ' .. .. .. ..$ rank: num 0 [22:16:49.025] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.025] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.025] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.025] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:49.025] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.025] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.025] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.052] | : . ' Formals differ [22:16:49.053] | : . all.equal() for future ... done [22:16:49.054] | : . Future strategies differ at level 1 [22:16:49.055] | : all.equal() for FutureStrategyList ... done [22:16:49.056] | : plan(): Setting new future backend stack: [22:16:49.056] | : List of future strategies: [22:16:49.056] | : 1. cluster: [22:16:49.056] | : - args: function (..., workers = "") [22:16:49.056] | : - tweaked: TRUE [22:16:49.056] | : - call: plan(cluster, workers = cl) [22:16:49.059] | : List of 1 [22:16:49.059] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.059] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.059] | : ..- attr(*, "init")= logi TRUE [22:16:49.059] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.059] | : earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.059] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.059] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.059] | : ..- attr(*, "tweaks")=List of 1 [22:16:49.059] | : .. ..$ workers:List of 1 [22:16:49.059] | : .. .. ..$ :List of 3 [22:16:49.059] | : .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.059] | : .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.059] | : .. .. .. ..$ host: chr "localhost" [22:16:49.059] | : .. .. .. ..$ rank: num 0 [22:16:49.059] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.059] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.059] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.059] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.072] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [22:16:49.073] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.074] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [22:16:49.075] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.076] | : . stopWorkers() for ClusterFutureBackend ... [22:16:49.077] | : . ' Interrupt active futures ... [22:16:49.078] | : . ' , Number of futures: 0 [22:16:49.079] | : . ' Interrupt active futures ... done [22:16:49.080] | : . ' Clear future registry ... [22:16:49.082] | : . ' Clear future registry ... done [22:16:49.083] | : . ' Stop cluster workers ... [22:16:49.084] | : . ' , Stopping existing cluster ... [22:16:49.085] | : . ' , ; No pre-existing cluster. Skipping [22:16:49.086] | : . ' , Stopping existing cluster ... done [22:16:49.087] | : . ' Stop cluster workers ... done [22:16:49.088] | : . stopWorkers() for ClusterFutureBackend ... done [22:16:49.089] | : . Legacy shutdown of cluster workers ... [22:16:49.089] | : . ' Stopping existing cluster ... [22:16:49.090] | : . ' , No pre-existing cluster. Skipping [22:16:49.091] | : . ' Stopping existing cluster ... done [22:16:49.092] | : . Legacy shutdown of cluster workers ... done [22:16:49.093] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:49.094] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [22:16:49.094] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.094] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.094] | : . - attr(*, "init")= logi TRUE [22:16:49.094] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.094] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.094] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.094] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.094] | : . - attr(*, "tweaks")=List of 1 [22:16:49.094] | : . ..$ workers:List of 1 [22:16:49.094] | : . .. ..$ :List of 3 [22:16:49.094] | : . .. .. ..$ con : 'sockconn' int 4 [22:16:49.094] | : . .. .. .. ..- attr(*, "conn_id")= [22:16:49.094] | : . .. .. ..$ host: chr "localhost" [22:16:49.094] | : . .. .. ..$ rank: num 0 [22:16:49.094] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.094] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.094] | : . - attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.107] | : . init: TRUE [22:16:49.108] | : . makeFutureBackend() ... [22:16:49.108] | : . ' Backend function: <'NULL'> [22:16:49.109] | : . ' Evaluator tweak arguments: [n=1] [22:16:49.109] | : . ' List of 1 [22:16:49.109] | : . ' $ workers:List of 1 [22:16:49.109] | : . ' ..$ :List of 3 [22:16:49.109] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:49.109] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:49.109] | : . ' .. ..$ host: chr "localhost" [22:16:49.109] | : . ' .. ..$ rank: num 0 [22:16:49.109] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.109] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.114] | : . ' Evaluator formal arguments: [n=1] [22:16:49.115] | : . ' Dotted pair list of 1 [22:16:49.115] | : . ' $ workers:List of 1 [22:16:49.115] | : . ' ..$ :List of 3 [22:16:49.115] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:49.115] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:49.115] | : . ' .. ..$ host: chr "localhost" [22:16:49.115] | : . ' .. ..$ rank: num 0 [22:16:49.115] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.115] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.119] | : . ' Arguments passed to the future-backend factory: [n=1] [22:16:49.120] | : . ' List of 1 [22:16:49.120] | : . ' $ workers:List of 1 [22:16:49.120] | : . ' ..$ :List of 3 [22:16:49.120] | : . ' .. ..$ con : 'sockconn' int 4 [22:16:49.120] | : . ' .. .. ..- attr(*, "conn_id")= [22:16:49.120] | : . ' .. ..$ host: chr "localhost" [22:16:49.120] | : . ' .. ..$ rank: num 0 [22:16:49.120] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.120] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.124] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... [22:16:49.125] | : . ' , Stopping existing cluster ... [22:16:49.126] | : . ' , ; No pre-existing cluster. Skipping [22:16:49.126] | : . ' , Stopping existing cluster ... done [22:16:49.127] | : . ' , Number of workers: 1 [22:16:49.127] | : . ' , Generated workers UUID [22:16:49.128] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [22:16:49.129] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = FALSE) ... done [22:16:49.129] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [22:16:49.130] | : . makeFutureBackend() ... done [22:16:49.130] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [22:16:49.131] | : nbrOfWorkers(NULL) ... [22:16:49.131] | : . List of 4 [22:16:49.131] | : . $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:49.131] | : . $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:49.131] | : . $ : language nbrOfWorkers() [22:16:49.131] | : . $ : language nbrOfWorkers.NULL() [22:16:49.134] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.135] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.136] | : . Number of workers: 1 [22:16:49.136] | : nbrOfWorkers(NULL) ... done [22:16:49.137] | : plan(): nbrOfWorkers() = 1 [22:16:49.138] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:16:49.138] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.139] future(..., label = NULL) ... [22:16:49.139] | lazy: FALSE [22:16:49.140] | stdout: TRUE [22:16:49.140] | conditions: [n=1] 'condition' [22:16:49.141] | getGlobalsAndPackages() ... [22:16:49.141] | : Searching for globals ... [22:16:49.149] | : . globals found: [3] '[[', 'X', 'i' [22:16:49.149] | : Searching for globals ... done [22:16:49.150] | : Resolving globals: FALSE [22:16:49.150] | : Search for packages associated with the globals ... [22:16:49.151] | : . Packages associated with globals: [1] 'base' [22:16:49.152] | : . Packages: [1] 'base' [22:16:49.153] | : Search for packages associated with the globals ... done [22:16:49.153] | : Packages after dropping 'base': [0] [22:16:49.154] | : globals: [2] 'X', 'i' [22:16:49.154] | : packages: [0] [22:16:49.155] | getGlobalsAndPackages() ... done [22:16:49.156] | run() for 'Future' () ... [22:16:49.156] | : state: 'created' [22:16:49.157] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.158] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.158] | : Using ClusterFutureBackend ... [22:16:49.159] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:16:49.159] | : . Launching futures ... [22:16:49.160] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:49.161] | : . ' , Workers: [n=1] [22:16:49.161] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:49.162] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:49.163] | : . ' , requestWorker() ... [22:16:49.163] | : . ' , ; requestNode() ... [22:16:49.164] | : . ' , ; ` Number of workers: 1 [22:16:49.164] | : . ' , ; ` Polling for a free worker ... [22:16:49.165] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.166] | : . ' , ; ` | : Listing all futures [22:16:49.166] | : . ' , ; ` | : Number of registered futures: 0 [22:16:49.167] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.167] | : . ' , ; ` | Total time: 0 [22:16:49.168] | : . ' , ; ` Polling for a free worker ... done [22:16:49.168] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.169] | : . ' , ; ` | Listing all futures [22:16:49.169] | : . ' , ; ` | Number of registered futures: 0 [22:16:49.170] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.171] | : . ' , ; ` avail: [n=1] '1' [22:16:49.171] | : . ' , ; ` Index of first available worker: 1 [22:16:49.172] | : . ' , ; ` Validate that the worker is functional ... [22:16:49.172] | : . ' , ; ` | Connection is valid [22:16:49.173] | : . ' , ; ` | node of a socket cluster on host 'localhost' with pid 69252 [22:16:49.175] | : . ' , ; ` | Worker is functional [22:16:49.176] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.176] | : . ' , ; ` | : Listing all futures [22:16:49.177] | : . ' , ; ` | : Number of registered futures: 0 [22:16:49.177] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.178] | : . ' , ; ` Validate that the worker is functional ... done [22:16:49.178] | : . ' , ; requestNode() ... done [22:16:49.179] | : . ' , ; cluster node index: 1 [22:16:49.179] | : . ' , requestWorker() ... done [22:16:49.180] | : . ' , eraseGlobalEnvironment() ... [22:16:49.279] | : . ' , eraseGlobalEnvironment() ... done [22:16:49.279] | : . ' , launchFuture() ... [22:16:49.280] | : . ' , ; cluster node index: 1 [22:16:49.281] | : . ' , ; getFutureData() ... [22:16:49.281] | : . ' , ; ` getFutureCore() ... [22:16:49.281] | : . ' , ; ` | Packages needed by the future expression (n = 0): [22:16:49.282] | : . ' , ; ` getFutureCore() ... done [22:16:49.282] | : . ' , ; ` getFutureCapture() ... [22:16:49.283] | : . ' , ; ` getFutureCapture() ... done [22:16:49.283] | : . ' , ; ` getFutureContext() ... [22:16:49.284] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.285] | : . ' , ; ` | : Getting stack without first backend: [n=0] [22:16:49.285] | : . ' , ; ` | plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.286] | : . ' , ; ` | Packages needed by future backend (n = 0): [22:16:49.286] | : . ' , ; ` getFutureContext() ... done [22:16:49.287] | : . ' , ; getFutureData() ... done [22:16:49.288] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:49.288] | : . ' , ; ` Appended future to position #1 [22:16:49.289] | : . ' , ; ` Number of registered futures: 1 [22:16:49.289] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:49.290] | : . ' , launchFuture() ... done [22:16:49.290] | : . ' , ClusterFuture started [22:16:49.291] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:49.291] | : . Launching futures ... done [22:16:49.292] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:49.293] | : Using ClusterFutureBackend ... done [22:16:49.293] | run() for 'Future' () ... done [22:16:49.294] future(..., label = NULL) ... done [22:16:49.294] future(..., label = NULL) ... [22:16:49.295] | lazy: FALSE [22:16:49.295] | stdout: TRUE [22:16:49.296] | conditions: [n=1] 'condition' [22:16:49.296] | getGlobalsAndPackages() ... [22:16:49.297] | : Searching for globals ... [22:16:49.302] | : . globals found: [3] '[[', 'X', 'i' [22:16:49.302] | : Searching for globals ... done [22:16:49.303] | : Resolving globals: FALSE [22:16:49.303] | : Search for packages associated with the globals ... [22:16:49.304] | : . Packages associated with globals: [1] 'base' [22:16:49.305] | : . Packages: [1] 'base' [22:16:49.305] | : Search for packages associated with the globals ... done [22:16:49.306] | : Packages after dropping 'base': [0] [22:16:49.306] | : globals: [2] 'X', 'i' [22:16:49.307] | : packages: [0] [22:16:49.307] | getGlobalsAndPackages() ... done [22:16:49.308] | run() for 'Future' () ... [22:16:49.309] | : state: 'created' [22:16:49.310] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.310] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.311] | : Using ClusterFutureBackend ... [22:16:49.311] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [22:16:49.312] | : . Launching futures ... [22:16:49.312] | : . ' launchFuture() for ClusterFutureBackend ... [22:16:49.313] | : . ' , Workers: [n=1] [22:16:49.313] | : . ' , socket cluster with 1 nodes on host 'localhost' [22:16:49.314] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [22:16:49.314] | : . ' , requestWorker() ... [22:16:49.315] | : . ' , ; requestNode() ... [22:16:49.316] | : . ' , ; ` Number of workers: 1 [22:16:49.316] | : . ' , ; ` Polling for a free worker ... [22:16:49.317] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.317] | : . ' , ; ` | : Listing all futures [22:16:49.318] | : . ' , ; ` | : Number of registered futures: 1 [22:16:49.318] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.319] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [22:16:49.340] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [22:16:49.341] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [22:16:49.342] | : . ' , ; ` | : . resolved() ... [22:16:49.343] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... [22:16:49.344] | : . ' , ; ` | : . ' , Cluster node socket connection: index=4, id=225 [22:16:49.345] | : . ' , ; ` | : . ' , assertValidConnection() ... [22:16:49.345] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:49.346] | : . ' , ; ` | : . ' , assertValidConnection() ... done [22:16:49.360] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... [22:16:49.360] | : . ' , ; ` | : . ' , ; cluster node index: 1 [22:16:49.361] | : . ' , ; ` | : . ' , ; [1] TRUE [22:16:49.362] | : . ' , ; ` | : . ' , ; Received data: [22:16:49.362] | : . ' , ; ` | : . ' , ; List of 5 [22:16:49.362] | : . ' , ; ` | : . ' , ; $ type : chr "VALUE" [22:16:49.362] | : . ' , ; ` | : . ' , ; $ value :List of 16 [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ value : int 1 [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ visible : logi TRUE [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ stdout : chr "" [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ conditions : list() [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ rng : logi FALSE [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ seed : NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ misuseGlobalEnv : NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ misuseConnections :List of 3 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ added : NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ removed : NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ replaced: NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ misuseDevices : NULL [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ misuseDefaultDevice: list() [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "5" [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ session_uuid : chr "b7ccbf92df2f07d576b53c714effffcb" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..- attr(*, "source")=List of 5 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ host : Named chr "CRANWIN3" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ pid : int 69252 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ random: int 2147483647 [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ r_info :List of 4 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. .. ..$ : int [1:3] 4 6 0 [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ os : chr "windows" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ os_name : chr "Windows" [22:16:49.362] | : . ' , ; ` | : . ' , ; .. ..$ captures_utf8: logi TRUE [22:16:49.362] | : . ' , ; ` | : . ' , ; ..$ version : chr "1.8" [22:16:49.362] | : . ' , ; ` | : . ' , ; ..- attr(*, "class")= chr "FutureResult" [22:16:49.362] | : . ' , ; ` | : . ' , ; $ success: logi TRUE [22:16:49.362] | : . ' , ; ` | : . ' , ; $ time : 'proc_time' Named num [1:5] 0.08 0 0.08 NA NA [22:16:49.362] | : . ' , ; ` | : . ' , ; ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:49.362] | : . ' , ; ` | : . ' , ; $ tag : NULL [22:16:49.378] | : . ' , ; ` | : . ' , ; Received FutureResult [22:16:49.378] | : . ' , ; ` | : . ' , ; FutureResult: [22:16:49.378] | : . ' , ; ` | : . ' , ; value: 'integer' [22:16:49.378] | : . ' , ; ` | : . ' , ; visible: TRUE [22:16:49.378] | : . ' , ; ` | : . ' , ; stdout: character [22:16:49.378] | : . ' , ; ` | : . ' , ; conditions: [n = 0] [22:16:49.378] | : . ' , ; ` | : . ' , ; RNG used: FALSE [22:16:49.378] | : . ' , ; ` | : . ' , ; duration: 0.06911397 secs (started 2026-03-13 22:16:49.289444) [22:16:49.378] | : . ' , ; ` | : . ' , ; version: 1.8 [22:16:49.380] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:49.380] | : . ' , ; ` | : . ' , ; ` Removed future from position #1 [22:16:49.381] | : . ' , ; ` | : . ' , ; ` Number of registered futures: 0 [22:16:49.381] | : . ' , ; ` | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:49.382] | : . ' , ; ` | : . ' , ; Erased future from future backend [22:16:49.382] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... [22:16:49.383] | : . ' , ; ` | : . ' , ; ` result already collected: FutureResult [22:16:49.383] | : . ' , ; ` | : . ' , ; result() for ClusterFuture ... done [22:16:49.384] | : . ' , ; ` | : . ' , ; signalConditions() ... [22:16:49.384] | : . ' , ; ` | : . ' , ; ` include = 'immediateCondition' [22:16:49.385] | : . ' , ; ` | : . ' , ; ` exclude = [22:16:49.385] | : . ' , ; ` | : . ' , ; ` resignal = FALSE [22:16:49.386] | : . ' , ; ` | : . ' , ; ` Number of conditions: 0 [22:16:49.386] | : . ' , ; ` | : . ' , ; ` int 0 [22:16:49.387] | : . ' , ; ` | : . ' , ; signalConditions() ... done [22:16:49.388] | : . ' , ; ` | : . ' , receiveMessageFromWorker() for ClusterFuture ... done [22:16:49.388] | : . ' , ; ` | : . ' , receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:49.389] | : . ' , ; ` | : . ' resolved() for ClusterFuture () ... done [22:16:49.389] | : . ' , ; ` | : . ' assertFutureResult() ... [22:16:49.390] | : . ' , ; ` | : . ' , Future uuid: a0e2c2057a439d13cf08c3e3f94af757-5 [22:16:49.390] | : . ' , ; ` | : . ' , identical; success [22:16:49.391] | : . ' , ; ` | : . ' assertFutureResult() ... done [22:16:49.391] | : . ' , ; ` | : . resolved() ... done [22:16:49.392] | : . ' , ; ` | : . Future at position #1 is resolved ... [22:16:49.392] | : . ' , ; ` | : . ' value() for ClusterFuture () ... [22:16:49.393] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:49.393] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:49.394] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:49.394] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [22:16:49.395] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [22:16:49.395] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [22:16:49.396] | : . ' , ; ` | : . ' , signalConditions() ... [22:16:49.396] | : . ' , ; ` | : . ' , ; include = 'immediateCondition' [22:16:49.397] | : . ' , ; ` | : . ' , ; exclude = [22:16:49.397] | : . ' , ; ` | : . ' , ; resignal = FALSE [22:16:49.398] | : . ' , ; ` | : . ' , ; Number of conditions: 0 [22:16:49.398] | : . ' , ; ` | : . ' , ; int 0 [22:16:49.399] | : . ' , ; ` | : . ' , signalConditions() ... done [22:16:49.399] | : . ' , ; ` | : . ' , check for misuse ... [22:16:49.400] | : . ' , ; ` | : . ' , check for misuse ... done [22:16:49.400] | : . ' , ; ` | : . ' value() for ClusterFuture () ... done [22:16:49.401] | : . ' , ; ` | : . Future at position #1 is resolved ... done [22:16:49.402] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [22:16:49.402] | : . ' , ; ` | : Remove collected futures ... [22:16:49.403] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [22:16:49.403] | : . ' , ; ` | : Remove collected futures ... done [22:16:49.404] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [22:16:49.404] | : . ' , ; ` | Early signaling of 1 future candidates ... [22:16:49.405] | : . ' , ; ` | Number of futures with early signaling requested: 0 [22:16:49.405] | : . ' , ; ` | Early signaling of 1 future candidates ... done [22:16:49.406] | : . ' , ; ` | Number of registered futures: 1 [22:16:49.406] | : . ' , ; ` Polling for a free worker ... done [22:16:49.407] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.407] | : . ' , ; ` | Listing all futures [22:16:49.408] | : . ' , ; ` | Number of registered futures: 0 [22:16:49.408] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.409] | : . ' , ; ` Total time: 0.0900270938873291 [22:16:49.409] | : . ' , ; requestNode() ... done [22:16:49.410] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.410] | : . ' , ; ` Listing all futures [22:16:49.410] | : . ' , ; ` Number of registered futures: 0 [22:16:49.411] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.411] | : . ' , ; avail: [n=1] '1' [22:16:49.412] | : . ' , ; Index of first available worker: 1 [22:16:49.412] | : . ' , ; Validate that the worker is functional ... [22:16:49.413] | : . ' , ; ` Connection is valid [22:16:49.413] | : . ' , ; ` node of a socket cluster on host 'localhost' with pid 69252 [22:16:49.415] | : . ' , ; ` Worker is functional [22:16:49.416] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [22:16:49.416] | : . ' , ; ` | Listing all futures [22:16:49.417] | : . ' , ; ` | Number of registered futures: 0 [22:16:49.417] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [22:16:49.418] | : . ' , ; Validate that the worker is functional ... done [22:16:49.418] | : . ' , requestWorker() ... done [22:16:49.419] | : . ' , cluster node index: 1 [22:16:49.419] | : . ' launchFuture() for ClusterFutureBackend ... done [22:16:49.420] | : . ' eraseGlobalEnvironment() ... [22:16:49.420] | : . ' eraseGlobalEnvironment() ... done [22:16:49.421] | : . ' launchFuture() ... [22:16:49.421] | : . ' , cluster node index: 1 [22:16:49.422] | : . ' , getFutureData() ... [22:16:49.422] | : . ' , ; getFutureCore() ... [22:16:49.423] | : . ' , ; ` Packages needed by the future expression (n = 0): [22:16:49.423] | : . ' , ; getFutureCore() ... done [22:16:49.424] | : . ' , ; getFutureCapture() ... [22:16:49.424] | : . ' , ; getFutureCapture() ... done [22:16:49.425] | : . ' , ; getFutureContext() ... [22:16:49.425] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.426] | : . ' , ; ` | Getting stack without first backend: [n=0] [22:16:49.426] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.427] | : . ' , ; ` Packages needed by future backend (n = 0): [22:16:49.427] | : . ' , ; getFutureContext() ... done [22:16:49.428] | : . ' , getFutureData() ... done [22:16:49.429] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [22:16:49.429] | : . ' , ; Appended future to position #1 [22:16:49.430] | : . ' , ; Number of registered futures: 1 [22:16:49.430] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [22:16:49.431] | : . ' launchFuture() ... done [22:16:49.431] | : . ' ClusterFuture started [22:16:49.432] | : . Launching futures ... done [22:16:49.432] | : Using ClusterFutureBackend ... done [22:16:49.433] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:16:49.433] | run() for 'Future' () ... done [22:16:49.434] future(..., label = NULL) ... done [22:16:49.435] value() for list ... [22:16:49.435] | recursive: 0 [22:16:49.436] | reduce: FALSE [22:16:49.437] | length: 2 [22:16:49.438] | Number of remaining objects: 2 [22:16:49.438] | checking value #1: [22:16:49.439] | 'obj' is ClusterFuture [22:16:49.439] | resolved() ... [22:16:49.440] | resolved() ... done [22:16:49.440] | ClusterFuture #1 [22:16:49.441] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:49.441] | : nx: 2 [22:16:49.442] | : relay: TRUE [22:16:49.442] | : stdout: TRUE [22:16:49.442] | : signal: TRUE [22:16:49.443] | : resignal: FALSE [22:16:49.443] | : force: TRUE [22:16:49.444] | : relayed: [n=2] FALSE, FALSE [22:16:49.444] | : queued futures: [n=2] FALSE, FALSE [22:16:49.445] | : until=1 [22:16:49.445] | : relaying element #1 [22:16:49.446] | : value() for ClusterFuture () ... [22:16:49.446] | : . result() for ClusterFuture ... [22:16:49.447] | : . ' result already collected: FutureResult [22:16:49.447] | : . result() for ClusterFuture ... done [22:16:49.448] | : . result() for ClusterFuture ... [22:16:49.448] | : . ' result already collected: FutureResult [22:16:49.449] | : . result() for ClusterFuture ... done [22:16:49.449] | : . signalConditions() ... [22:16:49.450] | : . ' include = 'immediateCondition' [22:16:49.450] | : . ' exclude = [22:16:49.451] | : . ' resignal = FALSE [22:16:49.451] | : . ' Number of conditions: 0 [22:16:49.452] | : . ' int 0 [22:16:49.453] | : . signalConditions() ... done [22:16:49.453] | : . relay stdout ... [22:16:49.454] | : . relay stdout ... done [22:16:49.454] | : . check for misuse ... [22:16:49.455] | : . check for misuse ... done [22:16:49.455] | : value() for ClusterFuture () ... done [22:16:49.456] | : result() for ClusterFuture ... [22:16:49.456] | : . result already collected: FutureResult [22:16:49.457] | : result() for ClusterFuture ... done [22:16:49.457] | : signalConditions() ... [22:16:49.458] | : . include = 'immediateCondition' [22:16:49.458] | : . exclude = [22:16:49.459] | : . resignal = FALSE [22:16:49.459] | : . Number of conditions: 0 [22:16:49.460] | : . int 0 [22:16:49.460] | : signalConditions() ... done [22:16:49.464] | : result() for ClusterFuture ... [22:16:49.465] | : . result already collected: FutureResult [22:16:49.465] | : result() for ClusterFuture ... done [22:16:49.466] | : signalConditions() ... [22:16:49.466] | : . include = 'condition' [22:16:49.467] | : . exclude = 'error', 'immediateCondition' [22:16:49.467] | : . resignal = TRUE [22:16:49.468] | : . Number of conditions: 0 [22:16:49.468] | : . int 0 [22:16:49.469] | : signalConditions() ... done [22:16:49.470] | : relayed: [n=2] TRUE, FALSE [22:16:49.470] | : queued futures: [n=2] TRUE, FALSE [22:16:49.471] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:49.471] | value(, ...) ... [22:16:49.472] | : value() for ClusterFuture () ... [22:16:49.472] | : . result() for ClusterFuture ... [22:16:49.472] | : . ' result already collected: FutureResult [22:16:49.473] | : . result() for ClusterFuture ... done [22:16:49.474] | : . result() for ClusterFuture ... [22:16:49.474] | : . ' result already collected: FutureResult [22:16:49.474] | : . result() for ClusterFuture ... done [22:16:49.475] | : . signalConditions() ... [22:16:49.475] | : . ' include = 'immediateCondition' [22:16:49.476] | : . ' exclude = [22:16:49.476] | : . ' resignal = FALSE [22:16:49.477] | : . ' Number of conditions: 0 [22:16:49.477] | : . ' int 0 [22:16:49.478] | : . signalConditions() ... done [22:16:49.479] | : . check for misuse ... [22:16:49.479] | : . check for misuse ... done [22:16:49.480] | : value() for ClusterFuture () ... done [22:16:49.480] | : value: [22:16:49.481] | value(, ...) ... done [22:16:49.481] | signalConditionsASAP(ClusterFuture, pos=1) ... [22:16:49.482] | : nx: 2 [22:16:49.482] | : relay: TRUE [22:16:49.483] | : stdout: TRUE [22:16:49.483] | : signal: TRUE [22:16:49.484] | : resignal: FALSE [22:16:49.484] | : force: TRUE [22:16:49.485] | : relayed: [n=2] TRUE, FALSE [22:16:49.485] | : queued futures: [n=2] TRUE, FALSE [22:16:49.486] | : until=2 [22:16:49.486] | : relaying element #2 [22:16:49.486] | : relayed: [n=2] TRUE, FALSE [22:16:49.487] | : queued futures: [n=2] TRUE, FALSE [22:16:49.487] | signalConditionsASAP(ClusterFuture, pos=1) ... done [22:16:49.488] | resolve() for ClusterFuture ... [22:16:49.488] | resolve() for ClusterFuture ... done [22:16:49.489] | length: 1 (resolved future 1) [22:16:49.489] | checking value #2: [22:16:49.490] | 'obj' is ClusterFuture [22:16:49.490] | resolved() ... [22:16:49.491] | : resolved() for ClusterFuture () ... [22:16:49.492] | : . Cluster node socket connection: index=4, id=225 [22:16:49.492] | : . assertValidConnection() ... [22:16:49.493] | : . ' cluster node index: 1 [22:16:49.494] | : . assertValidConnection() ... done [22:16:49.494] | : . receiveMessageFromWorker() for ClusterFuture ... [22:16:49.495] | : . ' cluster node index: 1 [22:16:49.495] | : . ' [1] TRUE [22:16:49.496] | : . ' Received data: [22:16:49.496] | : . ' List of 5 [22:16:49.496] | : . ' $ type : chr "VALUE" [22:16:49.496] | : . ' $ value :List of 16 [22:16:49.496] | : . ' ..$ value : int 2 [22:16:49.496] | : . ' ..$ visible : logi TRUE [22:16:49.496] | : . ' ..$ stdout : chr "" [22:16:49.496] | : . ' ..$ conditions : list() [22:16:49.496] | : . ' ..$ rng : logi FALSE [22:16:49.496] | : . ' ..$ seed : NULL [22:16:49.496] | : . ' ..$ misuseGlobalEnv : NULL [22:16:49.496] | : . ' ..$ misuseConnections :List of 3 [22:16:49.496] | : . ' .. ..$ added : NULL [22:16:49.496] | : . ' .. ..$ removed : NULL [22:16:49.496] | : . ' .. ..$ replaced: NULL [22:16:49.496] | : . ' ..$ misuseDevices : NULL [22:16:49.496] | : . ' ..$ misuseDefaultDevice: list() [22:16:49.496] | : . ' ..$ started : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.496] | : . ' ..$ finished : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.496] | : . ' ..$ uuid : chr [1:2] "a0e2c2057a439d13cf08c3e3f94af757" "6" [22:16:49.496] | : . ' ..$ session_uuid : chr "b7ccbf92df2f07d576b53c714effffcb" [22:16:49.496] | : . ' .. ..- attr(*, "source")=List of 5 [22:16:49.496] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [22:16:49.496] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:16:49.496] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:16:49.496] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:16:49.496] | : . ' .. .. ..$ pid : int 69252 [22:16:49.496] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:16:49" [22:16:49.496] | : . ' .. .. ..$ random: int 2147483647 [22:16:49.496] | : . ' ..$ r_info :List of 4 [22:16:49.496] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:16:49.496] | : . ' .. .. ..$ : int [1:3] 4 6 0 [22:16:49.496] | : . ' .. ..$ os : chr "windows" [22:16:49.496] | : . ' .. ..$ os_name : chr "Windows" [22:16:49.496] | : . ' .. ..$ captures_utf8: logi TRUE [22:16:49.496] | : . ' ..$ version : chr "1.8" [22:16:49.496] | : . ' ..- attr(*, "class")= chr "FutureResult" [22:16:49.496] | : . ' $ success: logi TRUE [22:16:49.496] | : . ' $ time : 'proc_time' Named num [1:5] 0.01 0 0.02 NA NA [22:16:49.496] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:16:49.496] | : . ' $ tag : NULL [22:16:49.512] | : . ' Received FutureResult [22:16:49.513] | : . ' FutureResult: [22:16:49.513] | : . ' value: 'integer' [22:16:49.513] | : . ' visible: TRUE [22:16:49.513] | : . ' stdout: character [22:16:49.513] | : . ' conditions: [n = 0] [22:16:49.513] | : . ' RNG used: FALSE [22:16:49.513] | : . ' duration: 0.005016804 secs (started 2026-03-13 22:16:49.429871) [22:16:49.513] | : . ' version: 1.8 [22:16:49.514] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [22:16:49.514] | : . ' , Removed future from position #1 [22:16:49.515] | : . ' , Number of registered futures: 0 [22:16:49.515] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [22:16:49.516] | : . ' Erased future from future backend [22:16:49.516] | : . ' result() for ClusterFuture ... [22:16:49.517] | : . ' , result already collected: FutureResult [22:16:49.517] | : . ' result() for ClusterFuture ... done [22:16:49.518] | : . ' signalConditions() ... [22:16:49.518] | : . ' , include = 'immediateCondition' [22:16:49.519] | : . ' , exclude = [22:16:49.519] | : . ' , resignal = FALSE [22:16:49.520] | : . ' , Number of conditions: 0 [22:16:49.520] | : . ' , int 0 [22:16:49.521] | : . ' signalConditions() ... done [22:16:49.522] | : . receiveMessageFromWorker() for ClusterFuture ... done [22:16:49.522] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [22:16:49.523] | : resolved() for ClusterFuture () ... done [22:16:49.523] | : assertFutureResult() ... [22:16:49.524] | : . Future uuid: a0e2c2057a439d13cf08c3e3f94af757-6 [22:16:49.524] | : . identical; success [22:16:49.525] | : assertFutureResult() ... done [22:16:49.525] | resolved() ... done [22:16:49.526] | ClusterFuture #2 [22:16:49.526] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:49.526] | : nx: 2 [22:16:49.527] | : relay: TRUE [22:16:49.527] | : stdout: TRUE [22:16:49.528] | : signal: TRUE [22:16:49.528] | : resignal: FALSE [22:16:49.529] | : force: TRUE [22:16:49.529] | : relayed: [n=2] TRUE, FALSE [22:16:49.530] | : queued futures: [n=2] TRUE, FALSE [22:16:49.530] | : until=2 [22:16:49.531] | : relaying element #2 [22:16:49.531] | : value() for ClusterFuture () ... [22:16:49.532] | : . result() for ClusterFuture ... [22:16:49.532] | : . ' result already collected: FutureResult [22:16:49.533] | : . result() for ClusterFuture ... done [22:16:49.533] | : . result() for ClusterFuture ... [22:16:49.534] | : . ' result already collected: FutureResult [22:16:49.534] | : . result() for ClusterFuture ... done [22:16:49.535] | : . signalConditions() ... [22:16:49.535] | : . ' include = 'immediateCondition' [22:16:49.536] | : . ' exclude = [22:16:49.536] | : . ' resignal = FALSE [22:16:49.537] | : . ' Number of conditions: 0 [22:16:49.537] | : . ' int 0 [22:16:49.538] | : . signalConditions() ... done [22:16:49.538] | : . relay stdout ... [22:16:49.539] | : . relay stdout ... done [22:16:49.539] | : . check for misuse ... [22:16:49.540] | : . check for misuse ... done [22:16:49.540] | : value() for ClusterFuture () ... done [22:16:49.541] | : result() for ClusterFuture ... [22:16:49.541] | : . result already collected: FutureResult [22:16:49.542] | : result() for ClusterFuture ... done [22:16:49.542] | : signalConditions() ... [22:16:49.543] | : . include = 'immediateCondition' [22:16:49.543] | : . exclude = [22:16:49.544] | : . resignal = FALSE [22:16:49.544] | : . Number of conditions: 0 [22:16:49.545] | : . int 0 [22:16:49.546] | : signalConditions() ... done [22:16:49.546] | : result() for ClusterFuture ... [22:16:49.547] | : . result already collected: FutureResult [22:16:49.547] | : result() for ClusterFuture ... done [22:16:49.548] | : signalConditions() ... [22:16:49.548] | : . include = 'condition' [22:16:49.549] | : . exclude = 'error', 'immediateCondition' [22:16:49.549] | : . resignal = TRUE [22:16:49.550] | : . Number of conditions: 0 [22:16:49.550] | : . int 0 [22:16:49.551] | : signalConditions() ... done [22:16:49.552] | : relayed: [n=2] TRUE, TRUE [22:16:49.552] | : queued futures: [n=2] TRUE, TRUE [22:16:49.553] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:49.553] | value(, ...) ... [22:16:49.554] | : value() for ClusterFuture () ... [22:16:49.554] | : . result() for ClusterFuture ... [22:16:49.555] | : . ' result already collected: FutureResult [22:16:49.555] | : . result() for ClusterFuture ... done [22:16:49.556] | : . result() for ClusterFuture ... [22:16:49.556] | : . ' result already collected: FutureResult [22:16:49.557] | : . result() for ClusterFuture ... done [22:16:49.557] | : . signalConditions() ... [22:16:49.557] | : . ' include = 'immediateCondition' [22:16:49.558] | : . ' exclude = [22:16:49.558] | : . ' resignal = FALSE [22:16:49.559] | : . ' Number of conditions: 0 [22:16:49.559] | : . ' int 0 [22:16:49.560] | : . signalConditions() ... done [22:16:49.561] | : . check for misuse ... [22:16:49.561] | : . check for misuse ... done [22:16:49.562] | : value() for ClusterFuture () ... done [22:16:49.562] | : value: [22:16:49.563] | value(, ...) ... done [22:16:49.563] | signalConditionsASAP(ClusterFuture, pos=2) ... [22:16:49.564] | : nx: 2 [22:16:49.564] | : relay: TRUE [22:16:49.565] | : stdout: TRUE [22:16:49.565] | : signal: TRUE [22:16:49.566] | : resignal: FALSE [22:16:49.566] | : force: TRUE [22:16:49.567] | : relayed: [n=2] TRUE, TRUE [22:16:49.567] | : queued futures: [n=2] TRUE, TRUE [22:16:49.568] | : until=2 [22:16:49.568] | : relayed: [n=2] TRUE, TRUE [22:16:49.569] | : queued futures: [n=2] TRUE, TRUE [22:16:49.569] | signalConditionsASAP(ClusterFuture, pos=2) ... done [22:16:49.570] | resolve() for ClusterFuture ... [22:16:49.570] | resolve() for ClusterFuture ... done [22:16:49.571] | length: 0 (resolved future 2) [22:16:49.571] | Relaying remaining futures ... [22:16:49.572] | : signalConditionsASAP(NULL, pos=0) ... [22:16:49.572] | : . nx: 2 [22:16:49.573] | : . relay: TRUE [22:16:49.573] | : . stdout: TRUE [22:16:49.573] | : . signal: TRUE [22:16:49.574] | : . resignal: FALSE [22:16:49.574] | : . force: TRUE [22:16:49.575] | : . relayed: [n=2] TRUE, TRUE [22:16:49.575] | : . queued futures: [n=2] TRUE, TRUE - flush all [22:16:49.576] | : . relayed: [n=2] TRUE, TRUE [22:16:49.576] | : . queued futures: [n=2] TRUE, TRUE [22:16:49.577] | : signalConditionsASAP(NULL, pos=0) ... done [22:16:49.577] | Relaying remaining futures ... done [22:16:49.578] value() for list ... done > plan(sequential) [22:16:49.586] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.586] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:16:49.587] | : all.equal() for FutureStrategyList ... [22:16:49.587] | : . New stack: [22:16:49.588] | : . List of 1 [22:16:49.588] | : . $ :function (..., envir = parent.frame()) [22:16:49.588] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:49.588] | : . ..- attr(*, "init")= logi TRUE [22:16:49.588] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:49.588] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.588] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.588] | : . ..- attr(*, "call")= language plan(sequential) [22:16:49.588] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.592] | : . Old stack: [22:16:49.592] | : . List of 1 [22:16:49.592] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.592] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.592] | : . ..- attr(*, "init")= chr "done" [22:16:49.592] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.592] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.592] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.592] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.592] | : . ..- attr(*, "tweaks")=List of 1 [22:16:49.592] | : . .. ..$ workers:List of 1 [22:16:49.592] | : . .. .. ..$ :List of 3 [22:16:49.592] | : . .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.592] | : . .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.592] | : . .. .. .. ..$ host: chr "localhost" [22:16:49.592] | : . .. .. .. ..$ rank: num 0 [22:16:49.592] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.592] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.592] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.592] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:49.592] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.592] | : . earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.592] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.592] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.600] | : . Not identical [22:16:49.600] | : . all.equal() for future ... [22:16:49.601] | : . ' List of 2 [22:16:49.601] | : . ' $ target :function (..., envir = parent.frame()) [22:16:49.601] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:49.601] | : . ' ..- attr(*, "init")= logi TRUE [22:16:49.601] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:49.601] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.601] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.601] | : . ' ..- attr(*, "call")= language plan(sequential) [22:16:49.601] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0))) [22:16:49.601] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [22:16:49.601] | : . ' ..- attr(*, "init")= chr "done" [22:16:49.601] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.601] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.601] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.601] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.601] | : . ' ..- attr(*, "tweaks")=List of 1 [22:16:49.601] | : . ' .. ..$ workers:List of 1 [22:16:49.601] | : . ' .. .. ..$ :List of 3 [22:16:49.601] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [22:16:49.601] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [22:16:49.601] | : . ' .. .. .. ..$ host: chr "localhost" [22:16:49.601] | : . ' .. .. .. ..$ rank: num 0 [22:16:49.601] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [22:16:49.601] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [22:16:49.601] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [22:16:49.601] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:16:49.601] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [22:16:49.601] | : . ' earlySignal = FALSE, interrupts = FALSE, persistent = FALSE, ...) [22:16:49.601] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [22:16:49.611] | : . ' Formals differ [22:16:49.611] | : . all.equal() for future ... done [22:16:49.612] | : . Future strategies differ at level 1 [22:16:49.612] | : all.equal() for FutureStrategyList ... done [22:16:49.613] | : plan(): Setting new future backend stack: [22:16:49.613] | : List of future strategies: [22:16:49.613] | : 1. sequential: [22:16:49.613] | : - args: function (..., envir = parent.frame(), workers = "") [22:16:49.613] | : - tweaked: FALSE [22:16:49.613] | : - call: plan(sequential) [22:16:49.614] | : List of 1 [22:16:49.614] | : $ :function (..., envir = parent.frame()) [22:16:49.614] | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:49.614] | : ..- attr(*, "init")= logi TRUE [22:16:49.614] | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:49.614] | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.614] | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.614] | : ..- attr(*, "call")= language plan(sequential) [22:16:49.614] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:16:49.618] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [22:16:49.618] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.619] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [22:16:49.619] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.620] | : . stopWorkers() for ClusterFutureBackend ... [22:16:49.620] | : . ' Interrupt active futures ... [22:16:49.621] | : . ' , Number of futures: 0 [22:16:49.622] | : . ' Interrupt active futures ... done [22:16:49.622] | : . ' Clear future registry ... [22:16:49.623] | : . ' Clear future registry ... done [22:16:49.624] | : . ' Stop cluster workers ... [22:16:49.624] | : . ' , Stopping existing cluster ... [22:16:49.625] | : . ' , ; No pre-existing cluster. Skipping [22:16:49.625] | : . ' , Stopping existing cluster ... done [22:16:49.626] | : . ' Stop cluster workers ... done [22:16:49.626] | : . stopWorkers() for ClusterFutureBackend ... done [22:16:49.626] | : . Legacy shutdown of cluster workers ... [22:16:49.627] | : . ' Stopping existing cluster ... [22:16:49.627] | : . ' , No pre-existing cluster. Skipping [22:16:49.628] | : . ' Stopping existing cluster ... done [22:16:49.628] | : . Legacy shutdown of cluster workers ... done [22:16:49.629] | : plan(): plan_cleanup(<'tweaked', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [22:16:49.629] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [22:16:49.630] | : . function (..., envir = parent.frame()) [22:16:49.630] | : . - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:16:49.630] | : . - attr(*, "init")= logi TRUE [22:16:49.630] | : . - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:16:49.630] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.630] | : . - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:16:49.630] | : . - attr(*, "call")= language plan(sequential) [22:16:49.633] | : . init: TRUE [22:16:49.633] | : . makeFutureBackend() ... [22:16:49.634] | : . ' Backend function: <'NULL'> [22:16:49.634] | : . ' Evaluator tweak arguments: [n=0] [22:16:49.635] | : . ' list() [22:16:49.636] | : . ' Evaluator formal arguments: [n=1] [22:16:49.636] | : . ' Dotted pair list of 1 [22:16:49.636] | : . ' $ envir: language parent.frame() [22:16:49.637] | : . ' Arguments passed to the future-backend factory: [n=1] [22:16:49.638] | : . ' List of 1 [22:16:49.638] | : . ' $ envir: language parent.frame() [22:16:49.639] | : . ' Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [22:16:49.639] | : . makeFutureBackend() ... done [22:16:49.640] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [22:16:49.640] | : nbrOfWorkers(NULL) ... [22:16:49.641] | : . List of 4 [22:16:49.641] | : . $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:16:49.641] | : . $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:16:49.641] | : . $ : language nbrOfWorkers() [22:16:49.641] | : . $ : language nbrOfWorkers.NULL() [22:16:49.643] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:16:49.644] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:16:49.644] | : . Number of workers: 1 [22:16:49.645] | : nbrOfWorkers(NULL) ... done [22:16:49.645] | : plan(): nbrOfWorkers() = 1 [22:16:49.646] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:16:49.646] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done > message("all.equal() for cluster backend ... done") all.equal() for cluster backend ... 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=1s, sys.self=0.2s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'cluster-connection-clashes' ... success > > proc.time() user system elapsed 1.57 0.32 2.82