R version 4.5.0 RC (2025-04-04 r88126 ucrt) -- "How About a Twenty-Six" 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 incl/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) [08:47:04.573] Option 'future.startup.script': TRUE [08:47:04.574] Future startup scripts considered: '.future.R', '~/.future.R' [08:47:04.575] 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 [08:47:04.834] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:04.836] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [08:47:04.837] | : all.equal() for FutureStrategyList ... [08:47:04.837] | : . New stack: [08:47:04.837] | : . List of 1 [08:47:04.837] | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:04.837] | : . envir = parent.frame()) [08:47:04.837] | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:04.837] | : . ..- attr(*, "init")= logi TRUE [08:47:04.837] | : . ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:04.837] | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:04.837] | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.837] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.837] | : . ..- attr(*, "tweaks")=List of 1 [08:47:04.837] | : . .. ..$ workers:List of 1 [08:47:04.837] | : . .. .. ..$ :List of 3 [08:47:04.837] | : . .. .. .. ..$ con : 'sockconn' int 4 [08:47:04.837] | : . .. .. .. .. ..- attr(*, "conn_id")= [08:47:04.837] | : . .. .. .. ..$ host: chr "localhost" [08:47:04.837] | : . .. .. .. ..$ rank: num 0 [08:47:04.837] | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:04.837] | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:04.837] | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:04.837] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:04.854] | : . Old stack: [08:47:04.854] | : . List of 1 [08:47:04.854] | : . $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:04.854] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:04.854] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:04.854] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:04.854] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:04.854] | : . ..- attr(*, "call")= language plan(sequential) [08:47:04.854] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:04.857] | : . Not identical [08:47:04.857] | : . all.equal() for future ... [08:47:04.858] | : . | List of 2 [08:47:04.858] | : . | $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:04.858] | : . | envir = parent.frame()) [08:47:04.858] | : . | ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:04.858] | : . | ..- attr(*, "init")= logi TRUE [08:47:04.858] | : . | ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:04.858] | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:04.858] | : . | .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.858] | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.858] | : . | ..- attr(*, "tweaks")=List of 1 [08:47:04.858] | : . | .. ..$ workers:List of 1 [08:47:04.858] | : . | .. .. ..$ :List of 3 [08:47:04.858] | : . | .. .. .. ..$ con : 'sockconn' int 4 [08:47:04.858] | : . | .. .. .. .. ..- attr(*, "conn_id")= [08:47:04.858] | : . | .. .. .. ..$ host: chr "localhost" [08:47:04.858] | : . | .. .. .. ..$ rank: num 0 [08:47:04.858] | : . | .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:04.858] | : . | .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:04.858] | : . | ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:04.858] | : . | $ current:function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:04.858] | : . | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:04.858] | : . | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:04.858] | : . | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:04.858] | : . | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:04.858] | : . | ..- attr(*, "call")= language plan(sequential) [08:47:04.871] | : . | Formals differ [08:47:04.871] | : . all.equal() for future ... done [08:47:04.872] | : . Future strategies differ at level 1 [08:47:04.872] | : all.equal() for FutureStrategyList ... done [08:47:04.872] | : plan(): Setting new future strategy stack: [08:47:04.873] | : List of future strategies: [08:47:04.873] | : 1. cluster: [08:47:04.873] | : - args: function (..., workers = "", envir = parent.frame()) [08:47:04.873] | : - tweaked: TRUE [08:47:04.873] | : - call: plan(cluster, workers = cl) [08:47:04.874] | : List of 1 [08:47:04.874] | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:04.874] | : envir = parent.frame()) [08:47:04.874] | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:04.874] | : ..- attr(*, "init")= logi TRUE [08:47:04.874] | : ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:04.874] | : interrupts = FALSE, persistent = FALSE, ...) [08:47:04.874] | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.874] | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.874] | : ..- attr(*, "tweaks")=List of 1 [08:47:04.874] | : .. ..$ workers:List of 1 [08:47:04.874] | : .. .. ..$ :List of 3 [08:47:04.874] | : .. .. .. ..$ con : 'sockconn' int 4 [08:47:04.874] | : .. .. .. .. ..- attr(*, "conn_id")= [08:47:04.874] | : .. .. .. ..$ host: chr "localhost" [08:47:04.874] | : .. .. .. ..$ rank: num 0 [08:47:04.874] | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:04.874] | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:04.874] | : ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:04.874] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:04.885] | : plan(): plan_cleanup('sequential', 'uniprocess', 'future', 'function', cleanup = NA) ... [08:47:04.885] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:04.885] | : . | Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [08:47:04.886] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:04.886] | : . Legacy shutdown of cluster workers ... [08:47:04.889] | : . | Stopping existing cluster ... [08:47:04.889] | : . | : No pre-existing cluster. Skipping [08:47:04.890] | : . | Stopping existing cluster ... done [08:47:04.890] | : . Legacy shutdown of cluster workers ... done [08:47:04.890] | : plan(): plan_cleanup('sequential', 'uniprocess', 'future', 'function', cleanup = NA) ... done [08:47:04.891] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [08:47:04.891] | : . function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:04.891] | : . envir = parent.frame()) [08:47:04.891] | : . - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:04.891] | : . - attr(*, "init")= logi TRUE [08:47:04.891] | : . - attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:04.891] | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:04.891] | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.891] | : . - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:04.891] | : . - attr(*, "tweaks")=List of 1 [08:47:04.891] | : . ..$ workers:List of 1 [08:47:04.891] | : . .. ..$ :List of 3 [08:47:04.891] | : . .. .. ..$ con : 'sockconn' int 4 [08:47:04.891] | : . .. .. .. ..- attr(*, "conn_id")= [08:47:04.891] | : . .. .. ..$ host: chr "localhost" [08:47:04.891] | : . .. .. ..$ rank: num 0 [08:47:04.891] | : . .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:04.891] | : . .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:04.891] | : . - attr(*, "call")= language plan(cluster, workers = cl) [08:47:04.900] | : . init: TRUE [08:47:04.901] | : . makeFutureBackend() ... [08:47:04.901] | : . | Backend function: <'NULL'> [08:47:04.901] | : . | ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [08:47:04.901] | : . | : Stopping existing cluster ... [08:47:04.902] | : . | : . No pre-existing cluster. Skipping [08:47:04.902] | : . | : Stopping existing cluster ... done [08:47:04.902] | : . | : Number of workers: 1 [08:47:04.903] | : . | : Generated workers UUID [08:47:04.903] | : . | : Workers UUID: '381ebb526f9eb2d90230a3c7b34be202' [08:47:04.904] | : . | ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [08:47:04.904] | : . | Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [08:47:04.904] | : . makeFutureBackend() ... done [08:47:04.905] | : plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [08:47:04.905] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:04.905] | : . Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:04.906] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:04.906] | : plan(): nbrOfWorkers() = 1 [08:47:04.906] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [08:47:04.907] plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:04.908] getGlobalsAndPackages() ... [08:47:04.908] | Searching for globals ... [08:47:04.916] | : globals found: [3] '[[', 'X', 'i' [08:47:04.916] | Searching for globals ... DONE [08:47:04.916] | Resolving globals: FALSE [08:47:04.917] | Search for packages associated with the globals ... [08:47:04.917] | : Packages associated with globals: [1] 'base' [08:47:04.918] | : Packages: [1] 'base' [08:47:04.918] | Search for packages associated with the globals ... DONE [08:47:04.919] | Packages after dropping 'base': [0] [08:47:04.919] | globals: [2] 'X', 'i' [08:47:04.919] | packages: [0] [08:47:04.920] getGlobalsAndPackages() ... done [08:47:04.921] run() for Future ('Future') ... [08:47:04.921] | state: 'created' [08:47:04.922] | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:04.922] | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:04.922] | Using ClusterFutureBackend ... [08:47:04.923] | : Launching futures ... [08:47:04.924] | : . launchFuture() for ClusterFutureBackend ... [08:47:04.924] | : . | Workers: [n=1] [08:47:04.924] | : . | socket cluster with 1 nodes on host 'localhost' [08:47:04.925] | : . | FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:04.925] | : . | requestWorker() ... [08:47:04.925] | : . | : requestNode() ... [08:47:04.926] | : . | : . Number of workers: 1 [08:47:04.926] | : . | : . Polling for a free worker ... [08:47:04.926] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:04.927] | : . | : . | : Created empty registry 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:04.927] | : . | : . | : Listing all futures [08:47:04.927] | : . | : . | : Number of registered futures: 0 [08:47:04.927] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:04.928] | : . | : . | Total time: 0 [08:47:04.928] | : . | : . Polling for a free worker ... done [08:47:04.928] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:04.929] | : . | : . | Listing all futures [08:47:04.929] | : . | : . | Number of registered futures: 0 [08:47:04.929] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:04.929] | : . | : . Index of first available worker: 1 [08:47:04.930] | : . | : . Validate that the worker is functional ... [08:47:05.057] | : . | : . | Worker is functional [08:47:05.058] | : . | : . requestNode() ... done [08:47:05.058] | : . | : . cluster node index: 1 [08:47:05.058] | : . | : requestWorker() ... done [08:47:05.058] | : . | : eraseGlobalEnvironment() ... [08:47:05.059] | : . | : eraseGlobalEnvironment() ... done [08:47:05.059] | : . | : launchFuture() ... [08:47:05.060] | : . | : . cluster node index: 1 [08:47:05.060] | : . | : . getFutureData() ... [08:47:05.061] | : . | : . | getFutureCore() ... [08:47:05.061] | : . | : . | : Packages needed by the future expression (n = 0): [08:47:05.061] | : . | : . | getFutureCore() ... done [08:47:05.066] | : . | : . | getFutureCapture() ... [08:47:05.067] | : . | : . | getFutureCapture() ... done [08:47:05.067] | : . | : . | getFutureContext() ... [08:47:05.067] | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.068] | : . | : . | : . Getting stack without first strategy: [n=0] [08:47:05.068] | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.068] | : . | : . | : Packages needed by future strategies (n = 0): [08:47:05.068] | : . | : . | getFutureContext() ... done [08:47:05.068] | : . | : . getFutureData() ... done [08:47:05.069] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:05.070] | : . | : . | Appended future to position #1 [08:47:05.070] | : . | : . | Number of registered futures: 1 [08:47:05.070] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:05.070] | : . | : launchFuture() ... done [08:47:05.070] | : . | : ClusterFuture started [08:47:05.070] | : . | launchFuture() for ClusterFutureBackend ... done [08:47:05.071] | : . Launching futures ... done [08:47:05.071] | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:05.071] | : Using ClusterFutureBackend ... done [08:47:05.071] | run() for Future ('ClusterFuture') ... done [08:47:05.072] | getGlobalsAndPackages() ... [08:47:05.072] | : Searching for globals ... [08:47:05.073] | : . globals found: [3] '[[', 'X', 'i' [08:47:05.073] | : Searching for globals ... DONE [08:47:05.074] | : Resolving globals: FALSE [08:47:05.074] | : Search for packages associated with the globals ... [08:47:05.074] | : . Packages associated with globals: [1] 'base' [08:47:05.075] | : . Packages: [1] 'base' [08:47:05.075] | : Search for packages associated with the globals ... DONE [08:47:05.075] | : Packages after dropping 'base': [0] [08:47:05.075] | : globals: [2] 'X', 'i' [08:47:05.076] | : packages: [0] [08:47:05.076] | getGlobalsAndPackages() ... done [08:47:05.076] | run() for Future ('Future') ... [08:47:05.076] | : state: 'created' [08:47:05.077] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.077] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.077] | : Using ClusterFutureBackend ... [08:47:05.077] | : . Launching futures ... [08:47:05.078] | : . | launchFuture() for ClusterFutureBackend ... [08:47:05.078] | : . | : Workers: [n=1] [08:47:05.078] | : . | : socket cluster with 1 nodes on host 'localhost' [08:47:05.079] | : . | : FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:05.079] | : . | : requestWorker() ... [08:47:05.080] | : . | : . requestNode() ... [08:47:05.080] | : . | : . | Number of workers: 1 [08:47:05.080] | : . | : . | Polling for a free worker ... [08:47:05.080] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.081] | : . | : . | : . Listing all futures [08:47:05.081] | : . | : . | : . Number of registered futures: 1 [08:47:05.081] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.082] | : . | : . | : Poll #1 (0): usedNodes() = 1, workers = 1 [08:47:05.093] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... [08:47:05.093] | : . | : . | : . collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... [08:47:05.094] | : . | : . | : . | isFutureResolved() ... [08:47:05.095] | : . | : . | : . | : assertValidConnection() ... [08:47:05.096] | : . | : . | : . | : . cluster node index: 1 [08:47:05.096] | : . | : . | : . | : assertValidConnection() ... done [08:47:05.159] | : . | : . | : . | : receiveMessageFromWorker() for ClusterFuture ... [08:47:05.159] | : . | : . | : . | : . cluster node index: 1 [08:47:05.160] | : . | : . | : . | : . [1] TRUE [08:47:05.160] | : . | : . | : . | : . received data: [08:47:05.160] | : . | : . | : . | : . List of 5 [08:47:05.160] | : . | : . | : . | : . $ type : chr "VALUE" [08:47:05.160] | : . | : . | : . | : . $ value :List of 13 [08:47:05.160] | : . | : . | : . | : . ..$ value : int 1 [08:47:05.160] | : . | : . | : . | : . ..$ visible : logi TRUE [08:47:05.160] | : . | : . | : . | : . ..$ stdout : chr "" [08:47:05.160] | : . | : . | : . | : . ..$ conditions : list() [08:47:05.160] | : . | : . | : . | : . ..$ rng : logi FALSE [08:47:05.160] | : . | : . | : . | : . ..$ misuseGlobalEnv : NULL [08:47:05.160] | : . | : . | : . | : . ..$ misuseConnections:List of 3 [08:47:05.160] | : . | : . | : . | : . .. ..$ added : NULL [08:47:05.160] | : . | : . | : . | : . .. ..$ removed : NULL [08:47:05.160] | : . | : . | : . | : . .. ..$ replaced: NULL [08:47:05.160] | : . | : . | : . | : . ..$ misuseDevices : NULL [08:47:05.160] | : . | : . | : . | : . ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.160] | : . | : . | : . | : . ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.160] | : . | : . | : . | : . ..$ session_uuid : chr "9584b21b31f196fb007a595bf1a628f3" [08:47:05.160] | : . | : . | : . | : . .. ..- attr(*, "source")=List of 5 [08:47:05.160] | : . | : . | : . | : . .. .. ..$ host : Named chr "CRANWIN3" [08:47:05.160] | : . | : . | : . | : . .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:05.160] | : . | : . | : . | : . .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:05.160] | : . | : . | : . | : . .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:05.160] | : . | : . | : . | : . .. .. ..$ pid : int 20272 [08:47:05.160] | : . | : . | : . | : . .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.160] | : . | : . | : . | : . .. .. ..$ random: int 2147483647 [08:47:05.160] | : . | : . | : . | : . ..$ r_info :List of 4 [08:47:05.160] | : . | : . | : . | : . .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:05.160] | : . | : . | : . | : . .. .. ..$ : int [1:3] 4 5 0 [08:47:05.160] | : . | : . | : . | : . .. ..$ os : chr "windows" [08:47:05.160] | : . | : . | : . | : . .. ..$ os_name : chr "Windows" [08:47:05.160] | : . | : . | : . | : . .. ..$ captures_utf8: logi TRUE [08:47:05.160] | : . | : . | : . | : . ..$ version : chr "1.8" [08:47:05.160] | : . | : . | : . | : . ..- attr(*, "class")= chr "FutureResult" [08:47:05.160] | : . | : . | : . | : . $ success: logi TRUE [08:47:05.160] | : . | : . | : . | : . $ time : 'proc_time' Named num [1:5] 0.09 0 0.09 NA NA [08:47:05.160] | : . | : . | : . | : . ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:05.160] | : . | : . | : . | : . $ tag : NULL [08:47:05.181] | : . | : . | : . | : . Received FutureResult [08:47:05.181] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:05.184] | : . | : . | : . | : . | Removed future from position #1 [08:47:05.184] | : . | : . | : . | : . | Number of registered futures: 0 [08:47:05.185] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:05.185] | : . | : . | : . | : . Erased future from future backend [08:47:05.185] | : . | : . | : . | : . result() for ClusterFuture ... [08:47:05.186] | : . | : . | : . | : . | result already collected: FutureResult [08:47:05.186] | : . | : . | : . | : . result() for ClusterFuture ... done [08:47:05.186] | : . | : . | : . | : receiveMessageFromWorker() for ClusterFuture ... done [08:47:05.186] | : . | : . | : . | isFutureResolved() ... done [08:47:05.186] | : . | : . | : . | Future at position #1 is resolved ... [08:47:05.187] | : . | : . | : . | : result() for ClusterFuture ... [08:47:05.187] | : . | : . | : . | : . result already collected: FutureResult [08:47:05.187] | : . | : . | : . | : result() for ClusterFuture ... done [08:47:05.188] | : . | : . | : . | : result() for ClusterFuture ... [08:47:05.188] | : . | : . | : . | : . result already collected: FutureResult [08:47:05.188] | : . | : . | : . | : result() for ClusterFuture ... done [08:47:05.188] | : . | : . | : . | Future at position #1 is resolved ... done [08:47:05.188] | : . | : . | : . collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... done [08:47:05.189] | : . | : . | : . Early signaling of 1 future candidates ... [08:47:05.189] | : . | : . | : . Number of futures with early signaling requested: 0 [08:47:05.189] | : . | : . | : . Early signaling of 1 future candidates ... done [08:47:05.189] | : . | : . | : . Number of registered futures: 1 [08:47:05.189] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... done [08:47:05.190] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.190] | : . | : . | : . Listing all futures [08:47:05.190] | : . | : . | : . Number of registered futures: 0 [08:47:05.190] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.190] | : . | : . | : Total time: 0.109138965606689 [08:47:05.191] | : . | : . | Polling for a free worker ... done [08:47:05.191] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.191] | : . | : . | : Listing all futures [08:47:05.191] | : . | : . | : Number of registered futures: 0 [08:47:05.191] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.191] | : . | : . | Index of first available worker: 1 [08:47:05.192] | : . | : . | Validate that the worker is functional ... [08:47:05.193] | : . | : . | : Worker is functional [08:47:05.193] | : . | : . | requestNode() ... done [08:47:05.193] | : . | : . | cluster node index: 1 [08:47:05.194] | : . | : . requestWorker() ... done [08:47:05.194] | : . | : . eraseGlobalEnvironment() ... [08:47:05.195] | : . | : . eraseGlobalEnvironment() ... done [08:47:05.195] | : . | : . launchFuture() ... [08:47:05.195] | : . | : . | cluster node index: 1 [08:47:05.195] | : . | : . | getFutureData() ... [08:47:05.196] | : . | : . | : getFutureCore() ... [08:47:05.196] | : . | : . | : . Packages needed by the future expression (n = 0): [08:47:05.196] | : . | : . | : getFutureCore() ... done [08:47:05.196] | : . | : . | : getFutureCapture() ... [08:47:05.197] | : . | : . | : getFutureCapture() ... done [08:47:05.197] | : . | : . | : getFutureContext() ... [08:47:05.197] | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.232] | : . | : . | : . | Getting stack without first strategy: [n=0] [08:47:05.233] | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.233] | : . | : . | : . Packages needed by future strategies (n = 0): [08:47:05.233] | : . | : . | : getFutureContext() ... done [08:47:05.234] | : . | : . | getFutureData() ... done [08:47:05.234] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:05.235] | : . | : . | : Appended future to position #1 [08:47:05.235] | : . | : . | : Number of registered futures: 1 [08:47:05.235] | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:05.235] | : . | : . launchFuture() ... done [08:47:05.236] | : . | : . ClusterFuture started [08:47:05.236] | : . | : launchFuture() for ClusterFutureBackend ... done [08:47:05.236] | : . | Launching futures ... done [08:47:05.236] | : . | Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:05.236] | : . Using ClusterFutureBackend ... done [08:47:05.237] | : run() for Future ('ClusterFuture') ... done [08:47:05.238] | : value() on list ... [08:47:05.238] | : . recursive: 0 [08:47:05.239] | : . length: 2 [08:47:05.239] | : . [08:47:05.239] | : . Number of remaining objects: 2 [08:47:05.239] | : . checking value #1 ... [08:47:05.239] | : . | 'obj' is ClusterFuture [08:47:05.240] | : . | ClusterFuture #1 [08:47:05.240] | : . | signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:05.240] | : . | : nx: 2 [08:47:05.240] | : . | : relay: TRUE [08:47:05.241] | : . | : stdout: TRUE [08:47:05.241] | : . | : signal: TRUE [08:47:05.241] | : . | : resignal: FALSE [08:47:05.241] | : . | : force: TRUE [08:47:05.241] | : . | : relayed: [n=2] FALSE, FALSE [08:47:05.242] | : . | : queued futures: [n=2] FALSE, FALSE [08:47:05.242] | : . | : until=1 [08:47:05.242] | : . | : relaying element #1 [08:47:05.242] | : . | : result() for ClusterFuture ... [08:47:05.243] | : . | : . result already collected: FutureResult [08:47:05.243] | : . | : result() for ClusterFuture ... done [08:47:05.243] | : . | : result() for ClusterFuture ... [08:47:05.243] | : . | : . result already collected: FutureResult [08:47:05.244] | : . | : result() for ClusterFuture ... done [08:47:05.244] | : . | : result() for ClusterFuture ... [08:47:05.244] | : . | : . result already collected: FutureResult [08:47:05.244] | : . | : result() for ClusterFuture ... done [08:47:05.245] | : . | : result() for ClusterFuture ... [08:47:05.245] | : . | : . result already collected: FutureResult [08:47:05.245] | : . | : result() for ClusterFuture ... done [08:47:05.245] | : . | : relayed: [n=2] TRUE, FALSE [08:47:05.245] | : . | : queued futures: [n=2] TRUE, FALSE [08:47:05.246] | : . | signalConditionsASAP(ClusterFuture, pos=1) ... done [08:47:05.246] | : . | value(, ...) ... [08:47:05.246] | : . | : result() for ClusterFuture ... [08:47:05.246] | : . | : . result already collected: FutureResult [08:47:05.247] | : . | : result() for ClusterFuture ... done [08:47:05.247] | : . | : result() for ClusterFuture ... [08:47:05.247] | : . | : . result already collected: FutureResult [08:47:05.247] | : . | : result() for ClusterFuture ... done [08:47:05.247] | : . | : value: [08:47:05.248] | : . | value(, ...) ... done [08:47:05.248] | : . | signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:05.248] | : . | : nx: 2 [08:47:05.248] | : . | : relay: TRUE [08:47:05.249] | : . | : stdout: TRUE [08:47:05.249] | : . | : signal: TRUE [08:47:05.249] | : . | : resignal: FALSE [08:47:05.249] | : . | : force: TRUE [08:47:05.249] | : . | : relayed: [n=2] TRUE, FALSE [08:47:05.250] | : . | : queued futures: [n=2] TRUE, FALSE [08:47:05.250] | : . | : until=2 [08:47:05.250] | : . | : relaying element #2 [08:47:05.250] | : . | : relayed: [n=2] TRUE, FALSE [08:47:05.250] | : . | : queued futures: [n=2] TRUE, FALSE [08:47:05.251] | : . | signalConditionsASAP(NULL, pos=1) ... done [08:47:05.251] | : . | length: 1 (resolved future 1) [08:47:05.251] | : . checking value #1 ... done [08:47:05.252] | : . checking value #2 ... [08:47:05.252] | : . | 'obj' is ClusterFuture [08:47:05.252] | : . | isFutureResolved() ... [08:47:05.253] | : . | : assertValidConnection() ... [08:47:05.253] | : . | : . cluster node index: 1 [08:47:05.253] | : . | : assertValidConnection() ... done [08:47:05.254] | : . | : receiveMessageFromWorker() for ClusterFuture ... [08:47:05.254] | : . | : . cluster node index: 1 [08:47:05.254] | : . | : . [1] TRUE [08:47:05.255] | : . | : . received data: [08:47:05.255] | : . | : . List of 5 [08:47:05.255] | : . | : . $ type : chr "VALUE" [08:47:05.255] | : . | : . $ value :List of 13 [08:47:05.255] | : . | : . ..$ value : int 2 [08:47:05.255] | : . | : . ..$ visible : logi TRUE [08:47:05.255] | : . | : . ..$ stdout : chr "" [08:47:05.255] | : . | : . ..$ conditions : list() [08:47:05.255] | : . | : . ..$ rng : logi FALSE [08:47:05.255] | : . | : . ..$ misuseGlobalEnv : NULL [08:47:05.255] | : . | : . ..$ misuseConnections:List of 3 [08:47:05.255] | : . | : . .. ..$ added : NULL [08:47:05.255] | : . | : . .. ..$ removed : NULL [08:47:05.255] | : . | : . .. ..$ replaced: NULL [08:47:05.255] | : . | : . ..$ misuseDevices : NULL [08:47:05.255] | : . | : . ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.255] | : . | : . ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.255] | : . | : . ..$ session_uuid : chr "9584b21b31f196fb007a595bf1a628f3" [08:47:05.255] | : . | : . .. ..- attr(*, "source")=List of 5 [08:47:05.255] | : . | : . .. .. ..$ host : Named chr "CRANWIN3" [08:47:05.255] | : . | : . .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:05.255] | : . | : . .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:05.255] | : . | : . .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:05.255] | : . | : . .. .. ..$ pid : int 20272 [08:47:05.255] | : . | : . .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.255] | : . | : . .. .. ..$ random: int 2147483647 [08:47:05.255] | : . | : . ..$ r_info :List of 4 [08:47:05.255] | : . | : . .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:05.255] | : . | : . .. .. ..$ : int [1:3] 4 5 0 [08:47:05.255] | : . | : . .. ..$ os : chr "windows" [08:47:05.255] | : . | : . .. ..$ os_name : chr "Windows" [08:47:05.255] | : . | : . .. ..$ captures_utf8: logi TRUE [08:47:05.255] | : . | : . ..$ version : chr "1.8" [08:47:05.255] | : . | : . ..- attr(*, "class")= chr "FutureResult" [08:47:05.255] | : . | : . $ success: logi TRUE [08:47:05.255] | : . | : . $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [08:47:05.255] | : . | : . ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:05.255] | : . | : . $ tag : NULL [08:47:05.310] | : . | : . Received FutureResult [08:47:05.310] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:05.311] | : . | : . | Removed future from position #1 [08:47:05.311] | : . | : . | Number of registered futures: 0 [08:47:05.311] | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:05.311] | : . | : . Erased future from future backend [08:47:05.312] | : . | : . result() for ClusterFuture ... [08:47:05.312] | : . | : . | result already collected: FutureResult [08:47:05.312] | : . | : . result() for ClusterFuture ... done [08:47:05.312] | : . | : receiveMessageFromWorker() for ClusterFuture ... done [08:47:05.313] | : . | isFutureResolved() ... done [08:47:05.313] | : . | ClusterFuture #2 [08:47:05.313] | : . | signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:05.313] | : . | : nx: 2 [08:47:05.313] | : . | : relay: TRUE [08:47:05.314] | : . | : stdout: TRUE [08:47:05.314] | : . | : signal: TRUE [08:47:05.314] | : . | : resignal: FALSE [08:47:05.314] | : . | : force: TRUE [08:47:05.315] | : . | : relayed: [n=2] TRUE, FALSE [08:47:05.315] | : . | : queued futures: [n=2] TRUE, FALSE [08:47:05.315] | : . | : until=2 [08:47:05.315] | : . | : relaying element #2 [08:47:05.316] | : . | : result() for ClusterFuture ... [08:47:05.316] | : . | : . result already collected: FutureResult [08:47:05.316] | : . | : result() for ClusterFuture ... done [08:47:05.316] | : . | : result() for ClusterFuture ... [08:47:05.317] | : . | : . result already collected: FutureResult [08:47:05.317] | : . | : result() for ClusterFuture ... done [08:47:05.317] | : . | : result() for ClusterFuture ... [08:47:05.317] | : . | : . result already collected: FutureResult [08:47:05.318] | : . | : result() for ClusterFuture ... done [08:47:05.318] | : . | : result() for ClusterFuture ... [08:47:05.318] | : . | : . result already collected: FutureResult [08:47:05.318] | : . | : result() for ClusterFuture ... done [08:47:05.319] | : . | : relayed: [n=2] TRUE, TRUE [08:47:05.319] | : . | : queued futures: [n=2] TRUE, TRUE [08:47:05.319] | : . | signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:05.319] | : . | value(, ...) ... [08:47:05.320] | : . | : result() for ClusterFuture ... [08:47:05.320] | : . | : . result already collected: FutureResult [08:47:05.320] | : . | : result() for ClusterFuture ... done [08:47:05.320] | : . | : result() for ClusterFuture ... [08:47:05.321] | : . | : . result already collected: FutureResult [08:47:05.321] | : . | : result() for ClusterFuture ... done [08:47:05.321] | : . | : value: [08:47:05.321] | : . | value(, ...) ... done [08:47:05.322] | : . | signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:05.322] | : . | : nx: 2 [08:47:05.322] | : . | : relay: TRUE [08:47:05.322] | : . | : stdout: TRUE [08:47:05.322] | : . | : signal: TRUE [08:47:05.323] | : . | : resignal: FALSE [08:47:05.323] | : . | : force: TRUE [08:47:05.323] | : . | : relayed: [n=2] TRUE, TRUE [08:47:05.323] | : . | : queued futures: [n=2] TRUE, TRUE [08:47:05.324] | : . | : until=2 [08:47:05.324] | : . | : relayed: [n=2] TRUE, TRUE [08:47:05.324] | : . | : queued futures: [n=2] TRUE, TRUE [08:47:05.324] | : . | signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:05.325] | : . | length: 0 (resolved future 2) [08:47:05.325] | : . checking value #2 ... done [08:47:05.325] | : . Relaying remaining futures ... [08:47:05.325] | : . | signalConditionsASAP(NULL, pos=0) ... [08:47:05.326] | : . | : nx: 2 [08:47:05.326] | : . | : relay: TRUE [08:47:05.326] | : . | : stdout: TRUE [08:47:05.326] | : . | : signal: TRUE [08:47:05.326] | : . | : resignal: FALSE [08:47:05.327] | : . | : force: TRUE [08:47:05.327] | : . | : relayed: [n=2] TRUE, TRUE [08:47:05.327] | : . | : queued futures: [n=2] TRUE, TRUE - flush all [08:47:05.328] | : . | : relayed: [n=2] TRUE, TRUE [08:47:05.328] | : . | : queued futures: [n=2] TRUE, TRUE [08:47:05.328] | : . | signalConditionsASAP(NULL, pos=0) ... done [08:47:05.328] | : . Relaying remaining futures ... done [08:47:05.328] | : value() on list ... done Iteration #2 [08:47:05.590] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.591] | : . plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [08:47:05.591] | : . | all.equal() for FutureStrategyList ... [08:47:05.592] | : . | : New stack: [08:47:05.592] | : . | : List of 1 [08:47:05.592] | : . | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.592] | : . | : envir = parent.frame()) [08:47:05.592] | : . | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.592] | : . | : ..- attr(*, "init")= logi TRUE [08:47:05.592] | : . | : ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.592] | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:05.592] | : . | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.592] | : . | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.592] | : . | : ..- attr(*, "tweaks")=List of 1 [08:47:05.592] | : . | : .. ..$ workers:List of 1 [08:47:05.592] | : . | : .. .. ..$ :List of 3 [08:47:05.592] | : . | : .. .. .. ..$ con : 'sockconn' int 4 [08:47:05.592] | : . | : .. .. .. .. ..- attr(*, "conn_id")= [08:47:05.592] | : . | : .. .. .. ..$ host: chr "localhost" [08:47:05.592] | : . | : .. .. .. ..$ rank: num 0 [08:47:05.592] | : . | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.592] | : . | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.592] | : . | : ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.592] | : . | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:05.605] | : . | : Old stack: [08:47:05.606] | : . | : List of 1 [08:47:05.606] | : . | : $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.606] | : . | : envir = parent.frame()) [08:47:05.606] | : . | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.606] | : . | : ..- attr(*, "init")= chr "done" [08:47:05.606] | : . | : ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.606] | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:05.606] | : . | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.606] | : . | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.606] | : . | : ..- attr(*, "tweaks")=List of 1 [08:47:05.606] | : . | : .. ..$ workers:List of 1 [08:47:05.606] | : . | : .. .. ..$ :List of 3 [08:47:05.606] | : . | : .. .. .. ..$ con : 'sockconn' int 4 [08:47:05.606] | : . | : .. .. .. .. ..- attr(*, "conn_id")= [08:47:05.606] | : . | : .. .. .. ..$ host: chr "localhost" [08:47:05.606] | : . | : .. .. .. ..$ rank: num 0 [08:47:05.606] | : . | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.606] | : . | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.606] | : . | : ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.606] | : . | : ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:05.606] | : . | : .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.606] | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:05.606] | : . | : .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.606] | : . | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:05.617] | : . | : Not identical [08:47:05.617] | : . | : all.equal() for future ... [08:47:05.618] | : . | : . List of 2 [08:47:05.618] | : . | : . $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.618] | : . | : . envir = parent.frame()) [08:47:05.618] | : . | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.618] | : . | : . ..- attr(*, "init")= logi TRUE [08:47:05.618] | : . | : . ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.618] | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:05.618] | : . | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.618] | : . | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.618] | : . | : . ..- attr(*, "tweaks")=List of 1 [08:47:05.618] | : . | : . .. ..$ workers:List of 1 [08:47:05.618] | : . | : . .. .. ..$ :List of 3 [08:47:05.618] | : . | : . .. .. .. ..$ con : 'sockconn' int 4 [08:47:05.618] | : . | : . .. .. .. .. ..- attr(*, "conn_id")= [08:47:05.618] | : . | : . .. .. .. ..$ host: chr "localhost" [08:47:05.618] | : . | : . .. .. .. ..$ rank: num 0 [08:47:05.618] | : . | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.618] | : . | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.618] | : . | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.618] | : . | : . $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.618] | : . | : . envir = parent.frame()) [08:47:05.618] | : . | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.618] | : . | : . ..- attr(*, "init")= chr "done" [08:47:05.618] | : . | : . ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.618] | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:05.618] | : . | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.618] | : . | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.618] | : . | : . ..- attr(*, "tweaks")=List of 1 [08:47:05.618] | : . | : . .. ..$ workers:List of 1 [08:47:05.618] | : . | : . .. .. ..$ :List of 3 [08:47:05.618] | : . | : . .. .. .. ..$ con : 'sockconn' int 4 [08:47:05.618] | : . | : . .. .. .. .. ..- attr(*, "conn_id")= [08:47:05.618] | : . | : . .. .. .. ..$ host: chr "localhost" [08:47:05.618] | : . | : . .. .. .. ..$ rank: num 0 [08:47:05.618] | : . | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.618] | : . | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.618] | : . | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.618] | : . | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:05.618] | : . | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.618] | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:05.618] | : . | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.641] | : . | : . Formals differ [08:47:05.642] | : . | : all.equal() for future ... done [08:47:05.642] | : . | : Future strategies differ at level 1 [08:47:05.642] | : . | all.equal() for FutureStrategyList ... done [08:47:05.643] | : . | plan(): Setting new future strategy stack: [08:47:05.643] | : . | List of future strategies: [08:47:05.643] | : . | 1. cluster: [08:47:05.643] | : . | - args: function (..., workers = "", envir = parent.frame()) [08:47:05.643] | : . | - tweaked: TRUE [08:47:05.643] | : . | - call: plan(cluster, workers = cl) [08:47:05.645] | : . | List of 1 [08:47:05.645] | : . | $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.645] | : . | envir = parent.frame()) [08:47:05.645] | : . | ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.645] | : . | ..- attr(*, "init")= logi TRUE [08:47:05.645] | : . | ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.645] | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:05.645] | : . | .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.645] | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.645] | : . | ..- attr(*, "tweaks")=List of 1 [08:47:05.645] | : . | .. ..$ workers:List of 1 [08:47:05.645] | : . | .. .. ..$ :List of 3 [08:47:05.645] | : . | .. .. .. ..$ con : 'sockconn' int 4 [08:47:05.645] | : . | .. .. .. .. ..- attr(*, "conn_id")= [08:47:05.645] | : . | .. .. .. ..$ host: chr "localhost" [08:47:05.645] | : . | .. .. .. ..$ rank: num 0 [08:47:05.645] | : . | .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.645] | : . | .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.645] | : . | ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.645] | : . | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:05.659] | : . | plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [08:47:05.659] | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.660] | : . | : . Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:05.660] | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.661] | : . | : stopWorkers() for ClusterFutureBackend ... [08:47:05.661] | : . | : . Interrupt active futures ... [08:47:05.663] | : . | : . | Number of futures: 0 [08:47:05.664] | : . | : . Interrupt active futures ... done [08:47:05.664] | : . | : . Clear future registry ... [08:47:05.665] | : . | : . Clear future registry ... done [08:47:05.666] | : . | : . Stop cluster workers ... [08:47:05.666] | : . | : . | Stopping existing cluster ... [08:47:05.666] | : . | : . | : No pre-existing cluster. Skipping [08:47:05.667] | : . | : . | Stopping existing cluster ... done [08:47:05.667] | : . | : . Stop cluster workers ... done [08:47:05.668] | : . | : stopWorkers() for ClusterFutureBackend ... done [08:47:05.668] | : . | : Legacy shutdown of cluster workers ... [08:47:05.669] | : . | : . Stopping existing cluster ... [08:47:05.669] | : . | : . | No pre-existing cluster. Skipping [08:47:05.669] | : . | : . Stopping existing cluster ... done [08:47:05.670] | : . | : Legacy shutdown of cluster workers ... done [08:47:05.670] | : . | plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [08:47:05.671] | : . | plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [08:47:05.671] | : . | : function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:05.671] | : . | : envir = parent.frame()) [08:47:05.671] | : . | : - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:05.671] | : . | : - attr(*, "init")= logi TRUE [08:47:05.671] | : . | : - attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:05.671] | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:05.671] | : . | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.671] | : . | : - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:05.671] | : . | : - attr(*, "tweaks")=List of 1 [08:47:05.671] | : . | : ..$ workers:List of 1 [08:47:05.671] | : . | : .. ..$ :List of 3 [08:47:05.671] | : . | : .. .. ..$ con : 'sockconn' int 4 [08:47:05.671] | : . | : .. .. .. ..- attr(*, "conn_id")= [08:47:05.671] | : . | : .. .. ..$ host: chr "localhost" [08:47:05.671] | : . | : .. .. ..$ rank: num 0 [08:47:05.671] | : . | : .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:05.671] | : . | : .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:05.671] | : . | : - attr(*, "call")= language plan(cluster, workers = cl) [08:47:05.683] | : . | : init: TRUE [08:47:05.684] | : . | : makeFutureBackend() ... [08:47:05.684] | : . | : . Backend function: <'NULL'> [08:47:05.752] | : . | : . ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [08:47:05.752] | : . | : . | Stopping existing cluster ... [08:47:05.753] | : . | : . | : No pre-existing cluster. Skipping [08:47:05.753] | : . | : . | Stopping existing cluster ... done [08:47:05.753] | : . | : . | Number of workers: 1 [08:47:05.754] | : . | : . | Generated workers UUID [08:47:05.754] | : . | : . | Workers UUID: '381ebb526f9eb2d90230a3c7b34be202' [08:47:05.755] | : . | : . ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [08:47:05.755] | : . | : . Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [08:47:05.756] | : . | : makeFutureBackend() ... done [08:47:05.756] | : . | plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [08:47:05.756] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.757] | : . | : Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:05.757] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.758] | : . | plan(): nbrOfWorkers() = 1 [08:47:05.758] | : . plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [08:47:05.758] | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.759] | : getGlobalsAndPackages() ... [08:47:05.759] | : . Searching for globals ... [08:47:05.761] | : . | globals found: [3] '[[', 'X', 'i' [08:47:05.762] | : . Searching for globals ... DONE [08:47:05.762] | : . Resolving globals: FALSE [08:47:05.762] | : . Search for packages associated with the globals ... [08:47:05.763] | : . | Packages associated with globals: [1] 'base' [08:47:05.763] | : . | Packages: [1] 'base' [08:47:05.764] | : . Search for packages associated with the globals ... DONE [08:47:05.764] | : . Packages after dropping 'base': [0] [08:47:05.765] | : . globals: [2] 'X', 'i' [08:47:05.765] | : . packages: [0] [08:47:05.765] | : getGlobalsAndPackages() ... done [08:47:05.766] | : run() for Future ('Future') ... [08:47:05.766] | : . state: 'created' [08:47:05.767] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.767] | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.767] | : . Using ClusterFutureBackend ... [08:47:05.768] | : . | Launching futures ... [08:47:05.768] | : . | : launchFuture() for ClusterFutureBackend ... [08:47:05.769] | : . | : . Workers: [n=1] [08:47:05.769] | : . | : . socket cluster with 1 nodes on host 'localhost' [08:47:05.770] | : . | : . FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:05.770] | : . | : . requestWorker() ... [08:47:05.771] | : . | : . | requestNode() ... [08:47:05.771] | : . | : . | : Number of workers: 1 [08:47:05.771] | : . | : . | : Polling for a free worker ... [08:47:05.772] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.772] | : . | : . | : . | Listing all futures [08:47:05.772] | : . | : . | : . | Number of registered futures: 0 [08:47:05.773] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.773] | : . | : . | : . Total time: 0 [08:47:05.773] | : . | : . | : Polling for a free worker ... done [08:47:05.774] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.774] | : . | : . | : . Listing all futures [08:47:05.774] | : . | : . | : . Number of registered futures: 0 [08:47:05.775] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.775] | : . | : . | : Index of first available worker: 1 [08:47:05.775] | : . | : . | : Validate that the worker is functional ... [08:47:05.912] | : . | : . | : . Worker is functional [08:47:05.913] | : . | : . | : requestNode() ... done [08:47:05.913] | : . | : . | : cluster node index: 1 [08:47:05.913] | : . | : . | requestWorker() ... done [08:47:05.914] | : . | : . | eraseGlobalEnvironment() ... [08:47:05.914] | : . | : . | eraseGlobalEnvironment() ... done [08:47:05.915] | : . | : . | launchFuture() ... [08:47:05.915] | : . | : . | : cluster node index: 1 [08:47:05.915] | : . | : . | : getFutureData() ... [08:47:05.916] | : . | : . | : . getFutureCore() ... [08:47:05.916] | : . | : . | : . | Packages needed by the future expression (n = 0): [08:47:05.916] | : . | : . | : . getFutureCore() ... done [08:47:05.916] | : . | : . | : . getFutureCapture() ... [08:47:05.917] | : . | : . | : . getFutureCapture() ... done [08:47:05.917] | : . | : . | : . getFutureContext() ... [08:47:05.917] | : . | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.918] | : . | : . | : . | : Getting stack without first strategy: [n=0] [08:47:05.918] | : . | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.918] | : . | : . | : . | Packages needed by future strategies (n = 0): [08:47:05.918] | : . | : . | : . getFutureContext() ... done [08:47:05.919] | : . | : . | : getFutureData() ... done [08:47:05.919] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:05.920] | : . | : . | : . Appended future to position #1 [08:47:05.920] | : . | : . | : . Number of registered futures: 1 [08:47:05.920] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:05.921] | : . | : . | launchFuture() ... done [08:47:05.921] | : . | : . | ClusterFuture started [08:47:05.921] | : . | : . launchFuture() for ClusterFutureBackend ... done [08:47:05.921] | : . | : Launching futures ... done [08:47:05.922] | : . | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:05.922] | : . | Using ClusterFutureBackend ... done [08:47:05.922] | : . run() for Future ('ClusterFuture') ... done [08:47:05.923] | : . getGlobalsAndPackages() ... [08:47:05.923] | : . | Searching for globals ... [08:47:05.925] | : . | : globals found: [3] '[[', 'X', 'i' [08:47:05.925] | : . | Searching for globals ... DONE [08:47:05.925] | : . | Resolving globals: FALSE [08:47:05.925] | : . | Search for packages associated with the globals ... [08:47:05.926] | : . | : Packages associated with globals: [1] 'base' [08:47:05.926] | : . | : Packages: [1] 'base' [08:47:05.927] | : . | Search for packages associated with the globals ... DONE [08:47:05.927] | : . | Packages after dropping 'base': [0] [08:47:05.927] | : . | globals: [2] 'X', 'i' [08:47:05.928] | : . | packages: [0] [08:47:05.928] | : . getGlobalsAndPackages() ... done [08:47:05.928] | : . run() for Future ('Future') ... [08:47:05.929] | : . | state: 'created' [08:47:05.929] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:05.929] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:05.930] | : . | Using ClusterFutureBackend ... [08:47:05.930] | : . | : Launching futures ... [08:47:05.930] | : . | : . launchFuture() for ClusterFutureBackend ... [08:47:05.931] | : . | : . | Workers: [n=1] [08:47:05.931] | : . | : . | socket cluster with 1 nodes on host 'localhost' [08:47:05.931] | : . | : . | FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:05.932] | : . | : . | requestWorker() ... [08:47:05.932] | : . | : . | : requestNode() ... [08:47:05.932] | : . | : . | : . Number of workers: 1 [08:47:05.933] | : . | : . | : . Polling for a free worker ... [08:47:05.933] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:05.933] | : . | : . | : . | : Listing all futures [08:47:05.934] | : . | : . | : . | : Number of registered futures: 1 [08:47:05.934] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:05.934] | : . | : . | : . | Poll #1 (0): usedNodes() = 1, workers = 1 [08:47:05.952] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... [08:47:05.975] | : . | : . | : . | : collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... [08:47:05.976] | : . | : . | : . | : . isFutureResolved() ... [08:47:05.976] | : . | : . | : . | : . | assertValidConnection() ... [08:47:05.977] | : . | : . | : . | : . | : cluster node index: 1 [08:47:05.977] | : . | : . | : . | : . | assertValidConnection() ... done [08:47:05.988] | : . | : . | : . | : . | receiveMessageFromWorker() for ClusterFuture ... [08:47:05.988] | : . | : . | : . | : . | : cluster node index: 1 [08:47:05.989] | : . | : . | : . | : . | : [1] TRUE [08:47:05.989] | : . | : . | : . | : . | : received data: [08:47:05.989] | : . | : . | : . | : . | : List of 5 [08:47:05.989] | : . | : . | : . | : . | : $ type : chr "VALUE" [08:47:05.989] | : . | : . | : . | : . | : $ value :List of 13 [08:47:05.989] | : . | : . | : . | : . | : ..$ value : int 1 [08:47:05.989] | : . | : . | : . | : . | : ..$ visible : logi TRUE [08:47:05.989] | : . | : . | : . | : . | : ..$ stdout : chr "" [08:47:05.989] | : . | : . | : . | : . | : ..$ conditions : list() [08:47:05.989] | : . | : . | : . | : . | : ..$ rng : logi FALSE [08:47:05.989] | : . | : . | : . | : . | : ..$ misuseGlobalEnv : NULL [08:47:05.989] | : . | : . | : . | : . | : ..$ misuseConnections:List of 3 [08:47:05.989] | : . | : . | : . | : . | : .. ..$ added : NULL [08:47:05.989] | : . | : . | : . | : . | : .. ..$ removed : NULL [08:47:05.989] | : . | : . | : . | : . | : .. ..$ replaced: NULL [08:47:05.989] | : . | : . | : . | : . | : ..$ misuseDevices : NULL [08:47:05.989] | : . | : . | : . | : . | : ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.989] | : . | : . | : . | : . | : ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.989] | : . | : . | : . | : . | : ..$ session_uuid : chr "fbb2587ed261ebc6f39e7669bea8a52a" [08:47:05.989] | : . | : . | : . | : . | : .. ..- attr(*, "source")=List of 5 [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ host : Named chr "CRANWIN3" [08:47:05.989] | : . | : . | : . | : . | : .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:05.989] | : . | : . | : . | : . | : .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ pid : int 10084 [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ random: int 2147483647 [08:47:05.989] | : . | : . | : . | : . | : ..$ r_info :List of 4 [08:47:05.989] | : . | : . | : . | : . | : .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:05.989] | : . | : . | : . | : . | : .. .. ..$ : int [1:3] 4 5 0 [08:47:05.989] | : . | : . | : . | : . | : .. ..$ os : chr "windows" [08:47:05.989] | : . | : . | : . | : . | : .. ..$ os_name : chr "Windows" [08:47:05.989] | : . | : . | : . | : . | : .. ..$ captures_utf8: logi TRUE [08:47:05.989] | : . | : . | : . | : . | : ..$ version : chr "1.8" [08:47:05.989] | : . | : . | : . | : . | : ..- attr(*, "class")= chr "FutureResult" [08:47:05.989] | : . | : . | : . | : . | : $ success: logi TRUE [08:47:05.989] | : . | : . | : . | : . | : $ time : 'proc_time' Named num [1:5] 0.08 0 0.08 NA NA [08:47:05.989] | : . | : . | : . | : . | : ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:05.989] | : . | : . | : . | : . | : $ tag : NULL [08:47:06.003] | : . | : . | : . | : . | : Received FutureResult [08:47:06.004] | : . | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:06.004] | : . | : . | : . | : . | : . Removed future from position #1 [08:47:06.004] | : . | : . | : . | : . | : . Number of registered futures: 0 [08:47:06.004] | : . | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:06.004] | : . | : . | : . | : . | : Erased future from future backend [08:47:06.005] | : . | : . | : . | : . | : result() for ClusterFuture ... [08:47:06.005] | : . | : . | : . | : . | : . result already collected: FutureResult [08:47:06.005] | : . | : . | : . | : . | : result() for ClusterFuture ... done [08:47:06.005] | : . | : . | : . | : . | receiveMessageFromWorker() for ClusterFuture ... done [08:47:06.005] | : . | : . | : . | : . isFutureResolved() ... done [08:47:06.006] | : . | : . | : . | : . Future at position #1 is resolved ... [08:47:06.006] | : . | : . | : . | : . | result() for ClusterFuture ... [08:47:06.006] | : . | : . | : . | : . | : result already collected: FutureResult [08:47:06.006] | : . | : . | : . | : . | result() for ClusterFuture ... done [08:47:06.006] | : . | : . | : . | : . | result() for ClusterFuture ... [08:47:06.007] | : . | : . | : . | : . | : result already collected: FutureResult [08:47:06.007] | : . | : . | : . | : . | result() for ClusterFuture ... done [08:47:06.007] | : . | : . | : . | : . Future at position #1 is resolved ... done [08:47:06.007] | : . | : . | : . | : collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... done [08:47:06.007] | : . | : . | : . | : Early signaling of 1 future candidates ... [08:47:06.007] | : . | : . | : . | : Number of futures with early signaling requested: 0 [08:47:06.008] | : . | : . | : . | : Early signaling of 1 future candidates ... done [08:47:06.008] | : . | : . | : . | : Number of registered futures: 1 [08:47:06.008] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... done [08:47:06.008] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.008] | : . | : . | : . | : Listing all futures [08:47:06.009] | : . | : . | : . | : Number of registered futures: 0 [08:47:06.009] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.009] | : . | : . | : . | Total time: 0.0750629901885986 [08:47:06.009] | : . | : . | : . Polling for a free worker ... done [08:47:06.009] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.009] | : . | : . | : . | Listing all futures [08:47:06.010] | : . | : . | : . | Number of registered futures: 0 [08:47:06.010] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.010] | : . | : . | : . Index of first available worker: 1 [08:47:06.010] | : . | : . | : . Validate that the worker is functional ... [08:47:06.011] | : . | : . | : . | Worker is functional [08:47:06.012] | : . | : . | : . requestNode() ... done [08:47:06.012] | : . | : . | : . cluster node index: 1 [08:47:06.012] | : . | : . | : requestWorker() ... done [08:47:06.012] | : . | : . | : eraseGlobalEnvironment() ... [08:47:06.013] | : . | : . | : eraseGlobalEnvironment() ... done [08:47:06.013] | : . | : . | : launchFuture() ... [08:47:06.014] | : . | : . | : . cluster node index: 1 [08:47:06.014] | : . | : . | : . getFutureData() ... [08:47:06.014] | : . | : . | : . | getFutureCore() ... [08:47:06.015] | : . | : . | : . | : Packages needed by the future expression (n = 0): [08:47:06.015] | : . | : . | : . | getFutureCore() ... done [08:47:06.015] | : . | : . | : . | getFutureCapture() ... [08:47:06.016] | : . | : . | : . | getFutureCapture() ... done [08:47:06.016] | : . | : . | : . | getFutureContext() ... [08:47:06.016] | : . | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.017] | : . | : . | : . | : . Getting stack without first strategy: [n=0] [08:47:06.017] | : . | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.017] | : . | : . | : . | : Packages needed by future strategies (n = 0): [08:47:06.018] | : . | : . | : . | getFutureContext() ... done [08:47:06.018] | : . | : . | : . getFutureData() ... done [08:47:06.019] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:06.019] | : . | : . | : . | Appended future to position #1 [08:47:06.019] | : . | : . | : . | Number of registered futures: 1 [08:47:06.020] | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:06.020] | : . | : . | : launchFuture() ... done [08:47:06.020] | : . | : . | : ClusterFuture started [08:47:06.020] | : . | : . | launchFuture() for ClusterFutureBackend ... done [08:47:06.021] | : . | : . Launching futures ... done [08:47:06.021] | : . | : . Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:06.021] | : . | : Using ClusterFutureBackend ... done [08:47:06.022] | : . | run() for Future ('ClusterFuture') ... done [08:47:06.022] | : . | value() on list ... [08:47:06.022] | : . | : recursive: 0 [08:47:06.023] | : . | : length: 2 [08:47:06.023] | : . | : [08:47:06.023] | : . | : Number of remaining objects: 2 [08:47:06.023] | : . | : checking value #1 ... [08:47:06.024] | : . | : . 'obj' is ClusterFuture [08:47:06.024] | : . | : . ClusterFuture #1 [08:47:06.024] | : . | : . signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:06.025] | : . | : . | nx: 2 [08:47:06.025] | : . | : . | relay: TRUE [08:47:06.025] | : . | : . | stdout: TRUE [08:47:06.025] | : . | : . | signal: TRUE [08:47:06.026] | : . | : . | resignal: FALSE [08:47:06.026] | : . | : . | force: TRUE [08:47:06.026] | : . | : . | relayed: [n=2] FALSE, FALSE [08:47:06.027] | : . | : . | queued futures: [n=2] FALSE, FALSE [08:47:06.027] | : . | : . | until=1 [08:47:06.027] | : . | : . | relaying element #1 [08:47:06.027] | : . | : . | result() for ClusterFuture ... [08:47:06.028] | : . | : . | : result already collected: FutureResult [08:47:06.028] | : . | : . | result() for ClusterFuture ... done [08:47:06.028] | : . | : . | result() for ClusterFuture ... [08:47:06.029] | : . | : . | : result already collected: FutureResult [08:47:06.029] | : . | : . | result() for ClusterFuture ... done [08:47:06.029] | : . | : . | result() for ClusterFuture ... [08:47:06.030] | : . | : . | : result already collected: FutureResult [08:47:06.030] | : . | : . | result() for ClusterFuture ... done [08:47:06.030] | : . | : . | result() for ClusterFuture ... [08:47:06.030] | : . | : . | : result already collected: FutureResult [08:47:06.031] | : . | : . | result() for ClusterFuture ... done [08:47:06.031] | : . | : . | relayed: [n=2] TRUE, FALSE [08:47:06.032] | : . | : . | queued futures: [n=2] TRUE, FALSE [08:47:06.032] | : . | : . signalConditionsASAP(ClusterFuture, pos=1) ... done [08:47:06.032] | : . | : . value(, ...) ... [08:47:06.033] | : . | : . | result() for ClusterFuture ... [08:47:06.033] | : . | : . | : result already collected: FutureResult [08:47:06.033] | : . | : . | result() for ClusterFuture ... done [08:47:06.033] | : . | : . | result() for ClusterFuture ... [08:47:06.034] | : . | : . | : result already collected: FutureResult [08:47:06.034] | : . | : . | result() for ClusterFuture ... done [08:47:06.034] | : . | : . | value: [08:47:06.034] | : . | : . value(, ...) ... done [08:47:06.035] | : . | : . signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:06.035] | : . | : . | nx: 2 [08:47:06.035] | : . | : . | relay: TRUE [08:47:06.035] | : . | : . | stdout: TRUE [08:47:06.035] | : . | : . | signal: TRUE [08:47:06.036] | : . | : . | resignal: FALSE [08:47:06.036] | : . | : . | force: TRUE [08:47:06.036] | : . | : . | relayed: [n=2] TRUE, FALSE [08:47:06.036] | : . | : . | queued futures: [n=2] TRUE, FALSE [08:47:06.036] | : . | : . | until=2 [08:47:06.036] | : . | : . | relaying element #2 [08:47:06.037] | : . | : . | relayed: [n=2] TRUE, FALSE [08:47:06.037] | : . | : . | queued futures: [n=2] TRUE, FALSE [08:47:06.037] | : . | : . signalConditionsASAP(NULL, pos=1) ... done [08:47:06.037] | : . | : . length: 1 (resolved future 1) [08:47:06.038] | : . | : checking value #1 ... done [08:47:06.038] | : . | : checking value #2 ... [08:47:06.038] | : . | : . 'obj' is ClusterFuture [08:47:06.038] | : . | : . isFutureResolved() ... [08:47:06.039] | : . | : . | assertValidConnection() ... [08:47:06.039] | : . | : . | : cluster node index: 1 [08:47:06.040] | : . | : . | assertValidConnection() ... done [08:47:06.040] | : . | : . | receiveMessageFromWorker() for ClusterFuture ... [08:47:06.040] | : . | : . | : cluster node index: 1 [08:47:06.041] | : . | : . | : [1] TRUE [08:47:06.041] | : . | : . | : received data: [08:47:06.041] | : . | : . | : List of 5 [08:47:06.041] | : . | : . | : $ type : chr "VALUE" [08:47:06.041] | : . | : . | : $ value :List of 13 [08:47:06.041] | : . | : . | : ..$ value : int 2 [08:47:06.041] | : . | : . | : ..$ visible : logi TRUE [08:47:06.041] | : . | : . | : ..$ stdout : chr "" [08:47:06.041] | : . | : . | : ..$ conditions : list() [08:47:06.041] | : . | : . | : ..$ rng : logi FALSE [08:47:06.041] | : . | : . | : ..$ misuseGlobalEnv : NULL [08:47:06.041] | : . | : . | : ..$ misuseConnections:List of 3 [08:47:06.041] | : . | : . | : .. ..$ added : NULL [08:47:06.041] | : . | : . | : .. ..$ removed : NULL [08:47:06.041] | : . | : . | : .. ..$ replaced: NULL [08:47:06.041] | : . | : . | : ..$ misuseDevices : NULL [08:47:06.041] | : . | : . | : ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.041] | : . | : . | : ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.041] | : . | : . | : ..$ session_uuid : chr "fbb2587ed261ebc6f39e7669bea8a52a" [08:47:06.041] | : . | : . | : .. ..- attr(*, "source")=List of 5 [08:47:06.041] | : . | : . | : .. .. ..$ host : Named chr "CRANWIN3" [08:47:06.041] | : . | : . | : .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:06.041] | : . | : . | : .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:06.041] | : . | : . | : .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:06.041] | : . | : . | : .. .. ..$ pid : int 10084 [08:47:06.041] | : . | : . | : .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:05" [08:47:06.041] | : . | : . | : .. .. ..$ random: int 2147483647 [08:47:06.041] | : . | : . | : ..$ r_info :List of 4 [08:47:06.041] | : . | : . | : .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:06.041] | : . | : . | : .. .. ..$ : int [1:3] 4 5 0 [08:47:06.041] | : . | : . | : .. ..$ os : chr "windows" [08:47:06.041] | : . | : . | : .. ..$ os_name : chr "Windows" [08:47:06.041] | : . | : . | : .. ..$ captures_utf8: logi TRUE [08:47:06.041] | : . | : . | : ..$ version : chr "1.8" [08:47:06.041] | : . | : . | : ..- attr(*, "class")= chr "FutureResult" [08:47:06.041] | : . | : . | : $ success: logi TRUE [08:47:06.041] | : . | : . | : $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [08:47:06.041] | : . | : . | : ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:06.041] | : . | : . | : $ tag : NULL [08:47:06.061] | : . | : . | : Received FutureResult [08:47:06.062] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:06.062] | : . | : . | : . Removed future from position #1 [08:47:06.062] | : . | : . | : . Number of registered futures: 0 [08:47:06.062] | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:06.063] | : . | : . | : Erased future from future backend [08:47:06.063] | : . | : . | : result() for ClusterFuture ... [08:47:06.063] | : . | : . | : . result already collected: FutureResult [08:47:06.063] | : . | : . | : result() for ClusterFuture ... done [08:47:06.063] | : . | : . | receiveMessageFromWorker() for ClusterFuture ... done [08:47:06.063] | : . | : . isFutureResolved() ... done [08:47:06.064] | : . | : . ClusterFuture #2 [08:47:06.064] | : . | : . signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:06.064] | : . | : . | nx: 2 [08:47:06.064] | : . | : . | relay: TRUE [08:47:06.064] | : . | : . | stdout: TRUE [08:47:06.065] | : . | : . | signal: TRUE [08:47:06.065] | : . | : . | resignal: FALSE [08:47:06.065] | : . | : . | force: TRUE [08:47:06.065] | : . | : . | relayed: [n=2] TRUE, FALSE [08:47:06.065] | : . | : . | queued futures: [n=2] TRUE, FALSE [08:47:06.065] | : . | : . | until=2 [08:47:06.066] | : . | : . | relaying element #2 [08:47:06.066] | : . | : . | result() for ClusterFuture ... [08:47:06.066] | : . | : . | : result already collected: FutureResult [08:47:06.066] | : . | : . | result() for ClusterFuture ... done [08:47:06.066] | : . | : . | result() for ClusterFuture ... [08:47:06.066] | : . | : . | : result already collected: FutureResult [08:47:06.067] | : . | : . | result() for ClusterFuture ... done [08:47:06.067] | : . | : . | result() for ClusterFuture ... [08:47:06.067] | : . | : . | : result already collected: FutureResult [08:47:06.067] | : . | : . | result() for ClusterFuture ... done [08:47:06.067] | : . | : . | result() for ClusterFuture ... [08:47:06.068] | : . | : . | : result already collected: FutureResult [08:47:06.068] | : . | : . | result() for ClusterFuture ... done [08:47:06.068] | : . | : . | relayed: [n=2] TRUE, TRUE [08:47:06.068] | : . | : . | queued futures: [n=2] TRUE, TRUE [08:47:06.068] | : . | : . signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:06.068] | : . | : . value(, ...) ... [08:47:06.069] | : . | : . | result() for ClusterFuture ... [08:47:06.069] | : . | : . | : result already collected: FutureResult [08:47:06.069] | : . | : . | result() for ClusterFuture ... done [08:47:06.069] | : . | : . | result() for ClusterFuture ... [08:47:06.069] | : . | : . | : result already collected: FutureResult [08:47:06.069] | : . | : . | result() for ClusterFuture ... done [08:47:06.070] | : . | : . | value: [08:47:06.070] | : . | : . value(, ...) ... done [08:47:06.070] | : . | : . signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:06.070] | : . | : . | nx: 2 [08:47:06.070] | : . | : . | relay: TRUE [08:47:06.071] | : . | : . | stdout: TRUE [08:47:06.071] | : . | : . | signal: TRUE [08:47:06.071] | : . | : . | resignal: FALSE [08:47:06.071] | : . | : . | force: TRUE [08:47:06.071] | : . | : . | relayed: [n=2] TRUE, TRUE [08:47:06.071] | : . | : . | queued futures: [n=2] TRUE, TRUE [08:47:06.072] | : . | : . | until=2 [08:47:06.072] | : . | : . | relayed: [n=2] TRUE, TRUE [08:47:06.072] | : . | : . | queued futures: [n=2] TRUE, TRUE [08:47:06.072] | : . | : . signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:06.072] | : . | : . length: 0 (resolved future 2) [08:47:06.072] | : . | : checking value #2 ... done [08:47:06.073] | : . | : Relaying remaining futures ... [08:47:06.073] | : . | : . signalConditionsASAP(NULL, pos=0) ... [08:47:06.073] | : . | : . | nx: 2 [08:47:06.073] | : . | : . | relay: TRUE [08:47:06.073] | : . | : . | stdout: TRUE [08:47:06.073] | : . | : . | signal: TRUE [08:47:06.074] | : . | : . | resignal: FALSE [08:47:06.074] | : . | : . | force: TRUE [08:47:06.074] | : . | : . | relayed: [n=2] TRUE, TRUE [08:47:06.074] | : . | : . | queued futures: [n=2] TRUE, TRUE - flush all [08:47:06.074] | : . | : . | relayed: [n=2] TRUE, TRUE [08:47:06.075] | : . | : . | queued futures: [n=2] TRUE, TRUE [08:47:06.075] | : . | : . signalConditionsASAP(NULL, pos=0) ... done [08:47:06.075] | : . | : Relaying remaining futures ... done [08:47:06.075] | : . | value() on list ... done Iteration #3 [08:47:06.346] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.347] | : . | : plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [08:47:06.347] | : . | : . all.equal() for FutureStrategyList ... [08:47:06.348] | : . | : . | New stack: [08:47:06.348] | : . | : . | List of 1 [08:47:06.348] | : . | : . | $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.348] | : . | : . | envir = parent.frame()) [08:47:06.348] | : . | : . | ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.348] | : . | : . | ..- attr(*, "init")= logi TRUE [08:47:06.348] | : . | : . | ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.348] | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.348] | : . | : . | .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.348] | : . | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.348] | : . | : . | ..- attr(*, "tweaks")=List of 1 [08:47:06.348] | : . | : . | .. ..$ workers:List of 1 [08:47:06.348] | : . | : . | .. .. ..$ :List of 3 [08:47:06.348] | : . | : . | .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.348] | : . | : . | .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.348] | : . | : . | .. .. .. ..$ host: chr "localhost" [08:47:06.348] | : . | : . | .. .. .. ..$ rank: num 0 [08:47:06.348] | : . | : . | .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.348] | : . | : . | .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.348] | : . | : . | ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.348] | : . | : . | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.358] | : . | : . | Old stack: [08:47:06.358] | : . | : . | List of 1 [08:47:06.358] | : . | : . | $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.358] | : . | : . | envir = parent.frame()) [08:47:06.358] | : . | : . | ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.358] | : . | : . | ..- attr(*, "init")= chr "done" [08:47:06.358] | : . | : . | ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.358] | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.358] | : . | : . | .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.358] | : . | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.358] | : . | : . | ..- attr(*, "tweaks")=List of 1 [08:47:06.358] | : . | : . | .. ..$ workers:List of 1 [08:47:06.358] | : . | : . | .. .. ..$ :List of 3 [08:47:06.358] | : . | : . | .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.358] | : . | : . | .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.358] | : . | : . | .. .. .. ..$ host: chr "localhost" [08:47:06.358] | : . | : . | .. .. .. ..$ rank: num 0 [08:47:06.358] | : . | : . | .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.358] | : . | : . | .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.358] | : . | : . | ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.358] | : . | : . | ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:06.358] | : . | : . | .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.358] | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.358] | : . | : . | .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.358] | : . | : . | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.370] | : . | : . | Not identical [08:47:06.371] | : . | : . | all.equal() for future ... [08:47:06.371] | : . | : . | : List of 2 [08:47:06.371] | : . | : . | : $ target :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.371] | : . | : . | : envir = parent.frame()) [08:47:06.371] | : . | : . | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.371] | : . | : . | : ..- attr(*, "init")= logi TRUE [08:47:06.371] | : . | : . | : ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.371] | : . | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:06.371] | : . | : . | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.371] | : . | : . | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.371] | : . | : . | : ..- attr(*, "tweaks")=List of 1 [08:47:06.371] | : . | : . | : .. ..$ workers:List of 1 [08:47:06.371] | : . | : . | : .. .. ..$ :List of 3 [08:47:06.371] | : . | : . | : .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.371] | : . | : . | : .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.371] | : . | : . | : .. .. .. ..$ host: chr "localhost" [08:47:06.371] | : . | : . | : .. .. .. ..$ rank: num 0 [08:47:06.371] | : . | : . | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.371] | : . | : . | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.371] | : . | : . | : ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.371] | : . | : . | : $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.371] | : . | : . | : envir = parent.frame()) [08:47:06.371] | : . | : . | : ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.371] | : . | : . | : ..- attr(*, "init")= chr "done" [08:47:06.371] | : . | : . | : ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.371] | : . | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:06.371] | : . | : . | : .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.371] | : . | : . | : ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.371] | : . | : . | : ..- attr(*, "tweaks")=List of 1 [08:47:06.371] | : . | : . | : .. ..$ workers:List of 1 [08:47:06.371] | : . | : . | : .. .. ..$ :List of 3 [08:47:06.371] | : . | : . | : .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.371] | : . | : . | : .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.371] | : . | : . | : .. .. .. ..$ host: chr "localhost" [08:47:06.371] | : . | : . | : .. .. .. ..$ rank: num 0 [08:47:06.371] | : . | : . | : .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.371] | : . | : . | : .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.371] | : . | : . | : ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.371] | : . | : . | : ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:06.371] | : . | : . | : .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.371] | : . | : . | : interrupts = FALSE, persistent = FALSE, ...) [08:47:06.371] | : . | : . | : .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.391] | : . | : . | : Formals differ [08:47:06.392] | : . | : . | all.equal() for future ... done [08:47:06.392] | : . | : . | Future strategies differ at level 1 [08:47:06.392] | : . | : . all.equal() for FutureStrategyList ... done [08:47:06.392] | : . | : . plan(): Setting new future strategy stack: [08:47:06.393] | : . | : . List of future strategies: [08:47:06.393] | : . | : . 1. cluster: [08:47:06.393] | : . | : . - args: function (..., workers = "", envir = parent.frame()) [08:47:06.393] | : . | : . - tweaked: TRUE [08:47:06.393] | : . | : . - call: plan(cluster, workers = cl) [08:47:06.394] | : . | : . List of 1 [08:47:06.394] | : . | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.394] | : . | : . envir = parent.frame()) [08:47:06.394] | : . | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.394] | : . | : . ..- attr(*, "init")= logi TRUE [08:47:06.394] | : . | : . ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.394] | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:06.394] | : . | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.394] | : . | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.394] | : . | : . ..- attr(*, "tweaks")=List of 1 [08:47:06.394] | : . | : . .. ..$ workers:List of 1 [08:47:06.394] | : . | : . .. .. ..$ :List of 3 [08:47:06.394] | : . | : . .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.394] | : . | : . .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.394] | : . | : . .. .. .. ..$ host: chr "localhost" [08:47:06.394] | : . | : . .. .. .. ..$ rank: num 0 [08:47:06.394] | : . | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.394] | : . | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.394] | : . | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.394] | : . | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.404] | : . | : . plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [08:47:06.404] | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.405] | : . | : . | : Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:06.405] | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.405] | : . | : . | stopWorkers() for ClusterFutureBackend ... [08:47:06.406] | : . | : . | : Interrupt active futures ... [08:47:06.407] | : . | : . | : . Number of futures: 0 [08:47:06.407] | : . | : . | : Interrupt active futures ... done [08:47:06.407] | : . | : . | : Clear future registry ... [08:47:06.408] | : . | : . | : Clear future registry ... done [08:47:06.408] | : . | : . | : Stop cluster workers ... [08:47:06.408] | : . | : . | : . Stopping existing cluster ... [08:47:06.408] | : . | : . | : . | No pre-existing cluster. Skipping [08:47:06.409] | : . | : . | : . Stopping existing cluster ... done [08:47:06.409] | : . | : . | : Stop cluster workers ... done [08:47:06.409] | : . | : . | stopWorkers() for ClusterFutureBackend ... done [08:47:06.410] | : . | : . | Legacy shutdown of cluster workers ... [08:47:06.410] | : . | : . | : Stopping existing cluster ... [08:47:06.410] | : . | : . | : . No pre-existing cluster. Skipping [08:47:06.411] | : . | : . | : Stopping existing cluster ... done [08:47:06.411] | : . | : . | Legacy shutdown of cluster workers ... done [08:47:06.411] | : . | : . plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [08:47:06.412] | : . | : . plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [08:47:06.412] | : . | : . | function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.412] | : . | : . | envir = parent.frame()) [08:47:06.412] | : . | : . | - attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.412] | : . | : . | - attr(*, "init")= logi TRUE [08:47:06.412] | : . | : . | - attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.412] | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.412] | : . | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.412] | : . | : . | - attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.412] | : . | : . | - attr(*, "tweaks")=List of 1 [08:47:06.412] | : . | : . | ..$ workers:List of 1 [08:47:06.412] | : . | : . | .. ..$ :List of 3 [08:47:06.412] | : . | : . | .. .. ..$ con : 'sockconn' int 4 [08:47:06.412] | : . | : . | .. .. .. ..- attr(*, "conn_id")= [08:47:06.412] | : . | : . | .. .. ..$ host: chr "localhost" [08:47:06.412] | : . | : . | .. .. ..$ rank: num 0 [08:47:06.412] | : . | : . | .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.412] | : . | : . | .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.412] | : . | : . | - attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.423] | : . | : . | init: TRUE [08:47:06.423] | : . | : . | makeFutureBackend() ... [08:47:06.424] | : . | : . | : Backend function: <'NULL'> [08:47:06.424] | : . | : . | : ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... [08:47:06.424] | : . | : . | : . Stopping existing cluster ... [08:47:06.425] | : . | : . | : . | No pre-existing cluster. Skipping [08:47:06.425] | : . | : . | : . Stopping existing cluster ... done [08:47:06.425] | : . | : . | : . Number of workers: 1 [08:47:06.426] | : . | : . | : . Generated workers UUID [08:47:06.426] | : . | : . | : . Workers UUID: '381ebb526f9eb2d90230a3c7b34be202' [08:47:06.427] | : . | : . | : ClusterFutureBackend(..., persistent = FALSE, gc = TRUE, earlySignal = TRUE) ... done [08:47:06.427] | : . | : . | : Backend: <'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [08:47:06.427] | : . | : . | makeFutureBackend() ... done [08:47:06.428] | : . | : . plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... done [08:47:06.428] | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.429] | : . | : . | Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:06.429] | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.429] | : . | : . plan(): nbrOfWorkers() = 1 [08:47:06.430] | : . | : plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [08:47:06.430] | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.430] | : . | getGlobalsAndPackages() ... [08:47:06.431] | : . | : Searching for globals ... [08:47:06.433] | : . | : . globals found: [3] '[[', 'X', 'i' [08:47:06.433] | : . | : Searching for globals ... DONE [08:47:06.434] | : . | : Resolving globals: FALSE [08:47:06.434] | : . | : Search for packages associated with the globals ... [08:47:06.434] | : . | : . Packages associated with globals: [1] 'base' [08:47:06.435] | : . | : . Packages: [1] 'base' [08:47:06.435] | : . | : Search for packages associated with the globals ... DONE [08:47:06.436] | : . | : Packages after dropping 'base': [0] [08:47:06.436] | : . | : globals: [2] 'X', 'i' [08:47:06.437] | : . | : packages: [0] [08:47:06.437] | : . | getGlobalsAndPackages() ... done [08:47:06.438] | : . | run() for Future ('Future') ... [08:47:06.438] | : . | : state: 'created' [08:47:06.438] | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.439] | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.439] | : . | : Using ClusterFutureBackend ... [08:47:06.439] | : . | : . Launching futures ... [08:47:06.440] | : . | : . | launchFuture() for ClusterFutureBackend ... [08:47:06.440] | : . | : . | : Workers: [n=1] [08:47:06.441] | : . | : . | : socket cluster with 1 nodes on host 'localhost' [08:47:06.441] | : . | : . | : FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:06.442] | : . | : . | : requestWorker() ... [08:47:06.442] | : . | : . | : . requestNode() ... [08:47:06.442] | : . | : . | : . | Number of workers: 1 [08:47:06.443] | : . | : . | : . | Polling for a free worker ... [08:47:06.443] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.443] | : . | : . | : . | : . Listing all futures [08:47:06.444] | : . | : . | : . | : . Number of registered futures: 0 [08:47:06.444] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.444] | : . | : . | : . | : Total time: 0 [08:47:06.445] | : . | : . | : . | Polling for a free worker ... done [08:47:06.445] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.445] | : . | : . | : . | : Listing all futures [08:47:06.445] | : . | : . | : . | : Number of registered futures: 0 [08:47:06.445] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.446] | : . | : . | : . | Index of first available worker: 1 [08:47:06.446] | : . | : . | : . | Validate that the worker is functional ... [08:47:06.575] | : . | : . | : . | : Worker is functional [08:47:06.575] | : . | : . | : . | requestNode() ... done [08:47:06.576] | : . | : . | : . | cluster node index: 1 [08:47:06.576] | : . | : . | : . requestWorker() ... done [08:47:06.576] | : . | : . | : . eraseGlobalEnvironment() ... [08:47:06.577] | : . | : . | : . eraseGlobalEnvironment() ... done [08:47:06.578] | : . | : . | : . launchFuture() ... [08:47:06.578] | : . | : . | : . | cluster node index: 1 [08:47:06.578] | : . | : . | : . | getFutureData() ... [08:47:06.579] | : . | : . | : . | : getFutureCore() ... [08:47:06.579] | : . | : . | : . | : . Packages needed by the future expression (n = 0): [08:47:06.579] | : . | : . | : . | : getFutureCore() ... done [08:47:06.579] | : . | : . | : . | : getFutureCapture() ... [08:47:06.580] | : . | : . | : . | : getFutureCapture() ... done [08:47:06.580] | : . | : . | : . | : getFutureContext() ... [08:47:06.580] | : . | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.581] | : . | : . | : . | : . | Getting stack without first strategy: [n=0] [08:47:06.581] | : . | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.581] | : . | : . | : . | : . Packages needed by future strategies (n = 0): [08:47:06.582] | : . | : . | : . | : getFutureContext() ... done [08:47:06.582] | : . | : . | : . | getFutureData() ... done [08:47:06.583] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:06.583] | : . | : . | : . | : Appended future to position #1 [08:47:06.584] | : . | : . | : . | : Number of registered futures: 1 [08:47:06.584] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:06.584] | : . | : . | : . launchFuture() ... done [08:47:06.584] | : . | : . | : . ClusterFuture started [08:47:06.585] | : . | : . | : launchFuture() for ClusterFutureBackend ... done [08:47:06.585] | : . | : . | Launching futures ... done [08:47:06.585] | : . | : . | Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:06.586] | : . | : . Using ClusterFutureBackend ... done [08:47:06.586] | : . | : run() for Future ('ClusterFuture') ... done [08:47:06.586] | : . | : getGlobalsAndPackages() ... [08:47:06.587] | : . | : . Searching for globals ... [08:47:06.589] | : . | : . | globals found: [3] '[[', 'X', 'i' [08:47:06.589] | : . | : . Searching for globals ... DONE [08:47:06.589] | : . | : . Resolving globals: FALSE [08:47:06.590] | : . | : . Search for packages associated with the globals ... [08:47:06.590] | : . | : . | Packages associated with globals: [1] 'base' [08:47:06.591] | : . | : . | Packages: [1] 'base' [08:47:06.591] | : . | : . Search for packages associated with the globals ... DONE [08:47:06.591] | : . | : . Packages after dropping 'base': [0] [08:47:06.592] | : . | : . globals: [2] 'X', 'i' [08:47:06.592] | : . | : . packages: [0] [08:47:06.592] | : . | : getGlobalsAndPackages() ... done [08:47:06.593] | : . | : run() for Future ('Future') ... [08:47:06.593] | : . | : . state: 'created' [08:47:06.594] | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.594] | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.594] | : . | : . Using ClusterFutureBackend ... [08:47:06.595] | : . | : . | Launching futures ... [08:47:06.595] | : . | : . | : launchFuture() for ClusterFutureBackend ... [08:47:06.595] | : . | : . | : . Workers: [n=1] [08:47:06.595] | : . | : . | : . socket cluster with 1 nodes on host 'localhost' [08:47:06.596] | : . | : . | : . FutureRegistry: 'workers-381ebb526f9eb2d90230a3c7b34be202' [08:47:06.596] | : . | : . | : . requestWorker() ... [08:47:06.597] | : . | : . | : . | requestNode() ... [08:47:06.597] | : . | : . | : . | : Number of workers: 1 [08:47:06.597] | : . | : . | : . | : Polling for a free worker ... [08:47:06.598] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.598] | : . | : . | : . | : . | Listing all futures [08:47:06.598] | : . | : . | : . | : . | Number of registered futures: 1 [08:47:06.598] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.599] | : . | : . | : . | : . Poll #1 (0): usedNodes() = 1, workers = 1 [08:47:06.624] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... [08:47:06.625] | : . | : . | : . | : . | collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... [08:47:06.625] | : . | : . | : . | : . | : isFutureResolved() ... [08:47:06.626] | : . | : . | : . | : . | : . assertValidConnection() ... [08:47:06.626] | : . | : . | : . | : . | : . | cluster node index: 1 [08:47:06.627] | : . | : . | : . | : . | : . assertValidConnection() ... done [08:47:06.692] | : . | : . | : . | : . | : . receiveMessageFromWorker() for ClusterFuture ... [08:47:06.693] | : . | : . | : . | : . | : . | cluster node index: 1 [08:47:06.696] | : . | : . | : . | : . | : . | [1] TRUE [08:47:06.696] | : . | : . | : . | : . | : . | received data: [08:47:06.697] | : . | : . | : . | : . | : . | List of 5 [08:47:06.697] | : . | : . | : . | : . | : . | $ type : chr "VALUE" [08:47:06.697] | : . | : . | : . | : . | : . | $ value :List of 13 [08:47:06.697] | : . | : . | : . | : . | : . | ..$ value : int 1 [08:47:06.697] | : . | : . | : . | : . | : . | ..$ visible : logi TRUE [08:47:06.697] | : . | : . | : . | : . | : . | ..$ stdout : chr "" [08:47:06.697] | : . | : . | : . | : . | : . | ..$ conditions : list() [08:47:06.697] | : . | : . | : . | : . | : . | ..$ rng : logi FALSE [08:47:06.697] | : . | : . | : . | : . | : . | ..$ misuseGlobalEnv : NULL [08:47:06.697] | : . | : . | : . | : . | : . | ..$ misuseConnections:List of 3 [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ added : NULL [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ removed : NULL [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ replaced: NULL [08:47:06.697] | : . | : . | : . | : . | : . | ..$ misuseDevices : NULL [08:47:06.697] | : . | : . | : . | : . | : . | ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.697] | : . | : . | : . | : . | : . | ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.697] | : . | : . | : . | : . | : . | ..$ session_uuid : chr "4c00e2a0b8a0dc696a9b54beb0e05265" [08:47:06.697] | : . | : . | : . | : . | : . | .. ..- attr(*, "source")=List of 5 [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ host : Named chr "CRANWIN3" [08:47:06.697] | : . | : . | : . | : . | : . | .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:06.697] | : . | : . | : . | : . | : . | .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ pid : int 33204 [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ random: int 2147483647 [08:47:06.697] | : . | : . | : . | : . | : . | ..$ r_info :List of 4 [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:06.697] | : . | : . | : . | : . | : . | .. .. ..$ : int [1:3] 4 5 0 [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ os : chr "windows" [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ os_name : chr "Windows" [08:47:06.697] | : . | : . | : . | : . | : . | .. ..$ captures_utf8: logi TRUE [08:47:06.697] | : . | : . | : . | : . | : . | ..$ version : chr "1.8" [08:47:06.697] | : . | : . | : . | : . | : . | ..- attr(*, "class")= chr "FutureResult" [08:47:06.697] | : . | : . | : . | : . | : . | $ success: logi TRUE [08:47:06.697] | : . | : . | : . | : . | : . | $ time : 'proc_time' Named num [1:5] 0.11 0 0.11 NA NA [08:47:06.697] | : . | : . | : . | : . | : . | ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:06.697] | : . | : . | : . | : . | : . | $ tag : NULL [08:47:06.717] | : . | : . | : . | : . | : . | Received FutureResult [08:47:06.717] | : . | : . | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:06.717] | : . | : . | : . | : . | : . | : Removed future from position #1 [08:47:06.718] | : . | : . | : . | : . | : . | : Number of registered futures: 0 [08:47:06.718] | : . | : . | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:06.718] | : . | : . | : . | : . | : . | Erased future from future backend [08:47:06.718] | : . | : . | : . | : . | : . | result() for ClusterFuture ... [08:47:06.719] | : . | : . | : . | : . | : . | : result already collected: FutureResult [08:47:06.719] | : . | : . | : . | : . | : . | result() for ClusterFuture ... done [08:47:06.719] | : . | : . | : . | : . | : . receiveMessageFromWorker() for ClusterFuture ... done [08:47:06.719] | : . | : . | : . | : . | : isFutureResolved() ... done [08:47:06.720] | : . | : . | : . | : . | : Future at position #1 is resolved ... [08:47:06.720] | : . | : . | : . | : . | : . result() for ClusterFuture ... [08:47:06.720] | : . | : . | : . | : . | : . | result already collected: FutureResult [08:47:06.721] | : . | : . | : . | : . | : . result() for ClusterFuture ... done [08:47:06.721] | : . | : . | : . | : . | : . result() for ClusterFuture ... [08:47:06.721] | : . | : . | : . | : . | : . | result already collected: FutureResult [08:47:06.721] | : . | : . | : . | : . | : . result() for ClusterFuture ... done [08:47:06.722] | : . | : . | : . | : . | : Future at position #1 is resolved ... done [08:47:06.722] | : . | : . | : . | : . | collectValues('workers-381ebb526f9eb2d90230a3c7b34be202', firstOnly = TRUE) ... done [08:47:06.722] | : . | : . | : . | : . | Early signaling of 1 future candidates ... [08:47:06.723] | : . | : . | : . | : . | Number of futures with early signaling requested: 0 [08:47:06.723] | : . | : . | : . | : . | Early signaling of 1 future candidates ... done [08:47:06.723] | : . | : . | : . | : . | Number of registered futures: 1 [08:47:06.723] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'collect-first', earlySignal = 1) ... done [08:47:06.724] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.724] | : . | : . | : . | : . | Listing all futures [08:47:06.724] | : . | : . | : . | : . | Number of registered futures: 0 [08:47:06.725] | : . | : . | : . | : . FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.725] | : . | : . | : . | : . Total time: 0.126147031784058 [08:47:06.725] | : . | : . | : . | : Polling for a free worker ... done [08:47:06.726] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... [08:47:06.726] | : . | : . | : . | : . Listing all futures [08:47:06.726] | : . | : . | : . | : . Number of registered futures: 0 [08:47:06.727] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'list', earlySignal = 0) ... done [08:47:06.727] | : . | : . | : . | : Index of first available worker: 1 [08:47:06.727] | : . | : . | : . | : Validate that the worker is functional ... [08:47:06.729] | : . | : . | : . | : . Worker is functional [08:47:06.729] | : . | : . | : . | : requestNode() ... done [08:47:06.730] | : . | : . | : . | : cluster node index: 1 [08:47:06.730] | : . | : . | : . | requestWorker() ... done [08:47:06.730] | : . | : . | : . | eraseGlobalEnvironment() ... [08:47:06.731] | : . | : . | : . | eraseGlobalEnvironment() ... done [08:47:06.731] | : . | : . | : . | launchFuture() ... [08:47:06.732] | : . | : . | : . | : cluster node index: 1 [08:47:06.732] | : . | : . | : . | : getFutureData() ... [08:47:06.732] | : . | : . | : . | : . getFutureCore() ... [08:47:06.732] | : . | : . | : . | : . | Packages needed by the future expression (n = 0): [08:47:06.733] | : . | : . | : . | : . getFutureCore() ... done [08:47:06.733] | : . | : . | : . | : . getFutureCapture() ... [08:47:06.733] | : . | : . | : . | : . getFutureCapture() ... done [08:47:06.734] | : . | : . | : . | : . getFutureContext() ... [08:47:06.734] | : . | : . | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.734] | : . | : . | : . | : . | : Getting stack without first strategy: [n=0] [08:47:06.735] | : . | : . | : . | : . | plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.735] | : . | : . | : . | : . | Packages needed by future strategies (n = 0): [08:47:06.735] | : . | : . | : . | : . getFutureContext() ... done [08:47:06.736] | : . | : . | : . | : getFutureData() ... done [08:47:06.736] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... [08:47:06.737] | : . | : . | : . | : . Appended future to position #1 [08:47:06.737] | : . | : . | : . | : . Number of registered futures: 1 [08:47:06.737] | : . | : . | : . | : FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'add', earlySignal = 0) ... done [08:47:06.737] | : . | : . | : . | launchFuture() ... done [08:47:06.738] | : . | : . | : . | ClusterFuture started [08:47:06.738] | : . | : . | : . launchFuture() for ClusterFutureBackend ... done [08:47:06.738] | : . | : . | : Launching futures ... done [08:47:06.739] | : . | : . | : Future launched: 'ClusterFuture', 'MultiprocessFuture', 'Future' [08:47:06.739] | : . | : . | Using ClusterFutureBackend ... done [08:47:06.739] | : . | : . run() for Future ('ClusterFuture') ... done [08:47:06.740] | : . | : . value() on list ... [08:47:06.740] | : . | : . | recursive: 0 [08:47:06.740] | : . | : . | length: 2 [08:47:06.741] | : . | : . | [08:47:06.741] | : . | : . | Number of remaining objects: 2 [08:47:06.741] | : . | : . | checking value #1 ... [08:47:06.742] | : . | : . | : 'obj' is ClusterFuture [08:47:06.742] | : . | : . | : ClusterFuture #1 [08:47:06.742] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:06.743] | : . | : . | : . nx: 2 [08:47:06.743] | : . | : . | : . relay: TRUE [08:47:06.743] | : . | : . | : . stdout: TRUE [08:47:06.743] | : . | : . | : . signal: TRUE [08:47:06.744] | : . | : . | : . resignal: FALSE [08:47:06.744] | : . | : . | : . force: TRUE [08:47:06.744] | : . | : . | : . relayed: [n=2] FALSE, FALSE [08:47:06.745] | : . | : . | : . queued futures: [n=2] FALSE, FALSE [08:47:06.745] | : . | : . | : . until=1 [08:47:06.745] | : . | : . | : . relaying element #1 [08:47:06.746] | : . | : . | : . result() for ClusterFuture ... [08:47:06.746] | : . | : . | : . | result already collected: FutureResult [08:47:06.746] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.746] | : . | : . | : . result() for ClusterFuture ... [08:47:06.747] | : . | : . | : . | result already collected: FutureResult [08:47:06.747] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.747] | : . | : . | : . result() for ClusterFuture ... [08:47:06.748] | : . | : . | : . | result already collected: FutureResult [08:47:06.748] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.748] | : . | : . | : . result() for ClusterFuture ... [08:47:06.749] | : . | : . | : . | result already collected: FutureResult [08:47:06.749] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.749] | : . | : . | : . relayed: [n=2] TRUE, FALSE [08:47:06.750] | : . | : . | : . queued futures: [n=2] TRUE, FALSE [08:47:06.750] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=1) ... done [08:47:06.750] | : . | : . | : value(, ...) ... [08:47:06.751] | : . | : . | : . result() for ClusterFuture ... [08:47:06.751] | : . | : . | : . | result already collected: FutureResult [08:47:06.751] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.751] | : . | : . | : . result() for ClusterFuture ... [08:47:06.752] | : . | : . | : . | result already collected: FutureResult [08:47:06.752] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.752] | : . | : . | : . value: [08:47:06.753] | : . | : . | : value(, ...) ... done [08:47:06.753] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=1) ... [08:47:06.753] | : . | : . | : . nx: 2 [08:47:06.754] | : . | : . | : . relay: TRUE [08:47:06.754] | : . | : . | : . stdout: TRUE [08:47:06.754] | : . | : . | : . signal: TRUE [08:47:06.755] | : . | : . | : . resignal: FALSE [08:47:06.755] | : . | : . | : . force: TRUE [08:47:06.755] | : . | : . | : . relayed: [n=2] TRUE, FALSE [08:47:06.755] | : . | : . | : . queued futures: [n=2] TRUE, FALSE [08:47:06.756] | : . | : . | : . until=2 [08:47:06.756] | : . | : . | : . relaying element #2 [08:47:06.756] | : . | : . | : . relayed: [n=2] TRUE, FALSE [08:47:06.757] | : . | : . | : . queued futures: [n=2] TRUE, FALSE [08:47:06.757] | : . | : . | : signalConditionsASAP(NULL, pos=1) ... done [08:47:06.757] | : . | : . | : length: 1 (resolved future 1) [08:47:06.758] | : . | : . | checking value #1 ... done [08:47:06.758] | : . | : . | checking value #2 ... [08:47:06.758] | : . | : . | : 'obj' is ClusterFuture [08:47:06.759] | : . | : . | : isFutureResolved() ... [08:47:06.759] | : . | : . | : . assertValidConnection() ... [08:47:06.760] | : . | : . | : . | cluster node index: 1 [08:47:06.760] | : . | : . | : . assertValidConnection() ... done [08:47:06.761] | : . | : . | : . receiveMessageFromWorker() for ClusterFuture ... [08:47:06.761] | : . | : . | : . | cluster node index: 1 [08:47:06.761] | : . | : . | : . | [1] TRUE [08:47:06.762] | : . | : . | : . | received data: [08:47:06.762] | : . | : . | : . | List of 5 [08:47:06.762] | : . | : . | : . | $ type : chr "VALUE" [08:47:06.762] | : . | : . | : . | $ value :List of 13 [08:47:06.762] | : . | : . | : . | ..$ value : int 2 [08:47:06.762] | : . | : . | : . | ..$ visible : logi TRUE [08:47:06.762] | : . | : . | : . | ..$ stdout : chr "" [08:47:06.762] | : . | : . | : . | ..$ conditions : list() [08:47:06.762] | : . | : . | : . | ..$ rng : logi FALSE [08:47:06.762] | : . | : . | : . | ..$ misuseGlobalEnv : NULL [08:47:06.762] | : . | : . | : . | ..$ misuseConnections:List of 3 [08:47:06.762] | : . | : . | : . | .. ..$ added : NULL [08:47:06.762] | : . | : . | : . | .. ..$ removed : NULL [08:47:06.762] | : . | : . | : . | .. ..$ replaced: NULL [08:47:06.762] | : . | : . | : . | ..$ misuseDevices : NULL [08:47:06.762] | : . | : . | : . | ..$ started : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.762] | : . | : . | : . | ..$ finished : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.762] | : . | : . | : . | ..$ session_uuid : chr "4c00e2a0b8a0dc696a9b54beb0e05265" [08:47:06.762] | : . | : . | : . | .. ..- attr(*, "source")=List of 5 [08:47:06.762] | : . | : . | : . | .. .. ..$ host : Named chr "CRANWIN3" [08:47:06.762] | : . | : . | : . | .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [08:47:06.762] | : . | : . | : . | .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [08:47:06.762] | : . | : . | : . | .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [08:47:06.762] | : . | : . | : . | .. .. ..$ pid : int 33204 [08:47:06.762] | : . | : . | : . | .. .. ..$ time : POSIXct[1:1], format: "2025-04-10 08:47:06" [08:47:06.762] | : . | : . | : . | .. .. ..$ random: int 2147483647 [08:47:06.762] | : . | : . | : . | ..$ r_info :List of 4 [08:47:06.762] | : . | : . | : . | .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [08:47:06.762] | : . | : . | : . | .. .. ..$ : int [1:3] 4 5 0 [08:47:06.762] | : . | : . | : . | .. ..$ os : chr "windows" [08:47:06.762] | : . | : . | : . | .. ..$ os_name : chr "Windows" [08:47:06.762] | : . | : . | : . | .. ..$ captures_utf8: logi TRUE [08:47:06.762] | : . | : . | : . | ..$ version : chr "1.8" [08:47:06.762] | : . | : . | : . | ..- attr(*, "class")= chr "FutureResult" [08:47:06.762] | : . | : . | : . | $ success: logi TRUE [08:47:06.762] | : . | : . | : . | $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [08:47:06.762] | : . | : . | : . | ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [08:47:06.762] | : . | : . | : . | $ tag : NULL [08:47:06.783] | : . | : . | : . | Received FutureResult [08:47:06.784] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... [08:47:06.784] | : . | : . | : . | : Removed future from position #1 [08:47:06.784] | : . | : . | : . | : Number of registered futures: 0 [08:47:06.785] | : . | : . | : . | FutureRegistry('workers-381ebb526f9eb2d90230a3c7b34be202', action = 'remove', earlySignal = 0) ... done [08:47:06.785] | : . | : . | : . | Erased future from future backend [08:47:06.785] | : . | : . | : . | result() for ClusterFuture ... [08:47:06.785] | : . | : . | : . | : result already collected: FutureResult [08:47:06.786] | : . | : . | : . | result() for ClusterFuture ... done [08:47:06.786] | : . | : . | : . receiveMessageFromWorker() for ClusterFuture ... done [08:47:06.786] | : . | : . | : isFutureResolved() ... done [08:47:06.786] | : . | : . | : ClusterFuture #2 [08:47:06.787] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:06.787] | : . | : . | : . nx: 2 [08:47:06.787] | : . | : . | : . relay: TRUE [08:47:06.788] | : . | : . | : . stdout: TRUE [08:47:06.788] | : . | : . | : . signal: TRUE [08:47:06.788] | : . | : . | : . resignal: FALSE [08:47:06.788] | : . | : . | : . force: TRUE [08:47:06.788] | : . | : . | : . relayed: [n=2] TRUE, FALSE [08:47:06.789] | : . | : . | : . queued futures: [n=2] TRUE, FALSE [08:47:06.789] | : . | : . | : . until=2 [08:47:06.789] | : . | : . | : . relaying element #2 [08:47:06.790] | : . | : . | : . result() for ClusterFuture ... [08:47:06.790] | : . | : . | : . | result already collected: FutureResult [08:47:06.790] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.790] | : . | : . | : . result() for ClusterFuture ... [08:47:06.791] | : . | : . | : . | result already collected: FutureResult [08:47:06.791] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.791] | : . | : . | : . result() for ClusterFuture ... [08:47:06.792] | : . | : . | : . | result already collected: FutureResult [08:47:06.792] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.792] | : . | : . | : . result() for ClusterFuture ... [08:47:06.792] | : . | : . | : . | result already collected: FutureResult [08:47:06.793] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.793] | : . | : . | : . relayed: [n=2] TRUE, TRUE [08:47:06.793] | : . | : . | : . queued futures: [n=2] TRUE, TRUE [08:47:06.794] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:06.794] | : . | : . | : value(, ...) ... [08:47:06.794] | : . | : . | : . result() for ClusterFuture ... [08:47:06.794] | : . | : . | : . | result already collected: FutureResult [08:47:06.795] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.795] | : . | : . | : . result() for ClusterFuture ... [08:47:06.795] | : . | : . | : . | result already collected: FutureResult [08:47:06.795] | : . | : . | : . result() for ClusterFuture ... done [08:47:06.796] | : . | : . | : . value: [08:47:06.796] | : . | : . | : value(, ...) ... done [08:47:06.796] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=2) ... [08:47:06.797] | : . | : . | : . nx: 2 [08:47:06.797] | : . | : . | : . relay: TRUE [08:47:06.797] | : . | : . | : . stdout: TRUE [08:47:06.797] | : . | : . | : . signal: TRUE [08:47:06.798] | : . | : . | : . resignal: FALSE [08:47:06.798] | : . | : . | : . force: TRUE [08:47:06.798] | : . | : . | : . relayed: [n=2] TRUE, TRUE [08:47:06.798] | : . | : . | : . queued futures: [n=2] TRUE, TRUE [08:47:06.799] | : . | : . | : . until=2 [08:47:06.799] | : . | : . | : . relayed: [n=2] TRUE, TRUE [08:47:06.799] | : . | : . | : . queued futures: [n=2] TRUE, TRUE [08:47:06.799] | : . | : . | : signalConditionsASAP(ClusterFuture, pos=2) ... done [08:47:06.800] | : . | : . | : length: 0 (resolved future 2) [08:47:06.800] | : . | : . | checking value #2 ... done [08:47:06.800] | : . | : . | Relaying remaining futures ... [08:47:06.801] | : . | : . | : signalConditionsASAP(NULL, pos=0) ... [08:47:06.801] | : . | : . | : . nx: 2 [08:47:06.801] | : . | : . | : . relay: TRUE [08:47:06.801] | : . | : . | : . stdout: TRUE [08:47:06.802] | : . | : . | : . signal: TRUE [08:47:06.802] | : . | : . | : . resignal: FALSE [08:47:06.802] | : . | : . | : . force: TRUE [08:47:06.802] | : . | : . | : . relayed: [n=2] TRUE, TRUE [08:47:06.803] | : . | : . | : . queued futures: [n=2] TRUE, TRUE - flush all [08:47:06.803] | : . | : . | : . relayed: [n=2] TRUE, TRUE [08:47:06.803] | : . | : . | : . queued futures: [n=2] TRUE, TRUE [08:47:06.804] | : . | : . | : signalConditionsASAP(NULL, pos=0) ... done [08:47:06.804] | : . | : . | Relaying remaining futures ... done [08:47:06.804] | : . | : . value() on list ... done > plan(sequential) [08:47:06.810] | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.810] | : . | : . | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [08:47:06.811] | : . | : . | : all.equal() for FutureStrategyList ... [08:47:06.811] | : . | : . | : . New stack: [08:47:06.811] | : . | : . | : . List of 1 [08:47:06.811] | : . | : . | : . $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:06.811] | : . | : . | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:06.811] | : . | : . | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:06.811] | : . | : . | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.811] | : . | : . | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.811] | : . | : . | : . ..- attr(*, "call")= language plan(sequential) [08:47:06.811] | : . | : . | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.816] | : . | : . | : . Old stack: [08:47:06.816] | : . | : . | : . List of 1 [08:47:06.816] | : . | : . | : . $ :function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.816] | : . | : . | : . envir = parent.frame()) [08:47:06.816] | : . | : . | : . ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.816] | : . | : . | : . ..- attr(*, "init")= chr "done" [08:47:06.816] | : . | : . | : . ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.816] | : . | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:06.816] | : . | : . | : . .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.816] | : . | : . | : . ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.816] | : . | : . | : . ..- attr(*, "tweaks")=List of 1 [08:47:06.816] | : . | : . | : . .. ..$ workers:List of 1 [08:47:06.816] | : . | : . | : . .. .. ..$ :List of 3 [08:47:06.816] | : . | : . | : . .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.816] | : . | : . | : . .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.816] | : . | : . | : . .. .. .. ..$ host: chr "localhost" [08:47:06.816] | : . | : . | : . .. .. .. ..$ rank: num 0 [08:47:06.816] | : . | : . | : . .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.816] | : . | : . | : . .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.816] | : . | : . | : . ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.816] | : . | : . | : . ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:06.816] | : . | : . | : . .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.816] | : . | : . | : . interrupts = FALSE, persistent = FALSE, ...) [08:47:06.816] | : . | : . | : . .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.816] | : . | : . | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.828] | : . | : . | : . Not identical [08:47:06.828] | : . | : . | : . all.equal() for future ... [08:47:06.828] | : . | : . | : . | List of 2 [08:47:06.828] | : . | : . | : . | $ target :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:06.828] | : . | : . | : . | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:06.828] | : . | : . | : . | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:06.828] | : . | : . | : . | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.828] | : . | : . | : . | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.828] | : . | : . | : . | ..- attr(*, "call")= language plan(sequential) [08:47:06.828] | : . | : . | : . | $ current:function (..., workers = list(list(con = 4L, host = "localhost", rank = 0)), [08:47:06.828] | : . | : . | : . | envir = parent.frame()) [08:47:06.828] | : . | : . | : . | ..- attr(*, "class")= chr [1:5] "tweaked" "cluster" "multiprocess" "future" ... [08:47:06.828] | : . | : . | : . | ..- attr(*, "init")= chr "done" [08:47:06.828] | : . | : . | : . | ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.828] | : . | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.828] | : . | : . | : . | .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.828] | : . | : . | : . | ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.828] | : . | : . | : . | ..- attr(*, "tweaks")=List of 1 [08:47:06.828] | : . | : . | : . | .. ..$ workers:List of 1 [08:47:06.828] | : . | : . | : . | .. .. ..$ :List of 3 [08:47:06.828] | : . | : . | : . | .. .. .. ..$ con : 'sockconn' int 4 [08:47:06.828] | : . | : . | : . | .. .. .. .. ..- attr(*, "conn_id")= [08:47:06.828] | : . | : . | : . | .. .. .. ..$ host: chr "localhost" [08:47:06.828] | : . | : . | : . | .. .. .. ..$ rank: num 0 [08:47:06.828] | : . | : . | : . | .. .. .. ..- attr(*, "class")= chr "SOCKnode" [08:47:06.828] | : . | : . | : . | .. .. ..- attr(*, "class")= chr [1:2] "SOCKcluster" "cluster" [08:47:06.828] | : . | : . | : . | ..- attr(*, "call")= language plan(cluster, workers = cl) [08:47:06.828] | : . | : . | : . | ..- attr(*, "backend")=Classes 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [08:47:06.828] | : . | : . | : . | .. ..- attr(*, "factory")=function (workers = availableWorkers(), gc = TRUE, earlySignal = TRUE, [08:47:06.828] | : . | : . | : . | interrupts = FALSE, persistent = FALSE, ...) [08:47:06.828] | : . | : . | : . | .. .. ..- attr(*, "tweakable")= chr [1:9] "gc" "earlySignal" "interrupts" "persistent" ... [08:47:06.843] | : . | : . | : . | Formals differ [08:47:06.843] | : . | : . | : . all.equal() for future ... done [08:47:06.843] | : . | : . | : . Future strategies differ at level 1 [08:47:06.843] | : . | : . | : all.equal() for FutureStrategyList ... done [08:47:06.844] | : . | : . | : plan(): Setting new future strategy stack: [08:47:06.844] | : . | : . | : List of future strategies: [08:47:06.844] | : . | : . | : 1. sequential: [08:47:06.844] | : . | : . | : - args: function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame(), workers = "") [08:47:06.844] | : . | : . | : - tweaked: FALSE [08:47:06.844] | : . | : . | : - call: plan(sequential) [08:47:06.845] | : . | : . | : List of 1 [08:47:06.845] | : . | : . | : $ :function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:06.845] | : . | : . | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:06.845] | : . | : . | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:06.845] | : . | : . | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.845] | : . | : . | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.845] | : . | : . | : ..- attr(*, "call")= language plan(sequential) [08:47:06.845] | : . | : . | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [08:47:06.849] | : . | : . | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... [08:47:06.850] | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.850] | : . | : . | : . | Getting current ("next") strategy: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [08:47:06.850] | : . | : . | : . plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.851] | : . | : . | : . stopWorkers() for ClusterFutureBackend ... [08:47:06.851] | : . | : . | : . | Interrupt active futures ... [08:47:06.852] | : . | : . | : . | : Number of futures: 0 [08:47:06.852] | : . | : . | : . | Interrupt active futures ... done [08:47:06.852] | : . | : . | : . | Clear future registry ... [08:47:06.853] | : . | : . | : . | Clear future registry ... done [08:47:06.854] | : . | : . | : . | Stop cluster workers ... [08:47:06.854] | : . | : . | : . | : Stopping existing cluster ... [08:47:06.854] | : . | : . | : . | : . No pre-existing cluster. Skipping [08:47:06.854] | : . | : . | : . | : Stopping existing cluster ... done [08:47:06.855] | : . | : . | : . | Stop cluster workers ... done [08:47:06.855] | : . | : . | : . stopWorkers() for ClusterFutureBackend ... done [08:47:06.855] | : . | : . | : . Legacy shutdown of cluster workers ... [08:47:06.855] | : . | : . | : . | Stopping existing cluster ... [08:47:06.856] | : . | : . | : . | : No pre-existing cluster. Skipping [08:47:06.856] | : . | : . | : . | Stopping existing cluster ... done [08:47:06.856] | : . | : . | : . Legacy shutdown of cluster workers ... done [08:47:06.856] | : . | : . | : plan(): plan_cleanup('tweaked', 'cluster', 'multiprocess', 'future', 'function', cleanup = NA) ... done [08:47:06.857] | : . | : . | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [08:47:06.857] | : . | : . | : . function (..., gc = FALSE, earlySignal = FALSE, envir = parent.frame()) [08:47:06.857] | : . | : . | : . - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [08:47:06.857] | : . | : . | : . - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [08:47:06.857] | : . | : . | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.857] | : . | : . | : . - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [08:47:06.857] | : . | : . | : . - attr(*, "call")= language plan(sequential) [08:47:06.860] | : . | : . | : . init: NULL [08:47:06.861] | : . | : . | : plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [08:47:06.861] | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [08:47:06.861] | : . | : . | : . Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [08:47:06.862] | : . | : . | : plan(, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [08:47:06.862] | : . | : . | : Backend function: <'NULL'> [08:47:06.863] | : . | : . | : Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [08:47:06.863] | : . | : . | : plan(): nbrOfWorkers() = 1 [08:47:06.863] | : . | : . | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [08:47:06.863] | : . | : . 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_04_10_08_45_16_15127' Sourcing 6 epilogue scripts ... done Test time: user.self=1s, sys.self=0.05s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'cluster-connection-clashes' ... success > > proc.time() user system elapsed 1.29 0.14 2.79