R Under development (unstable) (2025-05-08 r88190 ucrt) -- "Unsuffered Consequences" Copyright (C) 2025 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. > ## 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' ... Sourcing 9 prologue scripts ... 01/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/001.load.R' 02/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/002.record-state.R' 03/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/030.imports.R' 04/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/050.utils.R' 05/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/090.context.R' 06/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/090.options.R' 07/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/091.envvars.R' 08/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/099.future-setup.R' 09/09 prologue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_prologue/995.detrius-connections.R' Sourcing 9 prologue scripts ... done Running test script: 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/test-cluster-connection-clashes.R' > library(future) [01:56:46.439] Option 'future.startup.script': TRUE [01:56:46.440] Future startup scripts considered: '.future.R', '~/.future.R' [01:56:46.440] 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 [01:56:46.625] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.626] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [01:56:46.626] | : all.equal() for FutureStrategyList ... [01:56:46.627] | : . New stack: [01:56:46.627] | : . List of 1 [01:56:46.627] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:46.627] | : . envir = parent.frame()) [01:56:46.627] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:46.627] | : . ..- attr(*, "init")= logi TRUE [01:56:46.627] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:46.627] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:46.627] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.627] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.627] | : . ..- attr(*, "tweaks")=List of 1 [01:56:46.627] | : . .. ..$ workers:List of 1 [01:56:46.627] | : . .. .. ..$ :List of 3 [01:56:46.627] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:46.627] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:46.627] | : . .. .. .. ..$ host: chr "localhost" [01:56:46.627] | : . .. .. .. ..$ rank: num 0 [01:56:46.627] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.627] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.627] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:46.627] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:46.639] | : . Old stack: [01:56:46.639] | : . List of 1 [01:56:46.639] | : . $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:46.639] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:46.639] | : . ..- attr(*, "init")= logi TRUE [01:56:46.639] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:46.639] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:46.639] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:46.639] | : . ..- attr(*, "call")= language plan(sequential) [01:56:46.639] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:46.642] | : . Not identical [01:56:46.643] | : . all.equal() for future ... [01:56:46.643] | : . ' List of 2 [01:56:46.643] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:46.643] | : . ' envir = parent.frame()) [01:56:46.643] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:46.643] | : . ' ..- attr(*, "init")= logi TRUE [01:56:46.643] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:46.643] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:46.643] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.643] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.643] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:46.643] | : . ' .. ..$ workers:List of 1 [01:56:46.643] | : . ' .. .. ..$ :List of 3 [01:56:46.643] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:46.643] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:46.643] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:46.643] | : . ' .. .. .. ..$ rank: num 0 [01:56:46.643] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.643] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.643] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:46.643] | : . ' $ current:function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:46.643] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:46.643] | : . ' ..- attr(*, "init")= logi TRUE [01:56:46.643] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:46.643] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:46.643] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:46.643] | : . ' ..- attr(*, "call")= language plan(sequential) [01:56:46.652] | : . ' Formals differ [01:56:46.652] | : . all.equal() for future ... done [01:56:46.652] | : . Future strategies differ at level 1 [01:56:46.653] | : all.equal() for FutureStrategyList ... done [01:56:46.653] | : plan(): Setting new future backend stack: [01:56:46.653] | : List of future strategies: [01:56:46.653] | : 1. cluster: [01:56:46.653] | : - args: function (..., workers = "", envir = parent.frame()) [01:56:46.653] | : - tweaked: TRUE [01:56:46.653] | : - call: plan(cluster, workers = cl) [01:56:46.654] | : List of 1 [01:56:46.654] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:46.654] | : envir = parent.frame()) [01:56:46.654] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:46.654] | : ..- attr(*, "init")= logi TRUE [01:56:46.654] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:46.654] | : earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:46.654] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.654] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.654] | : ..- attr(*, "tweaks")=List of 1 [01:56:46.654] | : .. ..$ workers:List of 1 [01:56:46.654] | : .. .. ..$ :List of 3 [01:56:46.654] | : .. .. .. ..$ con : 'sockconn' int 4 [01:56:46.654] | : .. .. .. .. ..- attr(*, "conn_id")= [01:56:46.654] | : .. .. .. ..$ host: chr "localhost" [01:56:46.654] | : .. .. .. ..$ rank: num 0 [01:56:46.654] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.654] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.654] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:46.654] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:46.662] | : plan(): plan_cleanup('sequential', 'uniprocess', 'future', 'function', cleanup = NA) ... [01:56:46.662] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.663] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [01:56:46.663] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.663] | : . Legacy shutdown of cluster workers ... [01:56:46.663] | : . ' Stopping existing cluster ... [01:56:46.664] | : . ' , No pre-existing cluster. Skipping [01:56:46.664] | : . ' Stopping existing cluster ... done [01:56:46.664] | : . Legacy shutdown of cluster workers ... done [01:56:46.664] | : plan(): plan_cleanup('sequential', 'uniprocess', 'future', 'function', cleanup = NA) ... done [01:56:46.664] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [01:56:46.664] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:46.664] | : . envir = parent.frame()) [01:56:46.664] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:46.664] | : . - attr(*, "init")= logi TRUE [01:56:46.664] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:46.664] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:46.664] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.664] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:46.664] | : . - attr(*, "tweaks")=List of 1 [01:56:46.664] | : . ..$ workers:List of 1 [01:56:46.664] | : . .. ..$ :List of 3 [01:56:46.664] | : . .. .. ..$ con : 'sockconn' int 4 [01:56:46.664] | : . .. .. .. ..- attr(*, "conn_id")= [01:56:46.664] | : . .. .. ..$ host: chr "localhost" [01:56:46.664] | : . .. .. ..$ rank: num 0 [01:56:46.664] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.664] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.664] | : . - attr(*, "call")= language plan(cluster, workers = cl) [01:56:46.670] | : . init: TRUE [01:56:46.671] | : . makeFutureBackend() ... [01:56:46.671] | : . ' Backend function: <'NULL'> [01:56:46.671] | : . ' Evaluator tweak arguments: [n=1] [01:56:46.671] | : . ' List of 1 [01:56:46.671] | : . ' $ workers:List of 1 [01:56:46.671] | : . ' ..$ :List of 3 [01:56:46.671] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:46.671] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:46.671] | : . ' .. ..$ host: chr "localhost" [01:56:46.671] | : . ' .. ..$ rank: num 0 [01:56:46.671] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.671] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.675] | : . ' Evaluator formal arguments: [n=1] [01:56:46.675] | : . ' List of 1 [01:56:46.675] | : . ' $ workers:List of 1 [01:56:46.675] | : . ' ..$ :List of 3 [01:56:46.675] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:46.675] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:46.675] | : . ' .. ..$ host: chr "localhost" [01:56:46.675] | : . ' .. ..$ rank: num 0 [01:56:46.675] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.675] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.678] | : . ' Backend factory arguments: [n=1] [01:56:46.678] | : . ' Dotted pair list of 1 [01:56:46.678] | : . ' $ workers:List of 1 [01:56:46.678] | : . ' ..$ :List of 3 [01:56:46.678] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:46.678] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:46.678] | : . ' .. ..$ host: chr "localhost" [01:56:46.678] | : . ' .. ..$ rank: num 0 [01:56:46.678] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:46.678] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:46.681] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [01:56:46.682] | : . ' , Stopping existing cluster ... [01:56:46.682] | : . ' , ; No pre-existing cluster. Skipping [01:56:46.682] | : . ' , Stopping existing cluster ... done [01:56:46.682] | : . ' , Number of workers: 1 [01:56:46.683] | : . ' , Generated workers UUID [01:56:46.683] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [01:56:46.683] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [01:56:46.683] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [01:56:46.684] | : . makeFutureBackend() ... done [01:56:46.684] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [01:56:46.686] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.686] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.686] | : plan(): nbrOfWorkers() = 1 [01:56:46.686] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [01:56:46.686] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.686] future(..., label = NULL) ... [01:56:46.687] | lazy: FALSE [01:56:46.687] | stdout: TRUE [01:56:46.687] | conditions: [n=1] 'condition' [01:56:46.687] | gc: FALSE [01:56:46.687] | earlySignal: FALSE [01:56:46.688] | getGlobalsAndPackages() ... [01:56:46.688] | : Searching for globals ... [01:56:46.701] | : . globals found: [3] '[[', 'X', 'i' [01:56:46.701] | : Searching for globals ... done [01:56:46.702] | : Resolving globals: FALSE [01:56:46.702] | : Search for packages associated with the globals ... [01:56:46.702] | : . Packages associated with globals: [1] 'base' [01:56:46.703] | : . Packages: [1] 'base' [01:56:46.703] | : Search for packages associated with the globals ... done [01:56:46.703] | : Packages after dropping 'base': [0] [01:56:46.703] | : globals: [2] 'X', 'i' [01:56:46.704] | : packages: [0] [01:56:46.704] | getGlobalsAndPackages() ... done [01:56:46.705] | run() for 'Future' (NULL) ... [01:56:46.705] | : state: 'created' [01:56:46.705] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.705] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.705] | : Using ClusterFutureBackend ... [01:56:46.705] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [01:56:46.706] | : . Launching futures ... [01:56:46.706] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:46.706] | : . ' , Workers: [n=1] [01:56:46.707] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:46.707] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:46.707] | : . ' , requestWorker() ... [01:56:46.707] | : . ' , ; requestNode() ... [01:56:46.708] | : . ' , ; ` Number of workers: 1 [01:56:46.708] | : . ' , ; ` Polling for a free worker ... [01:56:46.708] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.708] | : . ' , ; ` | : Created empty registry 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:46.708] | : . ' , ; ` | : Listing all futures [01:56:46.708] | : . ' , ; ` | : Number of registered futures: 0 [01:56:46.709] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.709] | : . ' , ; ` | Total time: 0 [01:56:46.709] | : . ' , ; ` Polling for a free worker ... done [01:56:46.709] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.709] | : . ' , ; ` | Listing all futures [01:56:46.709] | : . ' , ; ` | Number of registered futures: 0 [01:56:46.710] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.710] | : . ' , ; ` avail: [n=1] '1' [01:56:46.710] | : . ' , ; ` Index of first available worker: 1 [01:56:46.710] | : . ' , ; ` Validate that the worker is functional ... [01:56:46.804] | : . ' , ; ` | Worker is functional [01:56:46.804] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.805] | : . ' , ; ` | : Listing all futures [01:56:46.805] | : . ' , ; ` | : Number of registered futures: 0 [01:56:46.805] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.805] | : . ' , ; ` Validate that the worker is functional ... done [01:56:46.805] | : . ' , ; requestNode() ... done [01:56:46.805] | : . ' , ; cluster node index: 1 [01:56:46.806] | : . ' , requestWorker() ... done [01:56:46.806] | : . ' , eraseGlobalEnvironment() ... [01:56:46.806] | : . ' , eraseGlobalEnvironment() ... done [01:56:46.807] | : . ' , launchFuture() ... [01:56:46.807] | : . ' , ; cluster node index: 1 [01:56:46.807] | : . ' , ; getFutureData() ... [01:56:46.807] | : . ' , ; ` getFutureCore() ... [01:56:46.807] | : . ' , ; ` | Packages needed by the future expression (n = 0): [01:56:46.808] | : . ' , ; ` getFutureCore() ... done [01:56:46.808] | : . ' , ; ` getFutureCapture() ... [01:56:46.808] | : . ' , ; ` getFutureCapture() ... done [01:56:46.808] | : . ' , ; ` getFutureContext() ... [01:56:46.809] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.809] | : . ' , ; ` | : Getting stack without first backend: [n=0] [01:56:46.809] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.809] | : . ' , ; ` | Packages needed by future backend (n = 0): [01:56:46.809] | : . ' , ; ` getFutureContext() ... done [01:56:46.809] | : . ' , ; getFutureData() ... done [01:56:46.810] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:46.811] | : . ' , ; ` Appended future to position #1 [01:56:46.811] | : . ' , ; ` Number of registered futures: 1 [01:56:46.811] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:46.811] | : . ' , launchFuture() ... done [01:56:46.811] | : . ' , ClusterFuture started [01:56:46.811] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:46.812] | : . Launching futures ... done [01:56:46.812] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:46.812] | : Using ClusterFutureBackend ... done [01:56:46.812] | run() for 'Future' (NULL) ... done [01:56:46.812] future(..., label = NULL) ... done [01:56:46.813] future(..., label = NULL) ... [01:56:46.813] | lazy: FALSE [01:56:46.813] | stdout: TRUE [01:56:46.813] | conditions: [n=1] 'condition' [01:56:46.813] | gc: FALSE [01:56:46.813] | earlySignal: FALSE [01:56:46.813] | getGlobalsAndPackages() ... [01:56:46.814] | : Searching for globals ... [01:56:46.819] | : . globals found: [3] '[[', 'X', 'i' [01:56:46.819] | : Searching for globals ... done [01:56:46.819] | : Resolving globals: FALSE [01:56:46.819] | : Search for packages associated with the globals ... [01:56:46.820] | : . Packages associated with globals: [1] 'base' [01:56:46.820] | : . Packages: [1] 'base' [01:56:46.820] | : Search for packages associated with the globals ... done [01:56:46.820] | : Packages after dropping 'base': [0] [01:56:46.821] | : globals: [2] 'X', 'i' [01:56:46.821] | : packages: [0] [01:56:46.821] | getGlobalsAndPackages() ... done [01:56:46.821] | run() for 'Future' (NULL) ... [01:56:46.822] | : state: 'created' [01:56:46.822] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.822] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.822] | : Using ClusterFutureBackend ... [01:56:46.822] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [01:56:46.822] | : . Launching futures ... [01:56:46.823] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:46.823] | : . ' , Workers: [n=1] [01:56:46.823] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:46.823] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:46.824] | : . ' , requestWorker() ... [01:56:46.824] | : . ' , ; requestNode() ... [01:56:46.824] | : . ' , ; ` Number of workers: 1 [01:56:46.824] | : . ' , ; ` Polling for a free worker ... [01:56:46.824] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.824] | : . ' , ; ` | : Listing all futures [01:56:46.825] | : . ' , ; ` | : Number of registered futures: 1 [01:56:46.825] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.825] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [01:56:46.837] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [01:56:46.865] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [01:56:46.865] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... [01:56:46.866] | : . ' , ; ` | : . ' Cluster node socket connection: index=4, id=139 [01:56:46.866] | : . ' , ; ` | : . ' assertValidConnection() ... [01:56:46.867] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:46.867] | : . ' , ; ` | : . ' assertValidConnection() ... done [01:56:46.869] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... [01:56:46.869] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:46.869] | : . ' , ; ` | : . ' , [1] TRUE [01:56:46.869] | : . ' , ; ` | : . ' , received data: [01:56:46.870] | : . ' , ; ` | : . ' , List of 5 [01:56:46.870] | : . ' , ; ` | : . ' , $ type : chr "VALUE" [01:56:46.870] | : . ' , ; ` | : . ' , $ value :List of 14 [01:56:46.870] | : . ' , ; ` | : . ' , ..$ value : int 1 [01:56:46.870] | : . ' , ; ` | : . ' , ..$ visible : logi TRUE [01:56:46.870] | : . ' , ; ` | : . ' , ..$ stdout : chr "" [01:56:46.870] | : . ' , ; ` | : . ' , ..$ conditions : list() [01:56:46.870] | : . ' , ; ` | : . ' , ..$ rng : logi FALSE [01:56:46.870] | : . ' , ; ` | : . ' , ..$ misuseGlobalEnv : NULL [01:56:46.870] | : . ' , ; ` | : . ' , ..$ misuseConnections:List of 3 [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ added : NULL [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ removed : NULL [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ replaced: NULL [01:56:46.870] | : . ' , ; ` | : . ' , ..$ misuseDevices : NULL [01:56:46.870] | : . ' , ; ` | : . ' , ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.870] | : . ' , ; ` | : . ' , ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.870] | : . ' , ; ` | : . ' , ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "1" [01:56:46.870] | : . ' , ; ` | : . ' , ..$ session_uuid : chr "f5c24d5d6f6bfe21974146f69432f935" [01:56:46.870] | : . ' , ; ` | : . ' , .. ..- attr(*, "source")=List of 5 [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ host : Named chr "CRANWIN3" [01:56:46.870] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:46.870] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ pid : int 198984 [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ random: int 2147483647 [01:56:46.870] | : . ' , ; ` | : . ' , ..$ r_info :List of 4 [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:46.870] | : . ' , ; ` | : . ' , .. .. ..$ : int [1:3] 4 6 0 [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ os : chr "windows" [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ os_name : chr "Windows" [01:56:46.870] | : . ' , ; ` | : . ' , .. ..$ captures_utf8: logi TRUE [01:56:46.870] | : . ' , ; ` | : . ' , ..$ version : chr "1.8" [01:56:46.870] | : . ' , ; ` | : . ' , ..- attr(*, "class")= chr "FutureResult" [01:56:46.870] | : . ' , ; ` | : . ' , $ success: logi TRUE [01:56:46.870] | : . ' , ; ` | : . ' , $ time : 'proc_time' Named num [1:5] 0.06 0 0.06 NA NA [01:56:46.870] | : . ' , ; ` | : . ' , ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:46.870] | : . ' , ; ` | : . ' , $ tag : NULL [01:56:46.883] | : . ' , ; ` | : . ' , Received FutureResult [01:56:46.883] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:46.909] | : . ' , ; ` | : . ' , ; Removed future from position #1 [01:56:46.909] | : . ' , ; ` | : . ' , ; Number of registered futures: 0 [01:56:46.909] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:46.910] | : . ' , ; ` | : . ' , Erased future from future backend [01:56:46.910] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:46.910] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:46.911] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:46.911] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... done [01:56:46.911] | : . ' , ; ` | : . ' receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:46.911] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... done [01:56:46.911] | : . ' , ; ` | : . assertFutureResult() ... [01:56:46.911] | : . ' , ; ` | : . ' Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:46.911] | : . ' , ; ` | : . ' Future uuid: 1 [01:56:46.912] | : . ' , ; ` | : . ' identical; success [01:56:46.912] | : . ' , ; ` | : . assertFutureResult() ... done [01:56:46.912] | : . ' , ; ` | : . Future at position #1 is resolved ... [01:56:46.913] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... [01:56:46.913] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:46.913] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:46.913] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:46.913] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:46.913] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:46.914] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:46.914] | : . ' , ; ` | : . ' , check for misuse ... [01:56:46.914] | : . ' , ; ` | : . ' , check for misuse ... done [01:56:46.914] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... done [01:56:46.914] | : . ' , ; ` | : . Future at position #1 is resolved ... done [01:56:46.915] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [01:56:46.915] | : . ' , ; ` | : Remove collected futures ... [01:56:46.915] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [01:56:46.915] | : . ' , ; ` | : Remove collected futures ... done [01:56:46.915] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [01:56:46.915] | : . ' , ; ` | Early signaling of 1 future candidates ... [01:56:46.916] | : . ' , ; ` | Number of futures with early signaling requested: 0 [01:56:46.916] | : . ' , ; ` | Early signaling of 1 future candidates ... done [01:56:46.916] | : . ' , ; ` | Number of registered futures: 1 [01:56:46.916] | : . ' , ; ` Polling for a free worker ... done [01:56:46.916] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.916] | : . ' , ; ` | Listing all futures [01:56:46.917] | : . ' , ; ` | Number of registered futures: 0 [01:56:46.917] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.917] | : . ' , ; ` Total time: 0.0919709205627441 [01:56:46.917] | : . ' , ; requestNode() ... done [01:56:46.917] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.917] | : . ' , ; ` Listing all futures [01:56:46.918] | : . ' , ; ` Number of registered futures: 0 [01:56:46.918] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.918] | : . ' , ; avail: [n=1] '1' [01:56:46.918] | : . ' , ; Index of first available worker: 1 [01:56:46.918] | : . ' , ; Validate that the worker is functional ... [01:56:46.919] | : . ' , ; ` Worker is functional [01:56:46.919] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:46.919] | : . ' , ; ` | Listing all futures [01:56:46.920] | : . ' , ; ` | Number of registered futures: 0 [01:56:46.920] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:46.920] | : . ' , ; Validate that the worker is functional ... done [01:56:46.920] | : . ' , requestWorker() ... done [01:56:46.920] | : . ' , cluster node index: 1 [01:56:46.920] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:46.921] | : . ' eraseGlobalEnvironment() ... [01:56:46.921] | : . ' eraseGlobalEnvironment() ... done [01:56:46.921] | : . ' launchFuture() ... [01:56:46.921] | : . ' , cluster node index: 1 [01:56:46.922] | : . ' , getFutureData() ... [01:56:46.922] | : . ' , ; getFutureCore() ... [01:56:46.922] | : . ' , ; ` Packages needed by the future expression (n = 0): [01:56:46.922] | : . ' , ; getFutureCore() ... done [01:56:46.922] | : . ' , ; getFutureCapture() ... [01:56:46.922] | : . ' , ; getFutureCapture() ... done [01:56:46.923] | : . ' , ; getFutureContext() ... [01:56:46.923] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:46.923] | : . ' , ; ` | Getting stack without first backend: [n=0] [01:56:46.923] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:46.923] | : . ' , ; ` Packages needed by future backend (n = 0): [01:56:46.923] | : . ' , ; getFutureContext() ... done [01:56:46.924] | : . ' , getFutureData() ... done [01:56:46.924] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:46.925] | : . ' , ; Appended future to position #1 [01:56:46.925] | : . ' , ; Number of registered futures: 1 [01:56:46.925] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:46.925] | : . ' launchFuture() ... done [01:56:46.925] | : . ' ClusterFuture started [01:56:46.925] | : . Launching futures ... done [01:56:46.926] | : Using ClusterFutureBackend ... done [01:56:46.926] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:46.926] | run() for 'Future' (NULL) ... done [01:56:46.926] future(..., label = NULL) ... done [01:56:46.927] value() for list ... [01:56:46.927] | recursive: 0 [01:56:46.927] | reduce: FALSE [01:56:46.928] | length: 2 [01:56:46.928] | Number of remaining objects: 2 [01:56:46.928] | checking value #1 ... [01:56:46.928] | : 'obj' is ClusterFuture [01:56:46.929] | : ClusterFuture #1 [01:56:46.929] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:46.929] | : . nx: 2 [01:56:46.929] | : . relay: TRUE [01:56:46.929] | : . stdout: TRUE [01:56:46.929] | : . signal: TRUE [01:56:46.929] | : . resignal: FALSE [01:56:46.930] | : . force: TRUE [01:56:46.930] | : . relayed: [n=2] FALSE, FALSE [01:56:46.930] | : . queued futures: [n=2] FALSE, FALSE [01:56:46.930] | : . until=1 [01:56:46.930] | : . relaying element #1 [01:56:46.930] | : . value() for ClusterFuture (NULL) ... [01:56:46.931] | : . ' result() for ClusterFuture ... [01:56:46.931] | : . ' , result already collected: FutureResult [01:56:46.931] | : . ' result() for ClusterFuture ... done [01:56:46.931] | : . ' result() for ClusterFuture ... [01:56:46.931] | : . ' , result already collected: FutureResult [01:56:46.932] | : . ' result() for ClusterFuture ... done [01:56:46.932] | : . ' relay stdout ... [01:56:46.932] | : . ' relay stdout ... done [01:56:46.932] | : . ' check for misuse ... [01:56:46.932] | : . ' check for misuse ... done [01:56:46.932] | : . value() for ClusterFuture (NULL) ... done [01:56:46.933] | : . result() for ClusterFuture ... [01:56:46.933] | : . ' result already collected: FutureResult [01:56:46.933] | : . result() for ClusterFuture ... done [01:56:46.933] | : . result() for ClusterFuture ... [01:56:46.933] | : . ' result already collected: FutureResult [01:56:46.934] | : . result() for ClusterFuture ... done [01:56:46.934] | : . relayed: [n=2] TRUE, FALSE [01:56:46.934] | : . queued futures: [n=2] TRUE, FALSE [01:56:46.934] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:46.934] | : value(, ...) ... [01:56:46.934] | : . value() for ClusterFuture (NULL) ... [01:56:46.935] | : . ' result() for ClusterFuture ... [01:56:46.935] | : . ' , result already collected: FutureResult [01:56:46.935] | : . ' result() for ClusterFuture ... done [01:56:46.935] | : . ' result() for ClusterFuture ... [01:56:46.935] | : . ' , result already collected: FutureResult [01:56:46.935] | : . ' result() for ClusterFuture ... done [01:56:46.936] | : . ' check for misuse ... [01:56:46.936] | : . ' check for misuse ... done [01:56:46.936] | : . value() for ClusterFuture (NULL) ... done [01:56:46.936] | : . value: [01:56:46.936] | : value(, ...) ... done [01:56:46.936] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:46.937] | : . nx: 2 [01:56:46.937] | : . relay: TRUE [01:56:46.937] | : . stdout: TRUE [01:56:46.937] | : . signal: TRUE [01:56:46.937] | : . resignal: FALSE [01:56:46.938] | : . force: TRUE [01:56:46.938] | : . relayed: [n=2] TRUE, FALSE [01:56:46.938] | : . queued futures: [n=2] TRUE, FALSE [01:56:46.938] | : . until=2 [01:56:46.938] | : . relaying element #2 [01:56:46.938] | : . relayed: [n=2] TRUE, FALSE [01:56:46.939] | : . queued futures: [n=2] TRUE, FALSE [01:56:46.939] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:46.939] | : resolve() for ClusterFuture ... [01:56:46.939] | : resolve() for ClusterFuture ... done [01:56:46.940] | : length: 1 (resolved future 1) [01:56:46.940] | checking value #1 ... done [01:56:46.940] | checking value #2 ... [01:56:46.940] | : 'obj' is ClusterFuture [01:56:46.941] | : resolved() for ClusterFuture (NULL) ... [01:56:46.941] | : . Cluster node socket connection: index=4, id=139 [01:56:46.941] | : . assertValidConnection() ... [01:56:46.941] | : . ' cluster node index: 1 [01:56:46.942] | : . assertValidConnection() ... done [01:56:46.942] | : . receiveMessageFromWorker() for ClusterFuture ... [01:56:46.942] | : . ' cluster node index: 1 [01:56:46.943] | : . ' [1] TRUE [01:56:46.943] | : . ' received data: [01:56:46.943] | : . ' List of 5 [01:56:46.943] | : . ' $ type : chr "VALUE" [01:56:46.943] | : . ' $ value :List of 14 [01:56:46.943] | : . ' ..$ value : int 2 [01:56:46.943] | : . ' ..$ visible : logi TRUE [01:56:46.943] | : . ' ..$ stdout : chr "" [01:56:46.943] | : . ' ..$ conditions : list() [01:56:46.943] | : . ' ..$ rng : logi FALSE [01:56:46.943] | : . ' ..$ misuseGlobalEnv : NULL [01:56:46.943] | : . ' ..$ misuseConnections:List of 3 [01:56:46.943] | : . ' .. ..$ added : NULL [01:56:46.943] | : . ' .. ..$ removed : NULL [01:56:46.943] | : . ' .. ..$ replaced: NULL [01:56:46.943] | : . ' ..$ misuseDevices : NULL [01:56:46.943] | : . ' ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.943] | : . ' ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.943] | : . ' ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "2" [01:56:46.943] | : . ' ..$ session_uuid : chr "f5c24d5d6f6bfe21974146f69432f935" [01:56:46.943] | : . ' .. ..- attr(*, "source")=List of 5 [01:56:46.943] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [01:56:46.943] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:46.943] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:46.943] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:46.943] | : . ' .. .. ..$ pid : int 198984 [01:56:46.943] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:46" [01:56:46.943] | : . ' .. .. ..$ random: int 2147483647 [01:56:46.943] | : . ' ..$ r_info :List of 4 [01:56:46.943] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:46.943] | : . ' .. .. ..$ : int [1:3] 4 6 0 [01:56:46.943] | : . ' .. ..$ os : chr "windows" [01:56:46.943] | : . ' .. ..$ os_name : chr "Windows" [01:56:46.943] | : . ' .. ..$ captures_utf8: logi TRUE [01:56:46.943] | : . ' ..$ version : chr "1.8" [01:56:46.943] | : . ' ..- attr(*, "class")= chr "FutureResult" [01:56:46.943] | : . ' $ success: logi TRUE [01:56:46.943] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [01:56:46.943] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:46.943] | : . ' $ tag : NULL [01:56:46.959] | : . ' Received FutureResult [01:56:46.959] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:46.959] | : . ' , Removed future from position #1 [01:56:46.959] | : . ' , Number of registered futures: 0 [01:56:46.960] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:46.960] | : . ' Erased future from future backend [01:56:46.960] | : . ' result() for ClusterFuture ... [01:56:46.960] | : . ' , result already collected: FutureResult [01:56:46.960] | : . ' result() for ClusterFuture ... done [01:56:46.961] | : . receiveMessageFromWorker() for ClusterFuture ... done [01:56:46.961] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:46.961] | : resolved() for ClusterFuture (NULL) ... done [01:56:46.961] | : assertFutureResult() ... [01:56:46.961] | : . Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:46.961] | : . Future uuid: 2 [01:56:46.962] | : . identical; success [01:56:46.962] | : assertFutureResult() ... done [01:56:46.962] | : ClusterFuture #2 [01:56:46.962] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:46.962] | : . nx: 2 [01:56:46.963] | : . relay: TRUE [01:56:46.963] | : . stdout: TRUE [01:56:46.963] | : . signal: TRUE [01:56:46.963] | : . resignal: FALSE [01:56:46.963] | : . force: TRUE [01:56:46.964] | : . relayed: [n=2] TRUE, FALSE [01:56:46.964] | : . queued futures: [n=2] TRUE, FALSE [01:56:46.964] | : . until=2 [01:56:46.964] | : . relaying element #2 [01:56:46.964] | : . value() for ClusterFuture (NULL) ... [01:56:46.965] | : . ' result() for ClusterFuture ... [01:56:46.965] | : . ' , result already collected: FutureResult [01:56:46.965] | : . ' result() for ClusterFuture ... done [01:56:46.965] | : . ' result() for ClusterFuture ... [01:56:46.965] | : . ' , result already collected: FutureResult [01:56:46.965] | : . ' result() for ClusterFuture ... done [01:56:46.966] | : . ' relay stdout ... [01:56:46.966] | : . ' relay stdout ... done [01:56:46.966] | : . ' check for misuse ... [01:56:46.966] | : . ' check for misuse ... done [01:56:46.966] | : . value() for ClusterFuture (NULL) ... done [01:56:46.967] | : . result() for ClusterFuture ... [01:56:46.967] | : . ' result already collected: FutureResult [01:56:46.967] | : . result() for ClusterFuture ... done [01:56:46.967] | : . result() for ClusterFuture ... [01:56:46.967] | : . ' result already collected: FutureResult [01:56:46.968] | : . result() for ClusterFuture ... done [01:56:46.968] | : . relayed: [n=2] TRUE, TRUE [01:56:46.968] | : . queued futures: [n=2] TRUE, TRUE [01:56:46.968] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:46.968] | : value(, ...) ... [01:56:46.969] | : . value() for ClusterFuture (NULL) ... [01:56:46.969] | : . ' result() for ClusterFuture ... [01:56:46.969] | : . ' , result already collected: FutureResult [01:56:46.969] | : . ' result() for ClusterFuture ... done [01:56:46.969] | : . ' result() for ClusterFuture ... [01:56:46.970] | : . ' , result already collected: FutureResult [01:56:46.970] | : . ' result() for ClusterFuture ... done [01:56:46.970] | : . ' check for misuse ... [01:56:46.970] | : . ' check for misuse ... done [01:56:46.970] | : . value() for ClusterFuture (NULL) ... done [01:56:46.970] | : . value: [01:56:46.971] | : value(, ...) ... done [01:56:46.971] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:46.971] | : . nx: 2 [01:56:46.971] | : . relay: TRUE [01:56:46.971] | : . stdout: TRUE [01:56:46.972] | : . signal: TRUE [01:56:46.972] | : . resignal: FALSE [01:56:46.972] | : . force: TRUE [01:56:46.972] | : . relayed: [n=2] TRUE, TRUE [01:56:46.972] | : . queued futures: [n=2] TRUE, TRUE [01:56:46.972] | : . until=2 [01:56:46.973] | : . relayed: [n=2] TRUE, TRUE [01:56:46.973] | : . queued futures: [n=2] TRUE, TRUE [01:56:46.973] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:46.973] | : resolve() for ClusterFuture ... [01:56:46.973] | : resolve() for ClusterFuture ... done [01:56:46.974] | : length: 0 (resolved future 2) [01:56:46.974] | checking value #2 ... done [01:56:46.974] | Relaying remaining futures ... [01:56:46.974] | : signalConditionsASAP(NULL, pos=0) ... [01:56:46.974] | : . nx: 2 [01:56:46.975] | : . relay: TRUE [01:56:46.975] | : . stdout: TRUE [01:56:46.975] | : . signal: TRUE [01:56:46.975] | : . resignal: FALSE [01:56:46.975] | : . force: TRUE [01:56:46.975] | : . relayed: [n=2] TRUE, TRUE [01:56:46.976] | : . queued futures: [n=2] TRUE, TRUE - flush all [01:56:46.976] | : . relayed: [n=2] TRUE, TRUE [01:56:46.976] | : . queued futures: [n=2] TRUE, TRUE [01:56:46.976] | : signalConditionsASAP(NULL, pos=0) ... done [01:56:46.976] | Relaying remaining futures ... done [01:56:46.977] value() for list ... done Iteration #2 [01:56:47.154] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.155] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [01:56:47.155] | : all.equal() for FutureStrategyList ... [01:56:47.156] | : . New stack: [01:56:47.156] | : . List of 1 [01:56:47.156] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.156] | : . envir = parent.frame()) [01:56:47.156] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.156] | : . ..- attr(*, "init")= logi TRUE [01:56:47.156] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.156] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.156] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.156] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.156] | : . ..- attr(*, "tweaks")=List of 1 [01:56:47.156] | : . .. ..$ workers:List of 1 [01:56:47.156] | : . .. .. ..$ :List of 3 [01:56:47.156] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.156] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.156] | : . .. .. .. ..$ host: chr "localhost" [01:56:47.156] | : . .. .. .. ..$ rank: num 0 [01:56:47.156] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.156] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.156] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.156] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.163] | : . Old stack: [01:56:47.163] | : . List of 1 [01:56:47.163] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.163] | : . envir = parent.frame()) [01:56:47.163] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.163] | : . ..- attr(*, "init")= chr "done" [01:56:47.163] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.163] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.163] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.163] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.163] | : . ..- attr(*, "tweaks")=List of 1 [01:56:47.163] | : . .. ..$ workers:List of 1 [01:56:47.163] | : . .. .. ..$ :List of 3 [01:56:47.163] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.163] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.163] | : . .. .. .. ..$ host: chr "localhost" [01:56:47.163] | : . .. .. .. ..$ rank: num 0 [01:56:47.163] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.163] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.163] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.163] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:47.163] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.163] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.163] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.163] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.172] | : . Not identical [01:56:47.172] | : . all.equal() for future ... [01:56:47.172] | : . ' List of 2 [01:56:47.172] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.172] | : . ' envir = parent.frame()) [01:56:47.172] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.172] | : . ' ..- attr(*, "init")= logi TRUE [01:56:47.172] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.172] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.172] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.172] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.172] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:47.172] | : . ' .. ..$ workers:List of 1 [01:56:47.172] | : . ' .. .. ..$ :List of 3 [01:56:47.172] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.172] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.172] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:47.172] | : . ' .. .. .. ..$ rank: num 0 [01:56:47.172] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.172] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.172] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.172] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.172] | : . ' envir = parent.frame()) [01:56:47.172] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.172] | : . ' ..- attr(*, "init")= chr "done" [01:56:47.172] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.172] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.172] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.172] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.172] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:47.172] | : . ' .. ..$ workers:List of 1 [01:56:47.172] | : . ' .. .. ..$ :List of 3 [01:56:47.172] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.172] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.172] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:47.172] | : . ' .. .. .. ..$ rank: num 0 [01:56:47.172] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.172] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.172] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.172] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:47.172] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.172] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.172] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.188] | : . ' Formals differ [01:56:47.188] | : . all.equal() for future ... done [01:56:47.188] | : . Future strategies differ at level 1 [01:56:47.188] | : all.equal() for FutureStrategyList ... done [01:56:47.188] | : plan(): Setting new future backend stack: [01:56:47.189] | : List of future strategies: [01:56:47.189] | : 1. cluster: [01:56:47.189] | : - args: function (..., workers = "", envir = parent.frame()) [01:56:47.189] | : - tweaked: TRUE [01:56:47.189] | : - call: plan(cluster, workers = cl) [01:56:47.189] | : List of 1 [01:56:47.189] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.189] | : envir = parent.frame()) [01:56:47.189] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.189] | : ..- attr(*, "init")= logi TRUE [01:56:47.189] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.189] | : earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.189] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.189] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.189] | : ..- attr(*, "tweaks")=List of 1 [01:56:47.189] | : .. ..$ workers:List of 1 [01:56:47.189] | : .. .. ..$ :List of 3 [01:56:47.189] | : .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.189] | : .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.189] | : .. .. .. ..$ host: chr "localhost" [01:56:47.189] | : .. .. .. ..$ rank: num 0 [01:56:47.189] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.189] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.189] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.189] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.199] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [01:56:47.199] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.199] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [01:56:47.199] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.199] | : . stopWorkers() for ClusterFutureBackend ... [01:56:47.200] | : . ' Interrupt active futures ... [01:56:47.200] | : . ' , Number of futures: 0 [01:56:47.201] | : . ' Interrupt active futures ... done [01:56:47.201] | : . ' Clear future registry ... [01:56:47.201] | : . ' Clear future registry ... done [01:56:47.201] | : . ' Stop cluster workers ... [01:56:47.202] | : . ' , Stopping existing cluster ... [01:56:47.202] | : . ' , ; No pre-existing cluster. Skipping [01:56:47.202] | : . ' , Stopping existing cluster ... done [01:56:47.202] | : . ' Stop cluster workers ... done [01:56:47.202] | : . stopWorkers() for ClusterFutureBackend ... done [01:56:47.203] | : . Legacy shutdown of cluster workers ... [01:56:47.203] | : . ' Stopping existing cluster ... [01:56:47.203] | : . ' , No pre-existing cluster. Skipping [01:56:47.203] | : . ' Stopping existing cluster ... done [01:56:47.203] | : . Legacy shutdown of cluster workers ... done [01:56:47.203] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [01:56:47.204] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [01:56:47.204] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.204] | : . envir = parent.frame()) [01:56:47.204] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.204] | : . - attr(*, "init")= logi TRUE [01:56:47.204] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.204] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.204] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.204] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.204] | : . - attr(*, "tweaks")=List of 1 [01:56:47.204] | : . ..$ workers:List of 1 [01:56:47.204] | : . .. ..$ :List of 3 [01:56:47.204] | : . .. .. ..$ con : 'sockconn' int 4 [01:56:47.204] | : . .. .. .. ..- attr(*, "conn_id")= [01:56:47.204] | : . .. .. ..$ host: chr "localhost" [01:56:47.204] | : . .. .. ..$ rank: num 0 [01:56:47.204] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.204] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.204] | : . - attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.209] | : . init: TRUE [01:56:47.210] | : . makeFutureBackend() ... [01:56:47.210] | : . ' Backend function: <'NULL'> [01:56:47.210] | : . ' Evaluator tweak arguments: [n=1] [01:56:47.210] | : . ' List of 1 [01:56:47.210] | : . ' $ workers:List of 1 [01:56:47.210] | : . ' ..$ :List of 3 [01:56:47.210] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.210] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.210] | : . ' .. ..$ host: chr "localhost" [01:56:47.210] | : . ' .. ..$ rank: num 0 [01:56:47.210] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.210] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.213] | : . ' Evaluator formal arguments: [n=1] [01:56:47.214] | : . ' List of 1 [01:56:47.214] | : . ' $ workers:List of 1 [01:56:47.214] | : . ' ..$ :List of 3 [01:56:47.214] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.214] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.214] | : . ' .. ..$ host: chr "localhost" [01:56:47.214] | : . ' .. ..$ rank: num 0 [01:56:47.214] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.214] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.217] | : . ' Backend factory arguments: [n=1] [01:56:47.217] | : . ' Dotted pair list of 1 [01:56:47.217] | : . ' $ workers:List of 1 [01:56:47.217] | : . ' ..$ :List of 3 [01:56:47.217] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.217] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.217] | : . ' .. ..$ host: chr "localhost" [01:56:47.217] | : . ' .. ..$ rank: num 0 [01:56:47.217] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.217] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.249] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [01:56:47.249] | : . ' , Stopping existing cluster ... [01:56:47.249] | : . ' , ; No pre-existing cluster. Skipping [01:56:47.249] | : . ' , Stopping existing cluster ... done [01:56:47.250] | : . ' , Number of workers: 1 [01:56:47.250] | : . ' , Generated workers UUID [01:56:47.250] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.250] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [01:56:47.250] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [01:56:47.250] | : . makeFutureBackend() ... done [01:56:47.251] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [01:56:47.251] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.251] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.251] | : plan(): nbrOfWorkers() = 1 [01:56:47.251] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [01:56:47.252] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.252] future(..., label = NULL) ... [01:56:47.252] | lazy: FALSE [01:56:47.252] | stdout: TRUE [01:56:47.252] | conditions: [n=1] 'condition' [01:56:47.252] | gc: FALSE [01:56:47.252] | earlySignal: FALSE [01:56:47.253] | getGlobalsAndPackages() ... [01:56:47.253] | : Searching for globals ... [01:56:47.258] | : . globals found: [3] '[[', 'X', 'i' [01:56:47.258] | : Searching for globals ... done [01:56:47.258] | : Resolving globals: FALSE [01:56:47.258] | : Search for packages associated with the globals ... [01:56:47.258] | : . Packages associated with globals: [1] 'base' [01:56:47.259] | : . Packages: [1] 'base' [01:56:47.259] | : Search for packages associated with the globals ... done [01:56:47.259] | : Packages after dropping 'base': [0] [01:56:47.259] | : globals: [2] 'X', 'i' [01:56:47.260] | : packages: [0] [01:56:47.260] | getGlobalsAndPackages() ... done [01:56:47.260] | run() for 'Future' (NULL) ... [01:56:47.260] | : state: 'created' [01:56:47.260] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.261] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.261] | : Using ClusterFutureBackend ... [01:56:47.261] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [01:56:47.261] | : . Launching futures ... [01:56:47.261] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:47.261] | : . ' , Workers: [n=1] [01:56:47.262] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:47.262] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.262] | : . ' , requestWorker() ... [01:56:47.262] | : . ' , ; requestNode() ... [01:56:47.262] | : . ' , ; ` Number of workers: 1 [01:56:47.263] | : . ' , ; ` Polling for a free worker ... [01:56:47.263] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.263] | : . ' , ; ` | : Listing all futures [01:56:47.263] | : . ' , ; ` | : Number of registered futures: 0 [01:56:47.263] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.263] | : . ' , ; ` | Total time: 0 [01:56:47.264] | : . ' , ; ` Polling for a free worker ... done [01:56:47.264] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.264] | : . ' , ; ` | Listing all futures [01:56:47.264] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.264] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.264] | : . ' , ; ` avail: [n=1] '1' [01:56:47.265] | : . ' , ; ` Index of first available worker: 1 [01:56:47.265] | : . ' , ; ` Validate that the worker is functional ... [01:56:47.359] | : . ' , ; ` | Worker is functional [01:56:47.359] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.359] | : . ' , ; ` | : Listing all futures [01:56:47.359] | : . ' , ; ` | : Number of registered futures: 0 [01:56:47.360] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.360] | : . ' , ; ` Validate that the worker is functional ... done [01:56:47.360] | : . ' , ; requestNode() ... done [01:56:47.360] | : . ' , ; cluster node index: 1 [01:56:47.360] | : . ' , requestWorker() ... done [01:56:47.361] | : . ' , eraseGlobalEnvironment() ... [01:56:47.361] | : . ' , eraseGlobalEnvironment() ... done [01:56:47.361] | : . ' , launchFuture() ... [01:56:47.362] | : . ' , ; cluster node index: 1 [01:56:47.362] | : . ' , ; getFutureData() ... [01:56:47.362] | : . ' , ; ` getFutureCore() ... [01:56:47.362] | : . ' , ; ` | Packages needed by the future expression (n = 0): [01:56:47.362] | : . ' , ; ` getFutureCore() ... done [01:56:47.362] | : . ' , ; ` getFutureCapture() ... [01:56:47.363] | : . ' , ; ` getFutureCapture() ... done [01:56:47.363] | : . ' , ; ` getFutureContext() ... [01:56:47.363] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.363] | : . ' , ; ` | : Getting stack without first backend: [n=0] [01:56:47.363] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.363] | : . ' , ; ` | Packages needed by future backend (n = 0): [01:56:47.364] | : . ' , ; ` getFutureContext() ... done [01:56:47.364] | : . ' , ; getFutureData() ... done [01:56:47.365] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:47.365] | : . ' , ; ` Appended future to position #1 [01:56:47.365] | : . ' , ; ` Number of registered futures: 1 [01:56:47.365] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:47.365] | : . ' , launchFuture() ... done [01:56:47.365] | : . ' , ClusterFuture started [01:56:47.366] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:47.366] | : . Launching futures ... done [01:56:47.366] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:47.366] | : Using ClusterFutureBackend ... done [01:56:47.366] | run() for 'Future' (NULL) ... done [01:56:47.367] future(..., label = NULL) ... done [01:56:47.367] future(..., label = NULL) ... [01:56:47.367] | lazy: FALSE [01:56:47.367] | stdout: TRUE [01:56:47.367] | conditions: [n=1] 'condition' [01:56:47.367] | gc: FALSE [01:56:47.367] | earlySignal: FALSE [01:56:47.368] | getGlobalsAndPackages() ... [01:56:47.368] | : Searching for globals ... [01:56:47.373] | : . globals found: [3] '[[', 'X', 'i' [01:56:47.373] | : Searching for globals ... done [01:56:47.373] | : Resolving globals: FALSE [01:56:47.373] | : Search for packages associated with the globals ... [01:56:47.374] | : . Packages associated with globals: [1] 'base' [01:56:47.374] | : . Packages: [1] 'base' [01:56:47.374] | : Search for packages associated with the globals ... done [01:56:47.374] | : Packages after dropping 'base': [0] [01:56:47.375] | : globals: [2] 'X', 'i' [01:56:47.375] | : packages: [0] [01:56:47.375] | getGlobalsAndPackages() ... done [01:56:47.375] | run() for 'Future' (NULL) ... [01:56:47.375] | : state: 'created' [01:56:47.376] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.376] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.376] | : Using ClusterFutureBackend ... [01:56:47.376] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [01:56:47.376] | : . Launching futures ... [01:56:47.378] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:47.378] | : . ' , Workers: [n=1] [01:56:47.379] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:47.379] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.379] | : . ' , requestWorker() ... [01:56:47.379] | : . ' , ; requestNode() ... [01:56:47.379] | : . ' , ; ` Number of workers: 1 [01:56:47.380] | : . ' , ; ` Polling for a free worker ... [01:56:47.380] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.380] | : . ' , ; ` | : Listing all futures [01:56:47.380] | : . ' , ; ` | : Number of registered futures: 1 [01:56:47.380] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.380] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [01:56:47.399] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [01:56:47.428] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [01:56:47.428] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... [01:56:47.429] | : . ' , ; ` | : . ' Cluster node socket connection: index=4, id=166 [01:56:47.429] | : . ' , ; ` | : . ' assertValidConnection() ... [01:56:47.429] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:47.430] | : . ' , ; ` | : . ' assertValidConnection() ... done [01:56:47.430] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... [01:56:47.430] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:47.430] | : . ' , ; ` | : . ' , [1] TRUE [01:56:47.431] | : . ' , ; ` | : . ' , received data: [01:56:47.431] | : . ' , ; ` | : . ' , List of 5 [01:56:47.431] | : . ' , ; ` | : . ' , $ type : chr "VALUE" [01:56:47.431] | : . ' , ; ` | : . ' , $ value :List of 14 [01:56:47.431] | : . ' , ; ` | : . ' , ..$ value : int 1 [01:56:47.431] | : . ' , ; ` | : . ' , ..$ visible : logi TRUE [01:56:47.431] | : . ' , ; ` | : . ' , ..$ stdout : chr "" [01:56:47.431] | : . ' , ; ` | : . ' , ..$ conditions : list() [01:56:47.431] | : . ' , ; ` | : . ' , ..$ rng : logi FALSE [01:56:47.431] | : . ' , ; ` | : . ' , ..$ misuseGlobalEnv : NULL [01:56:47.431] | : . ' , ; ` | : . ' , ..$ misuseConnections:List of 3 [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ added : NULL [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ removed : NULL [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ replaced: NULL [01:56:47.431] | : . ' , ; ` | : . ' , ..$ misuseDevices : NULL [01:56:47.431] | : . ' , ; ` | : . ' , ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.431] | : . ' , ; ` | : . ' , ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.431] | : . ' , ; ` | : . ' , ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "3" [01:56:47.431] | : . ' , ; ` | : . ' , ..$ session_uuid : chr "bf2d5bd4e5bcfbd2172a860d80cb4035" [01:56:47.431] | : . ' , ; ` | : . ' , .. ..- attr(*, "source")=List of 5 [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ host : Named chr "CRANWIN3" [01:56:47.431] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:47.431] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ pid : int 199792 [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ random: int 2147483647 [01:56:47.431] | : . ' , ; ` | : . ' , ..$ r_info :List of 4 [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:47.431] | : . ' , ; ` | : . ' , .. .. ..$ : int [1:3] 4 6 0 [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ os : chr "windows" [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ os_name : chr "Windows" [01:56:47.431] | : . ' , ; ` | : . ' , .. ..$ captures_utf8: logi TRUE [01:56:47.431] | : . ' , ; ` | : . ' , ..$ version : chr "1.8" [01:56:47.431] | : . ' , ; ` | : . ' , ..- attr(*, "class")= chr "FutureResult" [01:56:47.431] | : . ' , ; ` | : . ' , $ success: logi TRUE [01:56:47.431] | : . ' , ; ` | : . ' , $ time : 'proc_time' Named num [1:5] 0.07 0 0.07 NA NA [01:56:47.431] | : . ' , ; ` | : . ' , ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:47.431] | : . ' , ; ` | : . ' , $ tag : NULL [01:56:47.445] | : . ' , ; ` | : . ' , Received FutureResult [01:56:47.445] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:47.446] | : . ' , ; ` | : . ' , ; Removed future from position #1 [01:56:47.446] | : . ' , ; ` | : . ' , ; Number of registered futures: 0 [01:56:47.446] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:47.446] | : . ' , ; ` | : . ' , Erased future from future backend [01:56:47.446] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.447] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.447] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.447] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... done [01:56:47.447] | : . ' , ; ` | : . ' receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:47.447] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... done [01:56:47.447] | : . ' , ; ` | : . assertFutureResult() ... [01:56:47.448] | : . ' , ; ` | : . ' Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:47.448] | : . ' , ; ` | : . ' Future uuid: 3 [01:56:47.448] | : . ' , ; ` | : . ' identical; success [01:56:47.448] | : . ' , ; ` | : . assertFutureResult() ... done [01:56:47.448] | : . ' , ; ` | : . Future at position #1 is resolved ... [01:56:47.448] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... [01:56:47.448] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.449] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.449] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.449] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.449] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.449] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.449] | : . ' , ; ` | : . ' , check for misuse ... [01:56:47.450] | : . ' , ; ` | : . ' , check for misuse ... done [01:56:47.450] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... done [01:56:47.450] | : . ' , ; ` | : . Future at position #1 is resolved ... done [01:56:47.450] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [01:56:47.450] | : . ' , ; ` | : Remove collected futures ... [01:56:47.451] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [01:56:47.451] | : . ' , ; ` | : Remove collected futures ... done [01:56:47.451] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [01:56:47.451] | : . ' , ; ` | Early signaling of 1 future candidates ... [01:56:47.451] | : . ' , ; ` | Number of futures with early signaling requested: 0 [01:56:47.451] | : . ' , ; ` | Early signaling of 1 future candidates ... done [01:56:47.452] | : . ' , ; ` | Number of registered futures: 1 [01:56:47.452] | : . ' , ; ` Polling for a free worker ... done [01:56:47.452] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.452] | : . ' , ; ` | Listing all futures [01:56:47.452] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.452] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.453] | : . ' , ; ` Total time: 0.0721020698547363 [01:56:47.453] | : . ' , ; requestNode() ... done [01:56:47.453] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.453] | : . ' , ; ` Listing all futures [01:56:47.453] | : . ' , ; ` Number of registered futures: 0 [01:56:47.453] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.454] | : . ' , ; avail: [n=1] '1' [01:56:47.454] | : . ' , ; Index of first available worker: 1 [01:56:47.454] | : . ' , ; Validate that the worker is functional ... [01:56:47.455] | : . ' , ; ` Worker is functional [01:56:47.455] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.455] | : . ' , ; ` | Listing all futures [01:56:47.456] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.456] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.456] | : . ' , ; Validate that the worker is functional ... done [01:56:47.456] | : . ' , requestWorker() ... done [01:56:47.456] | : . ' , cluster node index: 1 [01:56:47.456] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:47.457] | : . ' eraseGlobalEnvironment() ... [01:56:47.457] | : . ' eraseGlobalEnvironment() ... done [01:56:47.457] | : . ' launchFuture() ... [01:56:47.457] | : . ' , cluster node index: 1 [01:56:47.457] | : . ' , getFutureData() ... [01:56:47.458] | : . ' , ; getFutureCore() ... [01:56:47.458] | : . ' , ; ` Packages needed by the future expression (n = 0): [01:56:47.458] | : . ' , ; getFutureCore() ... done [01:56:47.458] | : . ' , ; getFutureCapture() ... [01:56:47.458] | : . ' , ; getFutureCapture() ... done [01:56:47.458] | : . ' , ; getFutureContext() ... [01:56:47.459] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.459] | : . ' , ; ` | Getting stack without first backend: [n=0] [01:56:47.459] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.459] | : . ' , ; ` Packages needed by future backend (n = 0): [01:56:47.459] | : . ' , ; getFutureContext() ... done [01:56:47.460] | : . ' , getFutureData() ... done [01:56:47.460] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:47.460] | : . ' , ; Appended future to position #1 [01:56:47.461] | : . ' , ; Number of registered futures: 1 [01:56:47.461] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:47.461] | : . ' launchFuture() ... done [01:56:47.461] | : . ' ClusterFuture started [01:56:47.461] | : . Launching futures ... done [01:56:47.461] | : Using ClusterFutureBackend ... done [01:56:47.462] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:47.462] | run() for 'Future' (NULL) ... done [01:56:47.462] future(..., label = NULL) ... done [01:56:47.462] value() for list ... [01:56:47.462] | recursive: 0 [01:56:47.463] | reduce: FALSE [01:56:47.463] | length: 2 [01:56:47.463] | Number of remaining objects: 2 [01:56:47.463] | checking value #1 ... [01:56:47.463] | : 'obj' is ClusterFuture [01:56:47.464] | : ClusterFuture #1 [01:56:47.464] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:47.464] | : . nx: 2 [01:56:47.464] | : . relay: TRUE [01:56:47.464] | : . stdout: TRUE [01:56:47.464] | : . signal: TRUE [01:56:47.464] | : . resignal: FALSE [01:56:47.465] | : . force: TRUE [01:56:47.465] | : . relayed: [n=2] FALSE, FALSE [01:56:47.465] | : . queued futures: [n=2] FALSE, FALSE [01:56:47.465] | : . until=1 [01:56:47.465] | : . relaying element #1 [01:56:47.465] | : . value() for ClusterFuture (NULL) ... [01:56:47.466] | : . ' result() for ClusterFuture ... [01:56:47.466] | : . ' , result already collected: FutureResult [01:56:47.466] | : . ' result() for ClusterFuture ... done [01:56:47.466] | : . ' result() for ClusterFuture ... [01:56:47.466] | : . ' , result already collected: FutureResult [01:56:47.466] | : . ' result() for ClusterFuture ... done [01:56:47.467] | : . ' relay stdout ... [01:56:47.467] | : . ' relay stdout ... done [01:56:47.467] | : . ' check for misuse ... [01:56:47.467] | : . ' check for misuse ... done [01:56:47.467] | : . value() for ClusterFuture (NULL) ... done [01:56:47.467] | : . result() for ClusterFuture ... [01:56:47.468] | : . ' result already collected: FutureResult [01:56:47.468] | : . result() for ClusterFuture ... done [01:56:47.468] | : . result() for ClusterFuture ... [01:56:47.468] | : . ' result already collected: FutureResult [01:56:47.468] | : . result() for ClusterFuture ... done [01:56:47.468] | : . relayed: [n=2] TRUE, FALSE [01:56:47.468] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.469] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:47.469] | : value(, ...) ... [01:56:47.469] | : . value() for ClusterFuture (NULL) ... [01:56:47.469] | : . ' result() for ClusterFuture ... [01:56:47.469] | : . ' , result already collected: FutureResult [01:56:47.469] | : . ' result() for ClusterFuture ... done [01:56:47.470] | : . ' result() for ClusterFuture ... [01:56:47.470] | : . ' , result already collected: FutureResult [01:56:47.470] | : . ' result() for ClusterFuture ... done [01:56:47.470] | : . ' check for misuse ... [01:56:47.470] | : . ' check for misuse ... done [01:56:47.470] | : . value() for ClusterFuture (NULL) ... done [01:56:47.471] | : . value: [01:56:47.471] | : value(, ...) ... done [01:56:47.471] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:47.471] | : . nx: 2 [01:56:47.471] | : . relay: TRUE [01:56:47.471] | : . stdout: TRUE [01:56:47.472] | : . signal: TRUE [01:56:47.472] | : . resignal: FALSE [01:56:47.472] | : . force: TRUE [01:56:47.472] | : . relayed: [n=2] TRUE, FALSE [01:56:47.472] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.472] | : . until=2 [01:56:47.472] | : . relaying element #2 [01:56:47.473] | : . relayed: [n=2] TRUE, FALSE [01:56:47.473] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.473] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:47.473] | : resolve() for ClusterFuture ... [01:56:47.473] | : resolve() for ClusterFuture ... done [01:56:47.473] | : length: 1 (resolved future 1) [01:56:47.474] | checking value #1 ... done [01:56:47.474] | checking value #2 ... [01:56:47.474] | : 'obj' is ClusterFuture [01:56:47.474] | : resolved() for ClusterFuture (NULL) ... [01:56:47.474] | : . Cluster node socket connection: index=4, id=166 [01:56:47.475] | : . assertValidConnection() ... [01:56:47.475] | : . ' cluster node index: 1 [01:56:47.475] | : . assertValidConnection() ... done [01:56:47.475] | : . receiveMessageFromWorker() for ClusterFuture ... [01:56:47.476] | : . ' cluster node index: 1 [01:56:47.476] | : . ' [1] TRUE [01:56:47.476] | : . ' received data: [01:56:47.476] | : . ' List of 5 [01:56:47.476] | : . ' $ type : chr "VALUE" [01:56:47.476] | : . ' $ value :List of 14 [01:56:47.476] | : . ' ..$ value : int 2 [01:56:47.476] | : . ' ..$ visible : logi TRUE [01:56:47.476] | : . ' ..$ stdout : chr "" [01:56:47.476] | : . ' ..$ conditions : list() [01:56:47.476] | : . ' ..$ rng : logi FALSE [01:56:47.476] | : . ' ..$ misuseGlobalEnv : NULL [01:56:47.476] | : . ' ..$ misuseConnections:List of 3 [01:56:47.476] | : . ' .. ..$ added : NULL [01:56:47.476] | : . ' .. ..$ removed : NULL [01:56:47.476] | : . ' .. ..$ replaced: NULL [01:56:47.476] | : . ' ..$ misuseDevices : NULL [01:56:47.476] | : . ' ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.476] | : . ' ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.476] | : . ' ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "4" [01:56:47.476] | : . ' ..$ session_uuid : chr "bf2d5bd4e5bcfbd2172a860d80cb4035" [01:56:47.476] | : . ' .. ..- attr(*, "source")=List of 5 [01:56:47.476] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [01:56:47.476] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:47.476] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:47.476] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:47.476] | : . ' .. .. ..$ pid : int 199792 [01:56:47.476] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.476] | : . ' .. .. ..$ random: int 2147483647 [01:56:47.476] | : . ' ..$ r_info :List of 4 [01:56:47.476] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:47.476] | : . ' .. .. ..$ : int [1:3] 4 6 0 [01:56:47.476] | : . ' .. ..$ os : chr "windows" [01:56:47.476] | : . ' .. ..$ os_name : chr "Windows" [01:56:47.476] | : . ' .. ..$ captures_utf8: logi TRUE [01:56:47.476] | : . ' ..$ version : chr "1.8" [01:56:47.476] | : . ' ..- attr(*, "class")= chr "FutureResult" [01:56:47.476] | : . ' $ success: logi TRUE [01:56:47.476] | : . ' $ time : 'proc_time' Named num [1:5] 0.01 0 0.01 NA NA [01:56:47.476] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:47.476] | : . ' $ tag : NULL [01:56:47.491] | : . ' Received FutureResult [01:56:47.491] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:47.491] | : . ' , Removed future from position #1 [01:56:47.492] | : . ' , Number of registered futures: 0 [01:56:47.492] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:47.492] | : . ' Erased future from future backend [01:56:47.492] | : . ' result() for ClusterFuture ... [01:56:47.492] | : . ' , result already collected: FutureResult [01:56:47.492] | : . ' result() for ClusterFuture ... done [01:56:47.493] | : . receiveMessageFromWorker() for ClusterFuture ... done [01:56:47.493] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:47.493] | : resolved() for ClusterFuture (NULL) ... done [01:56:47.493] | : assertFutureResult() ... [01:56:47.493] | : . Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:47.493] | : . Future uuid: 4 [01:56:47.493] | : . identical; success [01:56:47.494] | : assertFutureResult() ... done [01:56:47.494] | : ClusterFuture #2 [01:56:47.494] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:47.494] | : . nx: 2 [01:56:47.494] | : . relay: TRUE [01:56:47.494] | : . stdout: TRUE [01:56:47.494] | : . signal: TRUE [01:56:47.495] | : . resignal: FALSE [01:56:47.495] | : . force: TRUE [01:56:47.495] | : . relayed: [n=2] TRUE, FALSE [01:56:47.495] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.495] | : . until=2 [01:56:47.495] | : . relaying element #2 [01:56:47.496] | : . value() for ClusterFuture (NULL) ... [01:56:47.496] | : . ' result() for ClusterFuture ... [01:56:47.496] | : . ' , result already collected: FutureResult [01:56:47.496] | : . ' result() for ClusterFuture ... done [01:56:47.496] | : . ' result() for ClusterFuture ... [01:56:47.496] | : . ' , result already collected: FutureResult [01:56:47.497] | : . ' result() for ClusterFuture ... done [01:56:47.497] | : . ' relay stdout ... [01:56:47.497] | : . ' relay stdout ... done [01:56:47.497] | : . ' check for misuse ... [01:56:47.497] | : . ' check for misuse ... done [01:56:47.497] | : . value() for ClusterFuture (NULL) ... done [01:56:47.498] | : . result() for ClusterFuture ... [01:56:47.498] | : . ' result already collected: FutureResult [01:56:47.498] | : . result() for ClusterFuture ... done [01:56:47.498] | : . result() for ClusterFuture ... [01:56:47.498] | : . ' result already collected: FutureResult [01:56:47.498] | : . result() for ClusterFuture ... done [01:56:47.499] | : . relayed: [n=2] TRUE, TRUE [01:56:47.499] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.499] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:47.499] | : value(, ...) ... [01:56:47.499] | : . value() for ClusterFuture (NULL) ... [01:56:47.499] | : . ' result() for ClusterFuture ... [01:56:47.499] | : . ' , result already collected: FutureResult [01:56:47.500] | : . ' result() for ClusterFuture ... done [01:56:47.500] | : . ' result() for ClusterFuture ... [01:56:47.500] | : . ' , result already collected: FutureResult [01:56:47.500] | : . ' result() for ClusterFuture ... done [01:56:47.500] | : . ' check for misuse ... [01:56:47.500] | : . ' check for misuse ... done [01:56:47.501] | : . value() for ClusterFuture (NULL) ... done [01:56:47.501] | : . value: [01:56:47.501] | : value(, ...) ... done [01:56:47.501] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:47.501] | : . nx: 2 [01:56:47.501] | : . relay: TRUE [01:56:47.502] | : . stdout: TRUE [01:56:47.502] | : . signal: TRUE [01:56:47.502] | : . resignal: FALSE [01:56:47.502] | : . force: TRUE [01:56:47.502] | : . relayed: [n=2] TRUE, TRUE [01:56:47.502] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.502] | : . until=2 [01:56:47.503] | : . relayed: [n=2] TRUE, TRUE [01:56:47.503] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.503] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:47.503] | : resolve() for ClusterFuture ... [01:56:47.503] | : resolve() for ClusterFuture ... done [01:56:47.503] | : length: 0 (resolved future 2) [01:56:47.504] | checking value #2 ... done [01:56:47.504] | Relaying remaining futures ... [01:56:47.504] | : signalConditionsASAP(NULL, pos=0) ... [01:56:47.504] | : . nx: 2 [01:56:47.504] | : . relay: TRUE [01:56:47.504] | : . stdout: TRUE [01:56:47.505] | : . signal: TRUE [01:56:47.505] | : . resignal: FALSE [01:56:47.505] | : . force: TRUE [01:56:47.505] | : . relayed: [n=2] TRUE, TRUE [01:56:47.505] | : . queued futures: [n=2] TRUE, TRUE - flush all [01:56:47.505] | : . relayed: [n=2] TRUE, TRUE [01:56:47.506] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.506] | : signalConditionsASAP(NULL, pos=0) ... done [01:56:47.506] | Relaying remaining futures ... done [01:56:47.506] value() for list ... done Iteration #3 [01:56:47.676] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.677] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [01:56:47.677] | : all.equal() for FutureStrategyList ... [01:56:47.678] | : . New stack: [01:56:47.678] | : . List of 1 [01:56:47.678] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.678] | : . envir = parent.frame()) [01:56:47.678] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.678] | : . ..- attr(*, "init")= logi TRUE [01:56:47.678] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.678] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.678] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.678] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.678] | : . ..- attr(*, "tweaks")=List of 1 [01:56:47.678] | : . .. ..$ workers:List of 1 [01:56:47.678] | : . .. .. ..$ :List of 3 [01:56:47.678] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.678] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.678] | : . .. .. .. ..$ host: chr "localhost" [01:56:47.678] | : . .. .. .. ..$ rank: num 0 [01:56:47.678] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.678] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.678] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.678] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.684] | : . Old stack: [01:56:47.685] | : . List of 1 [01:56:47.685] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.685] | : . envir = parent.frame()) [01:56:47.685] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.685] | : . ..- attr(*, "init")= chr "done" [01:56:47.685] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.685] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.685] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.685] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.685] | : . ..- attr(*, "tweaks")=List of 1 [01:56:47.685] | : . .. ..$ workers:List of 1 [01:56:47.685] | : . .. .. ..$ :List of 3 [01:56:47.685] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.685] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.685] | : . .. .. .. ..$ host: chr "localhost" [01:56:47.685] | : . .. .. .. ..$ rank: num 0 [01:56:47.685] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.685] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.685] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.685] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:47.685] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.685] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.685] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.685] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.692] | : . Not identical [01:56:47.692] | : . all.equal() for future ... [01:56:47.692] | : . ' List of 2 [01:56:47.692] | : . ' $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.692] | : . ' envir = parent.frame()) [01:56:47.692] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.692] | : . ' ..- attr(*, "init")= logi TRUE [01:56:47.692] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.692] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.692] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.692] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.692] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:47.692] | : . ' .. ..$ workers:List of 1 [01:56:47.692] | : . ' .. .. ..$ :List of 3 [01:56:47.692] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.692] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.692] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:47.692] | : . ' .. .. .. ..$ rank: num 0 [01:56:47.692] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.692] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.692] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.692] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.692] | : . ' envir = parent.frame()) [01:56:47.692] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.692] | : . ' ..- attr(*, "init")= chr "done" [01:56:47.692] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.692] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.692] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.692] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.692] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:47.692] | : . ' .. ..$ workers:List of 1 [01:56:47.692] | : . ' .. .. ..$ :List of 3 [01:56:47.692] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.692] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.692] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:47.692] | : . ' .. .. .. ..$ rank: num 0 [01:56:47.692] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.692] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.692] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.692] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:47.692] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.692] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.692] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.705] | : . ' Formals differ [01:56:47.705] | : . all.equal() for future ... done [01:56:47.705] | : . Future strategies differ at level 1 [01:56:47.705] | : all.equal() for FutureStrategyList ... done [01:56:47.706] | : plan(): Setting new future backend stack: [01:56:47.706] | : List of future strategies: [01:56:47.706] | : 1. cluster: [01:56:47.706] | : - args: function (..., workers = "", envir = parent.frame()) [01:56:47.706] | : - tweaked: TRUE [01:56:47.706] | : - call: plan(cluster, workers = cl) [01:56:47.707] | : List of 1 [01:56:47.707] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.707] | : envir = parent.frame()) [01:56:47.707] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.707] | : ..- attr(*, "init")= logi TRUE [01:56:47.707] | : ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.707] | : earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.707] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.707] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.707] | : ..- attr(*, "tweaks")=List of 1 [01:56:47.707] | : .. ..$ workers:List of 1 [01:56:47.707] | : .. .. ..$ :List of 3 [01:56:47.707] | : .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.707] | : .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.707] | : .. .. .. ..$ host: chr "localhost" [01:56:47.707] | : .. .. .. ..$ rank: num 0 [01:56:47.707] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.707] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.707] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.707] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.713] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [01:56:47.713] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.713] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [01:56:47.713] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.714] | : . stopWorkers() for ClusterFutureBackend ... [01:56:47.714] | : . ' Interrupt active futures ... [01:56:47.714] | : . ' , Number of futures: 0 [01:56:47.715] | : . ' Interrupt active futures ... done [01:56:47.715] | : . ' Clear future registry ... [01:56:47.715] | : . ' Clear future registry ... done [01:56:47.716] | : . ' Stop cluster workers ... [01:56:47.716] | : . ' , Stopping existing cluster ... [01:56:47.716] | : . ' , ; No pre-existing cluster. Skipping [01:56:47.716] | : . ' , Stopping existing cluster ... done [01:56:47.716] | : . ' Stop cluster workers ... done [01:56:47.716] | : . stopWorkers() for ClusterFutureBackend ... done [01:56:47.716] | : . Legacy shutdown of cluster workers ... [01:56:47.717] | : . ' Stopping existing cluster ... [01:56:47.717] | : . ' , No pre-existing cluster. Skipping [01:56:47.717] | : . ' Stopping existing cluster ... done [01:56:47.717] | : . Legacy shutdown of cluster workers ... done [01:56:47.717] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [01:56:47.717] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [01:56:47.718] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.718] | : . envir = parent.frame()) [01:56:47.718] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.718] | : . - attr(*, "init")= logi TRUE [01:56:47.718] | : . - attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.718] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.718] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.718] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.718] | : . - attr(*, "tweaks")=List of 1 [01:56:47.718] | : . ..$ workers:List of 1 [01:56:47.718] | : . .. ..$ :List of 3 [01:56:47.718] | : . .. .. ..$ con : 'sockconn' int 4 [01:56:47.718] | : . .. .. .. ..- attr(*, "conn_id")= [01:56:47.718] | : . .. .. ..$ host: chr "localhost" [01:56:47.718] | : . .. .. ..$ rank: num 0 [01:56:47.718] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.718] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.718] | : . - attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.723] | : . init: TRUE [01:56:47.724] | : . makeFutureBackend() ... [01:56:47.724] | : . ' Backend function: <'NULL'> [01:56:47.724] | : . ' Evaluator tweak arguments: [n=1] [01:56:47.724] | : . ' List of 1 [01:56:47.724] | : . ' $ workers:List of 1 [01:56:47.724] | : . ' ..$ :List of 3 [01:56:47.724] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.724] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.724] | : . ' .. ..$ host: chr "localhost" [01:56:47.724] | : . ' .. ..$ rank: num 0 [01:56:47.724] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.724] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.727] | : . ' Evaluator formal arguments: [n=1] [01:56:47.728] | : . ' List of 1 [01:56:47.728] | : . ' $ workers:List of 1 [01:56:47.728] | : . ' ..$ :List of 3 [01:56:47.728] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.728] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.728] | : . ' .. ..$ host: chr "localhost" [01:56:47.728] | : . ' .. ..$ rank: num 0 [01:56:47.728] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.728] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.732] | : . ' Backend factory arguments: [n=1] [01:56:47.732] | : . ' Dotted pair list of 1 [01:56:47.732] | : . ' $ workers:List of 1 [01:56:47.732] | : . ' ..$ :List of 3 [01:56:47.732] | : . ' .. ..$ con : 'sockconn' int 4 [01:56:47.732] | : . ' .. .. ..- attr(*, "conn_id")= [01:56:47.732] | : . ' .. ..$ host: chr "localhost" [01:56:47.732] | : . ' .. ..$ rank: num 0 [01:56:47.732] | : . ' .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.732] | : . ' ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.737] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [01:56:47.737] | : . ' , Stopping existing cluster ... [01:56:47.737] | : . ' , ; No pre-existing cluster. Skipping [01:56:47.738] | : . ' , Stopping existing cluster ... done [01:56:47.738] | : . ' , Number of workers: 1 [01:56:47.738] | : . ' , Generated workers UUID [01:56:47.738] | : . ' , Workers UUID: '37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.739] | : . ' ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [01:56:47.739] | : . ' Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [01:56:47.739] | : . makeFutureBackend() ... done [01:56:47.739] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [01:56:47.740] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.740] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.740] | : plan(): nbrOfWorkers() = 1 [01:56:47.740] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [01:56:47.741] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.741] future(..., label = NULL) ... [01:56:47.741] | lazy: FALSE [01:56:47.741] | stdout: TRUE [01:56:47.741] | conditions: [n=1] 'condition' [01:56:47.742] | gc: FALSE [01:56:47.742] | earlySignal: FALSE [01:56:47.742] | getGlobalsAndPackages() ... [01:56:47.742] | : Searching for globals ... [01:56:47.747] | : . globals found: [3] '[[', 'X', 'i' [01:56:47.748] | : Searching for globals ... done [01:56:47.748] | : Resolving globals: FALSE [01:56:47.748] | : Search for packages associated with the globals ... [01:56:47.748] | : . Packages associated with globals: [1] 'base' [01:56:47.749] | : . Packages: [1] 'base' [01:56:47.749] | : Search for packages associated with the globals ... done [01:56:47.749] | : Packages after dropping 'base': [0] [01:56:47.750] | : globals: [2] 'X', 'i' [01:56:47.750] | : packages: [0] [01:56:47.750] | getGlobalsAndPackages() ... done [01:56:47.750] | run() for 'Future' (NULL) ... [01:56:47.752] | : state: 'created' [01:56:47.753] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.753] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.753] | : Using ClusterFutureBackend ... [01:56:47.753] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [01:56:47.753] | : . Launching futures ... [01:56:47.754] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:47.754] | : . ' , Workers: [n=1] [01:56:47.754] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:47.754] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.754] | : . ' , requestWorker() ... [01:56:47.755] | : . ' , ; requestNode() ... [01:56:47.755] | : . ' , ; ` Number of workers: 1 [01:56:47.755] | : . ' , ; ` Polling for a free worker ... [01:56:47.755] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.755] | : . ' , ; ` | : Listing all futures [01:56:47.756] | : . ' , ; ` | : Number of registered futures: 0 [01:56:47.756] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.756] | : . ' , ; ` | Total time: 0 [01:56:47.756] | : . ' , ; ` Polling for a free worker ... done [01:56:47.756] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.757] | : . ' , ; ` | Listing all futures [01:56:47.757] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.757] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.757] | : . ' , ; ` avail: [n=1] '1' [01:56:47.757] | : . ' , ; ` Index of first available worker: 1 [01:56:47.758] | : . ' , ; ` Validate that the worker is functional ... [01:56:47.847] | : . ' , ; ` | Worker is functional [01:56:47.847] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.848] | : . ' , ; ` | : Listing all futures [01:56:47.848] | : . ' , ; ` | : Number of registered futures: 0 [01:56:47.848] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.848] | : . ' , ; ` Validate that the worker is functional ... done [01:56:47.848] | : . ' , ; requestNode() ... done [01:56:47.848] | : . ' , ; cluster node index: 1 [01:56:47.849] | : . ' , requestWorker() ... done [01:56:47.849] | : . ' , eraseGlobalEnvironment() ... [01:56:47.849] | : . ' , eraseGlobalEnvironment() ... done [01:56:47.849] | : . ' , launchFuture() ... [01:56:47.850] | : . ' , ; cluster node index: 1 [01:56:47.850] | : . ' , ; getFutureData() ... [01:56:47.850] | : . ' , ; ` getFutureCore() ... [01:56:47.850] | : . ' , ; ` | Packages needed by the future expression (n = 0): [01:56:47.850] | : . ' , ; ` getFutureCore() ... done [01:56:47.850] | : . ' , ; ` getFutureCapture() ... [01:56:47.851] | : . ' , ; ` getFutureCapture() ... done [01:56:47.851] | : . ' , ; ` getFutureContext() ... [01:56:47.851] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.851] | : . ' , ; ` | : Getting stack without first backend: [n=0] [01:56:47.851] | : . ' , ; ` | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.851] | : . ' , ; ` | Packages needed by future backend (n = 0): [01:56:47.852] | : . ' , ; ` getFutureContext() ... done [01:56:47.852] | : . ' , ; getFutureData() ... done [01:56:47.853] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:47.853] | : . ' , ; ` Appended future to position #1 [01:56:47.853] | : . ' , ; ` Number of registered futures: 1 [01:56:47.853] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:47.853] | : . ' , launchFuture() ... done [01:56:47.853] | : . ' , ClusterFuture started [01:56:47.854] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:47.854] | : . Launching futures ... done [01:56:47.854] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:47.854] | : Using ClusterFutureBackend ... done [01:56:47.854] | run() for 'Future' (NULL) ... done [01:56:47.854] future(..., label = NULL) ... done [01:56:47.855] future(..., label = NULL) ... [01:56:47.855] | lazy: FALSE [01:56:47.855] | stdout: TRUE [01:56:47.855] | conditions: [n=1] 'condition' [01:56:47.855] | gc: FALSE [01:56:47.855] | earlySignal: FALSE [01:56:47.856] | getGlobalsAndPackages() ... [01:56:47.856] | : Searching for globals ... [01:56:47.861] | : . globals found: [3] '[[', 'X', 'i' [01:56:47.861] | : Searching for globals ... done [01:56:47.861] | : Resolving globals: FALSE [01:56:47.861] | : Search for packages associated with the globals ... [01:56:47.861] | : . Packages associated with globals: [1] 'base' [01:56:47.862] | : . Packages: [1] 'base' [01:56:47.862] | : Search for packages associated with the globals ... done [01:56:47.862] | : Packages after dropping 'base': [0] [01:56:47.862] | : globals: [2] 'X', 'i' [01:56:47.863] | : packages: [0] [01:56:47.863] | getGlobalsAndPackages() ... done [01:56:47.863] | run() for 'Future' (NULL) ... [01:56:47.863] | : state: 'created' [01:56:47.863] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.864] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.864] | : Using ClusterFutureBackend ... [01:56:47.864] | : . Number of futures since start: 1 (1 created, 1 launched, 0 finished) [01:56:47.864] | : . Launching futures ... [01:56:47.864] | : . ' launchFuture() for ClusterFutureBackend ... [01:56:47.865] | : . ' , Workers: [n=1] [01:56:47.865] | : . ' , socket cluster with 1 nodes on host 'localhost' [01:56:47.865] | : . ' , FutureRegistry: 'workers-37e5752128e4483a3ce8b7fda2d6c13d' [01:56:47.865] | : . ' , requestWorker() ... [01:56:47.865] | : . ' , ; requestNode() ... [01:56:47.866] | : . ' , ; ` Number of workers: 1 [01:56:47.866] | : . ' , ; ` Polling for a free worker ... [01:56:47.866] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.866] | : . ' , ; ` | : Listing all futures [01:56:47.866] | : . ' , ; ` | : Number of registered futures: 1 [01:56:47.866] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.867] | : . ' , ; ` | Poll #1 (0): usedNodes() = 1, workers = 1 [01:56:47.884] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... [01:56:47.884] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... [01:56:47.884] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... [01:56:47.884] | : . ' , ; ` | : . ' Cluster node socket connection: index=4, id=193 [01:56:47.885] | : . ' , ; ` | : . ' assertValidConnection() ... [01:56:47.885] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:47.885] | : . ' , ; ` | : . ' assertValidConnection() ... done [01:56:47.911] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... [01:56:47.911] | : . ' , ; ` | : . ' , cluster node index: 1 [01:56:47.911] | : . ' , ; ` | : . ' , [1] TRUE [01:56:47.911] | : . ' , ; ` | : . ' , received data: [01:56:47.912] | : . ' , ; ` | : . ' , List of 5 [01:56:47.912] | : . ' , ; ` | : . ' , $ type : chr "VALUE" [01:56:47.912] | : . ' , ; ` | : . ' , $ value :List of 14 [01:56:47.912] | : . ' , ; ` | : . ' , ..$ value : int 1 [01:56:47.912] | : . ' , ; ` | : . ' , ..$ visible : logi TRUE [01:56:47.912] | : . ' , ; ` | : . ' , ..$ stdout : chr "" [01:56:47.912] | : . ' , ; ` | : . ' , ..$ conditions : list() [01:56:47.912] | : . ' , ; ` | : . ' , ..$ rng : logi FALSE [01:56:47.912] | : . ' , ; ` | : . ' , ..$ misuseGlobalEnv : NULL [01:56:47.912] | : . ' , ; ` | : . ' , ..$ misuseConnections:List of 3 [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ added : NULL [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ removed : NULL [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ replaced: NULL [01:56:47.912] | : . ' , ; ` | : . ' , ..$ misuseDevices : NULL [01:56:47.912] | : . ' , ; ` | : . ' , ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.912] | : . ' , ; ` | : . ' , ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.912] | : . ' , ; ` | : . ' , ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "5" [01:56:47.912] | : . ' , ; ` | : . ' , ..$ session_uuid : chr "ef14fa5c4caf33010ceab7f6650d8406" [01:56:47.912] | : . ' , ; ` | : . ' , .. ..- attr(*, "source")=List of 5 [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ host : Named chr "CRANWIN3" [01:56:47.912] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:47.912] | : . ' , ; ` | : . ' , .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ pid : int 50080 [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ random: int 2147483647 [01:56:47.912] | : . ' , ; ` | : . ' , ..$ r_info :List of 4 [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:47.912] | : . ' , ; ` | : . ' , .. .. ..$ : int [1:3] 4 6 0 [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ os : chr "windows" [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ os_name : chr "Windows" [01:56:47.912] | : . ' , ; ` | : . ' , .. ..$ captures_utf8: logi TRUE [01:56:47.912] | : . ' , ; ` | : . ' , ..$ version : chr "1.8" [01:56:47.912] | : . ' , ; ` | : . ' , ..- attr(*, "class")= chr "FutureResult" [01:56:47.912] | : . ' , ; ` | : . ' , $ success: logi TRUE [01:56:47.912] | : . ' , ; ` | : . ' , $ time : 'proc_time' Named num [1:5] 0.05 0 0.05 NA NA [01:56:47.912] | : . ' , ; ` | : . ' , ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:47.912] | : . ' , ; ` | : . ' , $ tag : NULL [01:56:47.925] | : . ' , ; ` | : . ' , Received FutureResult [01:56:47.925] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:47.925] | : . ' , ; ` | : . ' , ; Removed future from position #1 [01:56:47.925] | : . ' , ; ` | : . ' , ; Number of registered futures: 0 [01:56:47.925] | : . ' , ; ` | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:47.926] | : . ' , ; ` | : . ' , Erased future from future backend [01:56:47.926] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.926] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.926] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.926] | : . ' , ; ` | : . ' receiveMessageFromWorker() for ClusterFuture ... done [01:56:47.926] | : . ' , ; ` | : . ' receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:47.927] | : . ' , ; ` | : . resolved() for ClusterFuture (NULL) ... done [01:56:47.927] | : . ' , ; ` | : . assertFutureResult() ... [01:56:47.927] | : . ' , ; ` | : . ' Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:47.927] | : . ' , ; ` | : . ' Future uuid: 5 [01:56:47.927] | : . ' , ; ` | : . ' identical; success [01:56:47.927] | : . ' , ; ` | : . assertFutureResult() ... done [01:56:47.927] | : . ' , ; ` | : . Future at position #1 is resolved ... [01:56:47.928] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... [01:56:47.928] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.928] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.928] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.928] | : . ' , ; ` | : . ' , result() for ClusterFuture ... [01:56:47.929] | : . ' , ; ` | : . ' , ; result already collected: FutureResult [01:56:47.929] | : . ' , ; ` | : . ' , result() for ClusterFuture ... done [01:56:47.929] | : . ' , ; ` | : . ' , check for misuse ... [01:56:47.929] | : . ' , ; ` | : . ' , check for misuse ... done [01:56:47.929] | : . ' , ; ` | : . ' value() for ClusterFuture (NULL) ... done [01:56:47.930] | : . ' , ; ` | : . Future at position #1 is resolved ... done [01:56:47.930] | : . ' , ; ` | : collectValues('workers-37e5752128e4483a3ce8b7fda2d6c13d', firstOnly = TRUE) ... done [01:56:47.930] | : . ' , ; ` | : Remove collected futures ... [01:56:47.930] | : . ' , ; ` | : . Indices of futures to drop: [n=1] '1' [01:56:47.930] | : . ' , ; ` | : Remove collected futures ... done [01:56:47.930] | : . ' , ; ` | FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'collect-first', earlySignal = 1) ... done [01:56:47.931] | : . ' , ; ` | Early signaling of 1 future candidates ... [01:56:47.931] | : . ' , ; ` | Number of futures with early signaling requested: 0 [01:56:47.931] | : . ' , ; ` | Early signaling of 1 future candidates ... done [01:56:47.931] | : . ' , ; ` | Number of registered futures: 1 [01:56:47.932] | : . ' , ; ` Polling for a free worker ... done [01:56:47.932] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.932] | : . ' , ; ` | Listing all futures [01:56:47.933] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.933] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.933] | : . ' , ; ` Total time: 0.0661098957061768 [01:56:47.934] | : . ' , ; requestNode() ... done [01:56:47.934] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.934] | : . ' , ; ` Listing all futures [01:56:47.934] | : . ' , ; ` Number of registered futures: 0 [01:56:47.935] | : . ' , ; FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.935] | : . ' , ; avail: [n=1] '1' [01:56:47.935] | : . ' , ; Index of first available worker: 1 [01:56:47.935] | : . ' , ; Validate that the worker is functional ... [01:56:47.936] | : . ' , ; ` Worker is functional [01:56:47.937] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... [01:56:47.937] | : . ' , ; ` | Listing all futures [01:56:47.937] | : . ' , ; ` | Number of registered futures: 0 [01:56:47.937] | : . ' , ; ` FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'list', earlySignal = 0) ... done [01:56:47.937] | : . ' , ; Validate that the worker is functional ... done [01:56:47.937] | : . ' , requestWorker() ... done [01:56:47.938] | : . ' , cluster node index: 1 [01:56:47.938] | : . ' launchFuture() for ClusterFutureBackend ... done [01:56:47.938] | : . ' eraseGlobalEnvironment() ... [01:56:47.938] | : . ' eraseGlobalEnvironment() ... done [01:56:47.939] | : . ' launchFuture() ... [01:56:47.939] | : . ' , cluster node index: 1 [01:56:47.939] | : . ' , getFutureData() ... [01:56:47.939] | : . ' , ; getFutureCore() ... [01:56:47.939] | : . ' , ; ` Packages needed by the future expression (n = 0): [01:56:47.939] | : . ' , ; getFutureCore() ... done [01:56:47.940] | : . ' , ; getFutureCapture() ... [01:56:47.940] | : . ' , ; getFutureCapture() ... done [01:56:47.940] | : . ' , ; getFutureContext() ... [01:56:47.940] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.940] | : . ' , ; ` | Getting stack without first backend: [n=0] [01:56:47.940] | : . ' , ; ` plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:47.941] | : . ' , ; ` Packages needed by future backend (n = 0): [01:56:47.941] | : . ' , ; getFutureContext() ... done [01:56:47.941] | : . ' , getFutureData() ... done [01:56:47.942] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... [01:56:47.942] | : . ' , ; Appended future to position #1 [01:56:47.942] | : . ' , ; Number of registered futures: 1 [01:56:47.942] | : . ' , FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'add', earlySignal = 0) ... done [01:56:47.942] | : . ' launchFuture() ... done [01:56:47.943] | : . ' ClusterFuture started [01:56:47.943] | : . Launching futures ... done [01:56:47.943] | : Using ClusterFutureBackend ... done [01:56:47.943] | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [01:56:47.943] | run() for 'Future' (NULL) ... done [01:56:47.943] future(..., label = NULL) ... done [01:56:47.944] value() for list ... [01:56:47.944] | recursive: 0 [01:56:47.944] | reduce: FALSE [01:56:47.944] | length: 2 [01:56:47.944] | Number of remaining objects: 2 [01:56:47.945] | checking value #1 ... [01:56:47.945] | : 'obj' is ClusterFuture [01:56:47.945] | : ClusterFuture #1 [01:56:47.945] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:47.945] | : . nx: 2 [01:56:47.945] | : . relay: TRUE [01:56:47.946] | : . stdout: TRUE [01:56:47.946] | : . signal: TRUE [01:56:47.946] | : . resignal: FALSE [01:56:47.946] | : . force: TRUE [01:56:47.946] | : . relayed: [n=2] FALSE, FALSE [01:56:47.946] | : . queued futures: [n=2] FALSE, FALSE [01:56:47.947] | : . until=1 [01:56:47.947] | : . relaying element #1 [01:56:47.947] | : . value() for ClusterFuture (NULL) ... [01:56:47.947] | : . ' result() for ClusterFuture ... [01:56:47.947] | : . ' , result already collected: FutureResult [01:56:47.947] | : . ' result() for ClusterFuture ... done [01:56:47.948] | : . ' result() for ClusterFuture ... [01:56:47.948] | : . ' , result already collected: FutureResult [01:56:47.948] | : . ' result() for ClusterFuture ... done [01:56:47.948] | : . ' relay stdout ... [01:56:47.948] | : . ' relay stdout ... done [01:56:47.948] | : . ' check for misuse ... [01:56:47.949] | : . ' check for misuse ... done [01:56:47.949] | : . value() for ClusterFuture (NULL) ... done [01:56:47.949] | : . result() for ClusterFuture ... [01:56:47.949] | : . ' result already collected: FutureResult [01:56:47.949] | : . result() for ClusterFuture ... done [01:56:47.949] | : . result() for ClusterFuture ... [01:56:47.950] | : . ' result already collected: FutureResult [01:56:47.950] | : . result() for ClusterFuture ... done [01:56:47.950] | : . relayed: [n=2] TRUE, FALSE [01:56:47.950] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.950] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:47.950] | : value(, ...) ... [01:56:47.951] | : . value() for ClusterFuture (NULL) ... [01:56:47.951] | : . ' result() for ClusterFuture ... [01:56:47.951] | : . ' , result already collected: FutureResult [01:56:47.951] | : . ' result() for ClusterFuture ... done [01:56:47.951] | : . ' result() for ClusterFuture ... [01:56:47.951] | : . ' , result already collected: FutureResult [01:56:47.952] | : . ' result() for ClusterFuture ... done [01:56:47.952] | : . ' check for misuse ... [01:56:47.952] | : . ' check for misuse ... done [01:56:47.952] | : . value() for ClusterFuture (NULL) ... done [01:56:47.952] | : . value: [01:56:47.952] | : value(, ...) ... done [01:56:47.953] | : signalConditionsASAP(ClusterFuture, pos=1) ... [01:56:47.953] | : . nx: 2 [01:56:47.953] | : . relay: TRUE [01:56:47.953] | : . stdout: TRUE [01:56:47.953] | : . signal: TRUE [01:56:47.953] | : . resignal: FALSE [01:56:47.953] | : . force: TRUE [01:56:47.954] | : . relayed: [n=2] TRUE, FALSE [01:56:47.954] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.954] | : . until=2 [01:56:47.954] | : . relaying element #2 [01:56:47.954] | : . relayed: [n=2] TRUE, FALSE [01:56:47.954] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.955] | : signalConditionsASAP(ClusterFuture, pos=1) ... done [01:56:47.955] | : resolve() for ClusterFuture ... [01:56:47.955] | : resolve() for ClusterFuture ... done [01:56:47.955] | : length: 1 (resolved future 1) [01:56:47.955] | checking value #1 ... done [01:56:47.955] | checking value #2 ... [01:56:47.956] | : 'obj' is ClusterFuture [01:56:47.956] | : resolved() for ClusterFuture (NULL) ... [01:56:47.956] | : . Cluster node socket connection: index=4, id=193 [01:56:47.956] | : . assertValidConnection() ... [01:56:47.957] | : . ' cluster node index: 1 [01:56:47.957] | : . assertValidConnection() ... done [01:56:47.957] | : . receiveMessageFromWorker() for ClusterFuture ... [01:56:47.957] | : . ' cluster node index: 1 [01:56:47.958] | : . ' [1] TRUE [01:56:47.958] | : . ' received data: [01:56:47.958] | : . ' List of 5 [01:56:47.958] | : . ' $ type : chr "VALUE" [01:56:47.958] | : . ' $ value :List of 14 [01:56:47.958] | : . ' ..$ value : int 2 [01:56:47.958] | : . ' ..$ visible : logi TRUE [01:56:47.958] | : . ' ..$ stdout : chr "" [01:56:47.958] | : . ' ..$ conditions : list() [01:56:47.958] | : . ' ..$ rng : logi FALSE [01:56:47.958] | : . ' ..$ misuseGlobalEnv : NULL [01:56:47.958] | : . ' ..$ misuseConnections:List of 3 [01:56:47.958] | : . ' .. ..$ added : NULL [01:56:47.958] | : . ' .. ..$ removed : NULL [01:56:47.958] | : . ' .. ..$ replaced: NULL [01:56:47.958] | : . ' ..$ misuseDevices : NULL [01:56:47.958] | : . ' ..$ started : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.958] | : . ' ..$ finished : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.958] | : . ' ..$ uuid : chr [1:2] "3ef753ecd9f0c34953e419b61b9a685c" "6" [01:56:47.958] | : . ' ..$ session_uuid : chr "ef14fa5c4caf33010ceab7f6650d8406" [01:56:47.958] | : . ' .. ..- attr(*, "source")=List of 5 [01:56:47.958] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [01:56:47.958] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [01:56:47.958] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [01:56:47.958] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [01:56:47.958] | : . ' .. .. ..$ pid : int 50080 [01:56:47.958] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2025-05-09 01:56:47" [01:56:47.958] | : . ' .. .. ..$ random: int 2147483647 [01:56:47.958] | : . ' ..$ r_info :List of 4 [01:56:47.958] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [01:56:47.958] | : . ' .. .. ..$ : int [1:3] 4 6 0 [01:56:47.958] | : . ' .. ..$ os : chr "windows" [01:56:47.958] | : . ' .. ..$ os_name : chr "Windows" [01:56:47.958] | : . ' .. ..$ captures_utf8: logi TRUE [01:56:47.958] | : . ' ..$ version : chr "1.8" [01:56:47.958] | : . ' ..- attr(*, "class")= chr "FutureResult" [01:56:47.958] | : . ' $ success: logi TRUE [01:56:47.958] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [01:56:47.958] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [01:56:47.958] | : . ' $ tag : NULL [01:56:47.971] | : . ' Received FutureResult [01:56:47.972] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... [01:56:47.972] | : . ' , Removed future from position #1 [01:56:47.972] | : . ' , Number of registered futures: 0 [01:56:47.972] | : . ' FutureRegistry('workers-37e5752128e4483a3ce8b7fda2d6c13d', action = 'remove', earlySignal = 0) ... done [01:56:47.972] | : . ' Erased future from future backend [01:56:47.973] | : . ' result() for ClusterFuture ... [01:56:47.973] | : . ' , result already collected: FutureResult [01:56:47.973] | : . ' result() for ClusterFuture ... done [01:56:47.973] | : . receiveMessageFromWorker() for ClusterFuture ... done [01:56:47.973] | : . receiveMessageFromWorker() returned object of class FutureResult; resolved [01:56:47.973] | : resolved() for ClusterFuture (NULL) ... done [01:56:47.974] | : assertFutureResult() ... [01:56:47.974] | : . Future uuid: 3ef753ecd9f0c34953e419b61b9a685c[01:56:47.974] | : . Future uuid: 6 [01:56:47.974] | : . identical; success [01:56:47.974] | : assertFutureResult() ... done [01:56:47.974] | : ClusterFuture #2 [01:56:47.974] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:47.975] | : . nx: 2 [01:56:47.975] | : . relay: TRUE [01:56:47.975] | : . stdout: TRUE [01:56:47.975] | : . signal: TRUE [01:56:47.975] | : . resignal: FALSE [01:56:47.975] | : . force: TRUE [01:56:47.975] | : . relayed: [n=2] TRUE, FALSE [01:56:47.976] | : . queued futures: [n=2] TRUE, FALSE [01:56:47.976] | : . until=2 [01:56:47.976] | : . relaying element #2 [01:56:47.976] | : . value() for ClusterFuture (NULL) ... [01:56:47.976] | : . ' result() for ClusterFuture ... [01:56:47.976] | : . ' , result already collected: FutureResult [01:56:47.977] | : . ' result() for ClusterFuture ... done [01:56:47.977] | : . ' result() for ClusterFuture ... [01:56:47.977] | : . ' , result already collected: FutureResult [01:56:47.977] | : . ' result() for ClusterFuture ... done [01:56:47.977] | : . ' relay stdout ... [01:56:47.977] | : . ' relay stdout ... done [01:56:47.978] | : . ' check for misuse ... [01:56:47.978] | : . ' check for misuse ... done [01:56:47.978] | : . value() for ClusterFuture (NULL) ... done [01:56:47.978] | : . result() for ClusterFuture ... [01:56:47.978] | : . ' result already collected: FutureResult [01:56:47.979] | : . result() for ClusterFuture ... done [01:56:47.979] | : . result() for ClusterFuture ... [01:56:47.979] | : . ' result already collected: FutureResult [01:56:47.979] | : . result() for ClusterFuture ... done [01:56:47.979] | : . relayed: [n=2] TRUE, TRUE [01:56:47.979] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.979] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:47.980] | : value(, ...) ... [01:56:47.980] | : . value() for ClusterFuture (NULL) ... [01:56:47.980] | : . ' result() for ClusterFuture ... [01:56:47.980] | : . ' , result already collected: FutureResult [01:56:47.980] | : . ' result() for ClusterFuture ... done [01:56:47.980] | : . ' result() for ClusterFuture ... [01:56:47.981] | : . ' , result already collected: FutureResult [01:56:47.981] | : . ' result() for ClusterFuture ... done [01:56:47.981] | : . ' check for misuse ... [01:56:47.981] | : . ' check for misuse ... done [01:56:47.981] | : . value() for ClusterFuture (NULL) ... done [01:56:47.981] | : . value: [01:56:47.982] | : value(, ...) ... done [01:56:47.982] | : signalConditionsASAP(ClusterFuture, pos=2) ... [01:56:47.982] | : . nx: 2 [01:56:47.982] | : . relay: TRUE [01:56:47.982] | : . stdout: TRUE [01:56:47.982] | : . signal: TRUE [01:56:47.982] | : . resignal: FALSE [01:56:47.983] | : . force: TRUE [01:56:47.983] | : . relayed: [n=2] TRUE, TRUE [01:56:47.983] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.983] | : . until=2 [01:56:47.983] | : . relayed: [n=2] TRUE, TRUE [01:56:47.984] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.984] | : signalConditionsASAP(ClusterFuture, pos=2) ... done [01:56:47.984] | : resolve() for ClusterFuture ... [01:56:47.984] | : resolve() for ClusterFuture ... done [01:56:47.985] | : length: 0 (resolved future 2) [01:56:47.985] | checking value #2 ... done [01:56:47.985] | Relaying remaining futures ... [01:56:47.985] | : signalConditionsASAP(NULL, pos=0) ... [01:56:47.986] | : . nx: 2 [01:56:47.986] | : . relay: TRUE [01:56:47.986] | : . stdout: TRUE [01:56:47.986] | : . signal: TRUE [01:56:47.986] | : . resignal: FALSE [01:56:47.986] | : . force: TRUE [01:56:47.987] | : . relayed: [n=2] TRUE, TRUE [01:56:47.987] | : . queued futures: [n=2] TRUE, TRUE - flush all [01:56:47.987] | : . relayed: [n=2] TRUE, TRUE [01:56:47.987] | : . queued futures: [n=2] TRUE, TRUE [01:56:47.987] | : signalConditionsASAP(NULL, pos=0) ... done [01:56:47.988] | Relaying remaining futures ... done [01:56:47.988] value() for list ... done > plan(sequential) [01:56:47.993] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:47.993] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [01:56:47.993] | : all.equal() for FutureStrategyList ... [01:56:47.994] | : . New stack: [01:56:47.995] | : . List of 1 [01:56:47.995] | : . $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:47.995] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:47.995] | : . ..- attr(*, "init")= logi TRUE [01:56:47.995] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:47.995] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:47.995] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:47.995] | : . ..- attr(*, "call")= language plan(sequential) [01:56:47.995] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:47.999] | : . Old stack: [01:56:47.999] | : . List of 1 [01:56:47.999] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:47.999] | : . envir = parent.frame()) [01:56:47.999] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:47.999] | : . ..- attr(*, "init")= chr "done" [01:56:47.999] | : . ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.999] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.999] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.999] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.999] | : . ..- attr(*, "tweaks")=List of 1 [01:56:47.999] | : . .. ..$ workers:List of 1 [01:56:47.999] | : . .. .. ..$ :List of 3 [01:56:47.999] | : . .. .. .. ..$ con : 'sockconn' int 4 [01:56:47.999] | : . .. .. .. .. ..- attr(*, "conn_id")= [01:56:47.999] | : . .. .. .. ..$ host: chr "localhost" [01:56:47.999] | : . .. .. .. ..$ rank: num 0 [01:56:47.999] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:47.999] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:47.999] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:47.999] | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:47.999] | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:47.999] | : . earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:47.999] | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:47.999] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:48.006] | : . Not identical [01:56:48.006] | : . all.equal() for future ... [01:56:48.006] | : . ' List of 2 [01:56:48.006] | : . ' $ target :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:48.006] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:48.006] | : . ' ..- attr(*, "init")= logi TRUE [01:56:48.006] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:48.006] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.006] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.006] | : . ' ..- attr(*, "call")= language plan(sequential) [01:56:48.006] | : . ' $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [01:56:48.006] | : . ' envir = parent.frame()) [01:56:48.006] | : . ' ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [01:56:48.006] | : . ' ..- attr(*, "init")= chr "done" [01:56:48.006] | : . ' ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:48.006] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:48.006] | : . ' .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:48.006] | : . ' ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:48.006] | : . ' ..- attr(*, "tweaks")=List of 1 [01:56:48.006] | : . ' .. ..$ workers:List of 1 [01:56:48.006] | : . ' .. .. ..$ :List of 3 [01:56:48.006] | : . ' .. .. .. ..$ con : 'sockconn' int 4 [01:56:48.006] | : . ' .. .. .. .. ..- attr(*, "conn_id")= [01:56:48.006] | : . ' .. .. .. ..$ host: chr "localhost" [01:56:48.006] | : . ' .. .. .. ..$ rank: num 0 [01:56:48.006] | : . ' .. .. .. ..- attr(*, "class")= chr "SOCKnode" [01:56:48.006] | : . ' .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [01:56:48.006] | : . ' ..- attr(*, "call")= language plan(cluster, workers = cl) [01:56:48.006] | : . ' ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [01:56:48.006] | : . ' .. ..- attr(*, "factory")=function (workers = availableWorkers(constraints = "connections"), gc = TRUE, [01:56:48.006] | : . ' earlySignal = TRUE, interrupts = FALSE, persistent = FALSE, ...) [01:56:48.006] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [01:56:48.016] | : . ' Formals differ [01:56:48.017] | : . all.equal() for future ... done [01:56:48.017] | : . Future strategies differ at level 1 [01:56:48.017] | : all.equal() for FutureStrategyList ... done [01:56:48.017] | : plan(): Setting new future backend stack: [01:56:48.017] | : List of future strategies: [01:56:48.017] | : 1. sequential: [01:56:48.017] | : - args: function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame(), workers = "") [01:56:48.017] | : - tweaked: FALSE [01:56:48.017] | : - call: plan(sequential) [01:56:48.018] | : List of 1 [01:56:48.018] | : $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:48.018] | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:48.018] | : ..- attr(*, "init")= logi TRUE [01:56:48.018] | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:48.018] | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.018] | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.018] | : ..- attr(*, "call")= language plan(sequential) [01:56:48.018] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [01:56:48.021] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [01:56:48.021] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:48.021] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [01:56:48.022] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:48.022] | : . stopWorkers() for ClusterFutureBackend ... [01:56:48.022] | : . ' Interrupt active futures ... [01:56:48.022] | : . ' , Number of futures: 0 [01:56:48.023] | : . ' Interrupt active futures ... done [01:56:48.023] | : . ' Clear future registry ... [01:56:48.023] | : . ' Clear future registry ... done [01:56:48.024] | : . ' Stop cluster workers ... [01:56:48.024] | : . ' , Stopping existing cluster ... [01:56:48.024] | : . ' , ; No pre-existing cluster. Skipping [01:56:48.024] | : . ' , Stopping existing cluster ... done [01:56:48.024] | : . ' Stop cluster workers ... done [01:56:48.024] | : . stopWorkers() for ClusterFutureBackend ... done [01:56:48.025] | : . Legacy shutdown of cluster workers ... [01:56:48.025] | : . ' Stopping existing cluster ... [01:56:48.025] | : . ' , No pre-existing cluster. Skipping [01:56:48.025] | : . ' Stopping existing cluster ... done [01:56:48.025] | : . Legacy shutdown of cluster workers ... done [01:56:48.025] | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [01:56:48.026] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [01:56:48.026] | : . function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [01:56:48.026] | : . - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [01:56:48.026] | : . - attr(*, "init")= logi TRUE [01:56:48.026] | : . - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [01:56:48.026] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.026] | : . - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [01:56:48.026] | : . - attr(*, "call")= language plan(sequential) [01:56:48.028] | : . init: TRUE [01:56:48.028] | : . makeFutureBackend() ... [01:56:48.029] | : . ' Backend function: <'NULL'> [01:56:48.029] | : . ' Evaluator tweak arguments: [n=0] [01:56:48.029] | : . ' list() [01:56:48.029] | : . ' Evaluator formal arguments: [n=2] [01:56:48.029] | : . ' list() [01:56:48.030] | : . ' Backend factory arguments: [n=2] [01:56:48.030] | : . ' Dotted pair list of 2 [01:56:48.030] | : . ' $ gc : logi FALSE [01:56:48.030] | : . ' $ earlySignal: logi FALSE [01:56:48.031] | : . ' Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [01:56:48.031] | : . makeFutureBackend() ... done [01:56:48.032] | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [01:56:48.032] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [01:56:48.032] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [01:56:48.032] | : plan(): nbrOfWorkers() = 1 [01:56:48.032] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [01:56:48.033] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done > message("all.equal() for cluster backend ... done") all.equal() for cluster backend ... done Sourcing 6 epilogue scripts ... 01/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/001.undo-future.R' 02/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/002.undo-state.R' Failed to undo environment variables: - Expected environment variables: [n=213] '!ExitCode', 'ALLUSERSPROFILE', 'APPDATA', 'BIBINPUTS', 'BINDIR', 'BSTINPUTS', 'COMMONPROGRAMFILES', 'COMPUTERNAME', 'COMSPEC', 'CURL_CA_BUNDLE', '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', 'TEXINPUTS', 'TMP', 'TMPDIR', 'USERDOMAIN', 'USERDOMAIN_ROAMINGPROFILE', 'USERNAME', 'USERPROFILE', 'WINDIR', '_', '_R_CHECK_AUTOCONF_', '_R_CHECK_BOGUS_RETURN_', '_R_CHECK_BROWSER_NONINTERACTIVE_', '_R_CHECK_BUILD_VIGNETTES_SEPARATELY_', '_R_CHECK_CODETOOLS_PROFILE_', '_R_CHECK_CODE_ASSIGN_TO_GLOBALENV_', '_R_CHECK_CODE_ATTACH_', '_R_CHECK_CODE_CLASS_IS_STRING_', '_R_CHECK_CODE_DATA_INTO_GLOBALENV_', '_R_CHECK_CODE_USAGE_VIA_NAMESPACES_', '_R_CHECK_CODE_USAGE_WITHOUT_LOADING_', '_R_CHECK_CODE_USAGE_WITH_ONLY_BASE_ATTACHED_', '_R_CHECK_CODOC_VARIABLES_IN_USAGES_', '_R_CHECK_COMPACT_DATA2_', '_R_CHECK_COMPILATION_FLAGS_', '_R_CHECK_CONNECTIONS_LEFT_OPEN_', '_R_CHECK_CRAN_INCOMING_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_MAYBE_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_START_', '_R_CHECK_CRAN_INCOMING_CHECK_FILE_URIS_', '_R_CHECK_CRAN_INCOMING_CHECK_URLS_IN_PARALLEL_', '_R_CHECK_CRAN_INCOMING_NOTE_GNU_MAKE_', '_R_CHECK_CRAN_INCOMING_REMOTE_', '_R_CHECK_CRAN_INCOMING_USE_ASPELL_', '_R_CHECK_DATALIST_', '_R_CHECK_DEPRECATED_DEFUNCT_', '_R_CHECK_DOC_SIZES2_', '_R_CHECK_DOT_FIRSTLIB_', '_R_CHECK_DOT_INTERNAL_', '_R_CHECK_EXAMPLE_TIMING_THRESHOLD_', '_R_CHECK_EXECUTABLES_', '_R_CHECK_EXECUTABLES_EXCLUSIONS_', '_R_CHECK_FF_CALLS_', '_R_CHECK_FF_DUP_', '_R_CHECK_FORCE_SUGGESTS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_LEEWAY_', '_R_CHECK_HAVE_MYSQL_', '_R_CHECK_HAVE_ODBC_', '_R_CHECK_HAVE_PERL_', '_R_CHECK_HAVE_POSTGRES_', '_R_CHECK_INSTALL_DEPENDS_', '_R_CHECK_INTERNALS2_', '_R_CHECK_LENGTH_1_CONDITION_', '_R_CHECK_LICENSE_', '_R_CHECK_LIMIT_CORES_', '_R_CHECK_LOG_USE_INFO_', '_R_CHECK_MATRIX_DATA_', '_R_CHECK_MBCS_CONVERSION_FAILURE_', '_R_CHECK_NATIVE_ROUTINE_REGISTRATION_', '_R_CHECK_NEWS_IN_PLAIN_TEXT_', '_R_CHECK_NO_RECOMMENDED_', '_R_CHECK_NO_STOP_ON_TEST_ERROR_', '_R_CHECK_ORPHANED_', '_R_CHECK_OVERWRITE_REGISTERED_S3_METHODS_', '_R_CHECK_PACKAGES_USED_IGNORE_UNUSED_IMPORTS_', '_R_CHECK_PACKAGES_USED_IN_TESTS_USE_SUBDIRS_', '_R_CHECK_PACKAGE_DATASETS_SUPPRESS_NOTES_', '_R_CHECK_PACKAGE_NAME_', '_R_CHECK_PKG_SIZES_', '_R_CHECK_PKG_SIZES_THRESHOLD_', '_R_CHECK_PRAGMAS_', '_R_CHECK_RD_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_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_SPECIAL__', 'maj.version', 'nextArg--timingsnextArg--install', 'tempdirname' - Environment variables still there: [n=0] - Environment variables missing: [n=1] 'MAKEFLAGS' Differences environment variable by environment variable: 03/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/090.gc.R' 04/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/099.session_info.R' 05/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/995.detritus-connections.R' 06/06 epilogue script 'D:/RCompile/CRANincoming/R-devel/lib/future/testme/_epilogue/999.detritus-files.R' Skipping, because path appears not to be an 'R CMD check' folder: 'D:/temp/2025_05_09_01_55_17_23336' Sourcing 6 epilogue scripts ... done Test time: user.self=0.8s, sys.self=0.01s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'cluster-connection-clashes' ... success > > proc.time() user system elapsed 0.96 0.07 1.92