R Under development (unstable) (2023-12-20 r85713 ucrt) -- "Unsuffered Consequences" Copyright (C) 2023 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. > source("incl/start.R") [01:28:44.849] plan(): Setting new future strategy stack: [01:28:44.852] List of future strategies: [01:28:44.852] 1. sequential: [01:28:44.852] - args: function (..., envir = parent.frame(), workers = "") [01:28:44.852] - tweaked: FALSE [01:28:44.852] - call: future::plan("sequential") [01:28:44.894] plan(): nbrOfWorkers() = 1 > > message("*** requestNode() ...") *** requestNode() ... > > message("*** requestNode() - exceptions ...") *** requestNode() - exceptions ... > > workers <- makeClusterPSOCK(2L) [01:28:44.911] [local output] makeClusterPSOCK() ... [01:28:45.012] [local output] Workers: [n = 2] 'localhost', 'localhost' [01:28:45.021] [local output] Base port: 21275 [01:28:45.021] [local output] Getting setup options for 2 cluster nodes ... [01:28:45.022] [local output] - Node 1 of 2 ... [01:28:45.023] [local output] localMachine=TRUE => revtunnel=FALSE [01:28:45.025] Testing if worker's PID can be inferred: '"D:/RCompile/recent/R/bin/x64/Rscript" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f80490c26b7.pid\")), silent = TRUE)" -e "file.exists(\"D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f80490c26b7.pid\")"' [01:28:45.467] - Possible to infer worker's PID: TRUE [01:28:45.469] [local output] Rscript port: 21275 [01:28:45.469] [local output] - Node 2 of 2 ... [01:28:45.470] [local output] localMachine=TRUE => revtunnel=FALSE [01:28:45.472] [local output] Rscript port: 21275 [01:28:45.472] [local output] Getting setup options for 2 cluster nodes ... done [01:28:45.472] [local output] - Parallel setup requested for some PSOCK nodes [01:28:45.474] [local output] Setting up PSOCK nodes in parallel [01:28:45.474] List of 36 [01:28:45.474] $ worker : chr "localhost" [01:28:45.474] ..- attr(*, "localhost")= logi TRUE [01:28:45.474] $ master : chr "localhost" [01:28:45.474] $ port : int 21275 [01:28:45.474] $ connectTimeout : num 120 [01:28:45.474] $ timeout : num 120 [01:28:45.474] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [01:28:45.474] $ homogeneous : logi TRUE [01:28:45.474] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:12160:CRANWIN3:CRA"| __truncated__ [01:28:45.474] $ rscript_envs : NULL [01:28:45.474] $ rscript_libs : NULL [01:28:45.474] $ rscript_startup : NULL [01:28:45.474] $ rscript_sh : chr "cmd" [01:28:45.474] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [01:28:45.474] $ methods : logi TRUE [01:28:45.474] $ socketOptions : chr "no-delay" [01:28:45.474] $ useXDR : logi FALSE [01:28:45.474] $ outfile : chr "/dev/null" [01:28:45.474] $ renice : int NA [01:28:45.474] $ rshcmd : NULL [01:28:45.474] $ user : chr(0) [01:28:45.474] $ revtunnel : logi FALSE [01:28:45.474] $ rshlogfile : NULL [01:28:45.474] $ rshopts : chr(0) [01:28:45.474] $ rank : int 1 [01:28:45.474] $ manual : logi FALSE [01:28:45.474] $ dryrun : logi FALSE [01:28:45.474] $ quiet : logi FALSE [01:28:45.474] $ setup_strategy : chr "parallel" [01:28:45.474] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [01:28:45.474] $ pidfile : chr "D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f80490c26b7.pid" [01:28:45.474] $ rshcmd_label : NULL [01:28:45.474] $ rsh_call : NULL [01:28:45.474] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [01:28:45.474] $ localMachine : logi TRUE [01:28:45.474] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [01:28:45.474] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [01:28:45.474] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [01:28:45.474] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [01:28:45.474] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [01:28:45.474] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [01:28:45.474] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [01:28:45.474] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [01:28:45.474] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [01:28:45.474] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [01:28:45.474] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [01:28:45.474] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [01:28:45.474] "parallel"), action = c("launch", "options"), verbose = FALSE) [01:28:45.474] $ arguments :List of 28 [01:28:45.474] ..$ worker : chr "localhost" [01:28:45.474] ..$ master : NULL [01:28:45.474] ..$ port : int 21275 [01:28:45.474] ..$ connectTimeout : num 120 [01:28:45.474] ..$ timeout : num 120 [01:28:45.474] ..$ rscript : NULL [01:28:45.474] ..$ homogeneous : NULL [01:28:45.474] ..$ rscript_args : NULL [01:28:45.474] ..$ rscript_envs : NULL [01:28:45.474] ..$ rscript_libs : NULL [01:28:45.474] ..$ rscript_startup : NULL [01:28:45.474] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [01:28:45.474] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [01:28:45.474] ..$ methods : logi TRUE [01:28:45.474] ..$ socketOptions : chr "no-delay" [01:28:45.474] ..$ useXDR : logi FALSE [01:28:45.474] ..$ outfile : chr "/dev/null" [01:28:45.474] ..$ renice : int NA [01:28:45.474] ..$ rshcmd : NULL [01:28:45.474] ..$ user : NULL [01:28:45.474] ..$ revtunnel : logi NA [01:28:45.474] ..$ rshlogfile : NULL [01:28:45.474] ..$ rshopts : NULL [01:28:45.474] ..$ rank : int 1 [01:28:45.474] ..$ manual : logi FALSE [01:28:45.474] ..$ dryrun : logi FALSE [01:28:45.474] ..$ quiet : logi FALSE [01:28:45.474] ..$ setup_strategy : chr "parallel" [01:28:45.474] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [01:28:45.503] [local output] System call to launch all workers: [01:28:45.503] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:12160:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f80490c26b7.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=21275 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [01:28:45.503] [local output] Starting PSOCK main server [01:28:45.510] [local output] Workers launched [01:28:45.510] [local output] Waiting for workers to connect back [01:28:45.511] - [local output] 0 workers out of 2 ready [01:28:45.710] - [local output] 0 workers out of 2 ready [01:28:45.711] - [local output] 1 workers out of 2 ready [01:28:45.722] - [local output] 1 workers out of 2 ready [01:28:45.723] - [local output] 2 workers out of 2 ready [01:28:45.723] [local output] Launching of workers completed [01:28:45.723] [local output] Collecting session information from workers [01:28:45.724] [local output] - Worker #1 of 2 [01:28:45.725] [local output] - Worker #2 of 2 [01:28:45.725] [local output] makeClusterPSOCK() ... done > print(workers) Socket cluster with 2 nodes where 2 nodes are on host 'localhost' (R Under development (unstable) (2023-12-20 r85713 ucrt), platform x86_64-w64-mingw32) > > res <- tryCatch({ + requestNode(function() {}, workers = workers, timeout = -1.0) + }, error = identity) > stopifnot(inherits(res, "error")) > > res <- tryCatch({ + requestNode(function() {}, workers = workers, alpha = 0) + }, error = identity) > stopifnot(inherits(res, "error")) > > parallel::stopCluster(workers) > > message("*** requestNode() - exceptions ... DONE") *** requestNode() - exceptions ... DONE > > message("*** requestNode() - timeout ...") *** requestNode() - timeout ... > > plan(cluster, workers = "localhost") [01:28:45.734] plan(): Setting new future strategy stack: [01:28:45.734] List of future strategies: [01:28:45.734] 1. cluster: [01:28:45.734] - args: function (..., workers = "localhost", envir = parent.frame()) [01:28:45.734] - tweaked: TRUE [01:28:45.734] - call: plan(cluster, workers = "localhost") [01:28:45.761] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [01:28:45.762] cluster: [01:28:45.762] - args: function (..., workers = "localhost", envir = parent.frame()) [01:28:45.762] - tweaked: TRUE [01:28:45.762] - call: plan(cluster, workers = "localhost") [01:28:45.765] getGlobalsAndPackages() ... [01:28:45.765] Not searching for globals [01:28:45.766] - globals: [0] [01:28:45.766] getGlobalsAndPackages() ... DONE [01:28:45.768] [local output] makeClusterPSOCK() ... [01:28:45.768] [local output] Workers: [n = 1] 'localhost' [01:28:45.771] [local output] Base port: 33100 [01:28:45.772] [local output] Getting setup options for 1 cluster nodes ... [01:28:45.772] [local output] - Node 1 of 1 ... [01:28:45.773] [local output] localMachine=TRUE => revtunnel=FALSE [01:28:45.774] [local output] Rscript port: 33100 [01:28:45.775] [local output] Getting setup options for 1 cluster nodes ... done [01:28:45.775] [local output] - Parallel setup requested for some PSOCK nodes [01:28:45.775] [local output] Setting up PSOCK nodes in parallel [01:28:45.776] List of 36 [01:28:45.776] $ worker : chr "localhost" [01:28:45.776] ..- attr(*, "localhost")= logi TRUE [01:28:45.776] $ master : chr "localhost" [01:28:45.776] $ port : int 33100 [01:28:45.776] $ connectTimeout : num 120 [01:28:45.776] $ timeout : num 120 [01:28:45.776] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [01:28:45.776] $ homogeneous : logi TRUE [01:28:45.776] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:12160:CRANWIN3:CRA"| __truncated__ [01:28:45.776] $ rscript_envs : NULL [01:28:45.776] $ rscript_libs : NULL [01:28:45.776] $ rscript_startup : NULL [01:28:45.776] $ rscript_sh : chr "cmd" [01:28:45.776] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [01:28:45.776] $ methods : logi TRUE [01:28:45.776] $ socketOptions : chr "no-delay" [01:28:45.776] $ useXDR : logi FALSE [01:28:45.776] $ outfile : chr "/dev/null" [01:28:45.776] $ renice : int NA [01:28:45.776] $ rshcmd : NULL [01:28:45.776] $ user : chr(0) [01:28:45.776] $ revtunnel : logi FALSE [01:28:45.776] $ rshlogfile : NULL [01:28:45.776] $ rshopts : chr(0) [01:28:45.776] $ rank : int 1 [01:28:45.776] $ manual : logi FALSE [01:28:45.776] $ dryrun : logi FALSE [01:28:45.776] $ quiet : logi FALSE [01:28:45.776] $ setup_strategy : chr "parallel" [01:28:45.776] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [01:28:45.776] $ pidfile : chr "D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f807f7a6922.pid" [01:28:45.776] $ rshcmd_label : NULL [01:28:45.776] $ rsh_call : NULL [01:28:45.776] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [01:28:45.776] $ localMachine : logi TRUE [01:28:45.776] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [01:28:45.776] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [01:28:45.776] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [01:28:45.776] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [01:28:45.776] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [01:28:45.776] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [01:28:45.776] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [01:28:45.776] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [01:28:45.776] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [01:28:45.776] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [01:28:45.776] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [01:28:45.776] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [01:28:45.776] "parallel"), action = c("launch", "options"), verbose = FALSE) [01:28:45.776] $ arguments :List of 28 [01:28:45.776] ..$ worker : chr "localhost" [01:28:45.776] ..$ master : NULL [01:28:45.776] ..$ port : int 33100 [01:28:45.776] ..$ connectTimeout : num 120 [01:28:45.776] ..$ timeout : num 120 [01:28:45.776] ..$ rscript : NULL [01:28:45.776] ..$ homogeneous : NULL [01:28:45.776] ..$ rscript_args : NULL [01:28:45.776] ..$ rscript_envs : NULL [01:28:45.776] ..$ rscript_libs : NULL [01:28:45.776] ..$ rscript_startup : NULL [01:28:45.776] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [01:28:45.776] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [01:28:45.776] ..$ methods : logi TRUE [01:28:45.776] ..$ socketOptions : chr "no-delay" [01:28:45.776] ..$ useXDR : logi FALSE [01:28:45.776] ..$ outfile : chr "/dev/null" [01:28:45.776] ..$ renice : int NA [01:28:45.776] ..$ rshcmd : NULL [01:28:45.776] ..$ user : NULL [01:28:45.776] ..$ revtunnel : logi NA [01:28:45.776] ..$ rshlogfile : NULL [01:28:45.776] ..$ rshopts : NULL [01:28:45.776] ..$ rank : int 1 [01:28:45.776] ..$ manual : logi FALSE [01:28:45.776] ..$ dryrun : logi FALSE [01:28:45.776] ..$ quiet : logi FALSE [01:28:45.776] ..$ setup_strategy : chr "parallel" [01:28:45.776] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [01:28:45.808] [local output] System call to launch all workers: [01:28:45.809] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:12160:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/Rtmpsrmct0/worker.rank=1.parallelly.parent=12160.2f807f7a6922.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=33100 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [01:28:45.809] [local output] Starting PSOCK main server [01:28:45.814] [local output] Workers launched [01:28:45.814] [local output] Waiting for workers to connect back [01:28:45.814] - [local output] 0 workers out of 1 ready [01:28:46.012] - [local output] 0 workers out of 1 ready [01:28:46.012] - [local output] 1 workers out of 1 ready [01:28:46.013] [local output] Launching of workers completed [01:28:46.013] [local output] Collecting session information from workers [01:28:46.013] [local output] - Worker #1 of 1 [01:28:46.014] [local output] makeClusterPSOCK() ... done [01:28:46.021] Packages needed by the future expression (n = 0): [01:28:46.022] Packages needed by future strategies (n = 0): [01:28:46.023] { [01:28:46.023] { [01:28:46.023] { [01:28:46.023] ...future.startTime <- base::Sys.time() [01:28:46.023] { [01:28:46.023] { [01:28:46.023] { [01:28:46.023] base::local({ [01:28:46.023] has_future <- base::requireNamespace("future", [01:28:46.023] quietly = TRUE) [01:28:46.023] if (has_future) { [01:28:46.023] ns <- base::getNamespace("future") [01:28:46.023] version <- ns[[".package"]][["version"]] [01:28:46.023] if (is.null(version)) [01:28:46.023] version <- utils::packageVersion("future") [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] version <- NULL [01:28:46.023] } [01:28:46.023] if (!has_future || version < "1.8.0") { [01:28:46.023] info <- base::c(r_version = base::gsub("R version ", [01:28:46.023] "", base::R.version$version.string), [01:28:46.023] platform = base::sprintf("%s (%s-bit)", [01:28:46.023] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [01:28:46.023] os = base::paste(base::Sys.info()[base::c("sysname", [01:28:46.023] "release", "version")], collapse = " "), [01:28:46.023] hostname = base::Sys.info()[["nodename"]]) [01:28:46.023] info <- base::sprintf("%s: %s", base::names(info), [01:28:46.023] info) [01:28:46.023] info <- base::paste(info, collapse = "; ") [01:28:46.023] if (!has_future) { [01:28:46.023] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [01:28:46.023] info) [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [01:28:46.023] info, version) [01:28:46.023] } [01:28:46.023] base::stop(msg) [01:28:46.023] } [01:28:46.023] }) [01:28:46.023] } [01:28:46.023] options(future.plan = NULL) [01:28:46.023] Sys.unsetenv("R_FUTURE_PLAN") [01:28:46.023] future::plan("default", .cleanup = FALSE, .init = FALSE) [01:28:46.023] } [01:28:46.023] ...future.workdir <- getwd() [01:28:46.023] } [01:28:46.023] ...future.oldOptions <- base::as.list(base::.Options) [01:28:46.023] ...future.oldEnvVars <- base::Sys.getenv() [01:28:46.023] } [01:28:46.023] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [01:28:46.023] future.globals.maxSize = NULL, future.globals.method = NULL, [01:28:46.023] future.globals.onMissing = NULL, future.globals.onReference = NULL, [01:28:46.023] future.globals.resolve = NULL, future.resolve.recursive = NULL, [01:28:46.023] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [01:28:46.023] future.stdout.windows.reencode = NULL, width = 80L) [01:28:46.023] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [01:28:46.023] base::names(...future.oldOptions)) [01:28:46.023] } [01:28:46.023] if (FALSE) { [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] if (TRUE) { [01:28:46.023] ...future.stdout <- base::rawConnection(base::raw(0L), [01:28:46.023] open = "w") [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [01:28:46.023] windows = "NUL", "/dev/null"), open = "w") [01:28:46.023] } [01:28:46.023] base::sink(...future.stdout, type = "output", split = FALSE) [01:28:46.023] base::on.exit(if (!base::is.null(...future.stdout)) { [01:28:46.023] base::sink(type = "output", split = FALSE) [01:28:46.023] base::close(...future.stdout) [01:28:46.023] }, add = TRUE) [01:28:46.023] } [01:28:46.023] ...future.frame <- base::sys.nframe() [01:28:46.023] ...future.conditions <- base::list() [01:28:46.023] ...future.rng <- base::globalenv()$.Random.seed [01:28:46.023] if (FALSE) { [01:28:46.023] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [01:28:46.023] "...future.value", "...future.globalenv.names", ".Random.seed") [01:28:46.023] } [01:28:46.023] ...future.result <- base::tryCatch({ [01:28:46.023] base::withCallingHandlers({ [01:28:46.023] ...future.value <- base::withVisible(base::local({ [01:28:46.023] ...future.makeSendCondition <- base::local({ [01:28:46.023] sendCondition <- NULL [01:28:46.023] function(frame = 1L) { [01:28:46.023] if (is.function(sendCondition)) [01:28:46.023] return(sendCondition) [01:28:46.023] ns <- getNamespace("parallel") [01:28:46.023] if (exists("sendData", mode = "function", [01:28:46.023] envir = ns)) { [01:28:46.023] parallel_sendData <- get("sendData", mode = "function", [01:28:46.023] envir = ns) [01:28:46.023] envir <- sys.frame(frame) [01:28:46.023] master <- NULL [01:28:46.023] while (!identical(envir, .GlobalEnv) && [01:28:46.023] !identical(envir, emptyenv())) { [01:28:46.023] if (exists("master", mode = "list", envir = envir, [01:28:46.023] inherits = FALSE)) { [01:28:46.023] master <- get("master", mode = "list", [01:28:46.023] envir = envir, inherits = FALSE) [01:28:46.023] if (inherits(master, c("SOCKnode", [01:28:46.023] "SOCK0node"))) { [01:28:46.023] sendCondition <<- function(cond) { [01:28:46.023] data <- list(type = "VALUE", value = cond, [01:28:46.023] success = TRUE) [01:28:46.023] parallel_sendData(master, data) [01:28:46.023] } [01:28:46.023] return(sendCondition) [01:28:46.023] } [01:28:46.023] } [01:28:46.023] frame <- frame + 1L [01:28:46.023] envir <- sys.frame(frame) [01:28:46.023] } [01:28:46.023] } [01:28:46.023] sendCondition <<- function(cond) NULL [01:28:46.023] } [01:28:46.023] }) [01:28:46.023] withCallingHandlers({ [01:28:46.023] NA [01:28:46.023] }, immediateCondition = function(cond) { [01:28:46.023] sendCondition <- ...future.makeSendCondition() [01:28:46.023] sendCondition(cond) [01:28:46.023] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.023] { [01:28:46.023] inherits <- base::inherits [01:28:46.023] invokeRestart <- base::invokeRestart [01:28:46.023] is.null <- base::is.null [01:28:46.023] muffled <- FALSE [01:28:46.023] if (inherits(cond, "message")) { [01:28:46.023] muffled <- grepl(pattern, "muffleMessage") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleMessage") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "warning")) { [01:28:46.023] muffled <- grepl(pattern, "muffleWarning") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleWarning") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "condition")) { [01:28:46.023] if (!is.null(pattern)) { [01:28:46.023] computeRestarts <- base::computeRestarts [01:28:46.023] grepl <- base::grepl [01:28:46.023] restarts <- computeRestarts(cond) [01:28:46.023] for (restart in restarts) { [01:28:46.023] name <- restart$name [01:28:46.023] if (is.null(name)) [01:28:46.023] next [01:28:46.023] if (!grepl(pattern, name)) [01:28:46.023] next [01:28:46.023] invokeRestart(restart) [01:28:46.023] muffled <- TRUE [01:28:46.023] break [01:28:46.023] } [01:28:46.023] } [01:28:46.023] } [01:28:46.023] invisible(muffled) [01:28:46.023] } [01:28:46.023] muffleCondition(cond) [01:28:46.023] }) [01:28:46.023] })) [01:28:46.023] future::FutureResult(value = ...future.value$value, [01:28:46.023] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [01:28:46.023] ...future.rng), globalenv = if (FALSE) [01:28:46.023] list(added = base::setdiff(base::names(base::.GlobalEnv), [01:28:46.023] ...future.globalenv.names)) [01:28:46.023] else NULL, started = ...future.startTime, version = "1.8") [01:28:46.023] }, condition = base::local({ [01:28:46.023] c <- base::c [01:28:46.023] inherits <- base::inherits [01:28:46.023] invokeRestart <- base::invokeRestart [01:28:46.023] length <- base::length [01:28:46.023] list <- base::list [01:28:46.023] seq.int <- base::seq.int [01:28:46.023] signalCondition <- base::signalCondition [01:28:46.023] sys.calls <- base::sys.calls [01:28:46.023] `[[` <- base::`[[` [01:28:46.023] `+` <- base::`+` [01:28:46.023] `<<-` <- base::`<<-` [01:28:46.023] sysCalls <- function(calls = sys.calls(), from = 1L) { [01:28:46.023] calls[seq.int(from = from + 12L, to = length(calls) - [01:28:46.023] 3L)] [01:28:46.023] } [01:28:46.023] function(cond) { [01:28:46.023] is_error <- inherits(cond, "error") [01:28:46.023] ignore <- !is_error && !is.null(NULL) && inherits(cond, [01:28:46.023] NULL) [01:28:46.023] if (is_error) { [01:28:46.023] sessionInformation <- function() { [01:28:46.023] list(r = base::R.Version(), locale = base::Sys.getlocale(), [01:28:46.023] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [01:28:46.023] search = base::search(), system = base::Sys.info()) [01:28:46.023] } [01:28:46.023] ...future.conditions[[length(...future.conditions) + [01:28:46.023] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [01:28:46.023] cond$call), session = sessionInformation(), [01:28:46.023] timestamp = base::Sys.time(), signaled = 0L) [01:28:46.023] signalCondition(cond) [01:28:46.023] } [01:28:46.023] else if (!ignore && TRUE && inherits(cond, c("condition", [01:28:46.023] "immediateCondition"))) { [01:28:46.023] signal <- TRUE && inherits(cond, "immediateCondition") [01:28:46.023] ...future.conditions[[length(...future.conditions) + [01:28:46.023] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [01:28:46.023] if (TRUE && !signal) { [01:28:46.023] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.023] { [01:28:46.023] inherits <- base::inherits [01:28:46.023] invokeRestart <- base::invokeRestart [01:28:46.023] is.null <- base::is.null [01:28:46.023] muffled <- FALSE [01:28:46.023] if (inherits(cond, "message")) { [01:28:46.023] muffled <- grepl(pattern, "muffleMessage") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleMessage") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "warning")) { [01:28:46.023] muffled <- grepl(pattern, "muffleWarning") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleWarning") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "condition")) { [01:28:46.023] if (!is.null(pattern)) { [01:28:46.023] computeRestarts <- base::computeRestarts [01:28:46.023] grepl <- base::grepl [01:28:46.023] restarts <- computeRestarts(cond) [01:28:46.023] for (restart in restarts) { [01:28:46.023] name <- restart$name [01:28:46.023] if (is.null(name)) [01:28:46.023] next [01:28:46.023] if (!grepl(pattern, name)) [01:28:46.023] next [01:28:46.023] invokeRestart(restart) [01:28:46.023] muffled <- TRUE [01:28:46.023] break [01:28:46.023] } [01:28:46.023] } [01:28:46.023] } [01:28:46.023] invisible(muffled) [01:28:46.023] } [01:28:46.023] muffleCondition(cond, pattern = "^muffle") [01:28:46.023] } [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] if (TRUE) { [01:28:46.023] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.023] { [01:28:46.023] inherits <- base::inherits [01:28:46.023] invokeRestart <- base::invokeRestart [01:28:46.023] is.null <- base::is.null [01:28:46.023] muffled <- FALSE [01:28:46.023] if (inherits(cond, "message")) { [01:28:46.023] muffled <- grepl(pattern, "muffleMessage") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleMessage") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "warning")) { [01:28:46.023] muffled <- grepl(pattern, "muffleWarning") [01:28:46.023] if (muffled) [01:28:46.023] invokeRestart("muffleWarning") [01:28:46.023] } [01:28:46.023] else if (inherits(cond, "condition")) { [01:28:46.023] if (!is.null(pattern)) { [01:28:46.023] computeRestarts <- base::computeRestarts [01:28:46.023] grepl <- base::grepl [01:28:46.023] restarts <- computeRestarts(cond) [01:28:46.023] for (restart in restarts) { [01:28:46.023] name <- restart$name [01:28:46.023] if (is.null(name)) [01:28:46.023] next [01:28:46.023] if (!grepl(pattern, name)) [01:28:46.023] next [01:28:46.023] invokeRestart(restart) [01:28:46.023] muffled <- TRUE [01:28:46.023] break [01:28:46.023] } [01:28:46.023] } [01:28:46.023] } [01:28:46.023] invisible(muffled) [01:28:46.023] } [01:28:46.023] muffleCondition(cond, pattern = "^muffle") [01:28:46.023] } [01:28:46.023] } [01:28:46.023] } [01:28:46.023] })) [01:28:46.023] }, error = function(ex) { [01:28:46.023] base::structure(base::list(value = NULL, visible = NULL, [01:28:46.023] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [01:28:46.023] ...future.rng), started = ...future.startTime, [01:28:46.023] finished = Sys.time(), session_uuid = NA_character_, [01:28:46.023] version = "1.8"), class = "FutureResult") [01:28:46.023] }, finally = { [01:28:46.023] if (!identical(...future.workdir, getwd())) [01:28:46.023] setwd(...future.workdir) [01:28:46.023] { [01:28:46.023] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [01:28:46.023] ...future.oldOptions$nwarnings <- NULL [01:28:46.023] } [01:28:46.023] base::options(...future.oldOptions) [01:28:46.023] if (.Platform$OS.type == "windows") { [01:28:46.023] old_names <- names(...future.oldEnvVars) [01:28:46.023] envs <- base::Sys.getenv() [01:28:46.023] names <- names(envs) [01:28:46.023] common <- intersect(names, old_names) [01:28:46.023] added <- setdiff(names, old_names) [01:28:46.023] removed <- setdiff(old_names, names) [01:28:46.023] changed <- common[...future.oldEnvVars[common] != [01:28:46.023] envs[common]] [01:28:46.023] NAMES <- toupper(changed) [01:28:46.023] args <- list() [01:28:46.023] for (kk in seq_along(NAMES)) { [01:28:46.023] name <- changed[[kk]] [01:28:46.023] NAME <- NAMES[[kk]] [01:28:46.023] if (name != NAME && is.element(NAME, old_names)) [01:28:46.023] next [01:28:46.023] args[[name]] <- ...future.oldEnvVars[[name]] [01:28:46.023] } [01:28:46.023] NAMES <- toupper(added) [01:28:46.023] for (kk in seq_along(NAMES)) { [01:28:46.023] name <- added[[kk]] [01:28:46.023] NAME <- NAMES[[kk]] [01:28:46.023] if (name != NAME && is.element(NAME, old_names)) [01:28:46.023] next [01:28:46.023] args[[name]] <- "" [01:28:46.023] } [01:28:46.023] NAMES <- toupper(removed) [01:28:46.023] for (kk in seq_along(NAMES)) { [01:28:46.023] name <- removed[[kk]] [01:28:46.023] NAME <- NAMES[[kk]] [01:28:46.023] if (name != NAME && is.element(NAME, old_names)) [01:28:46.023] next [01:28:46.023] args[[name]] <- ...future.oldEnvVars[[name]] [01:28:46.023] } [01:28:46.023] if (length(args) > 0) [01:28:46.023] base::do.call(base::Sys.setenv, args = args) [01:28:46.023] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [01:28:46.023] } [01:28:46.023] { [01:28:46.023] if (base::length(...future.futureOptionsAdded) > [01:28:46.023] 0L) { [01:28:46.023] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [01:28:46.023] base::names(opts) <- ...future.futureOptionsAdded [01:28:46.023] base::options(opts) [01:28:46.023] } [01:28:46.023] { [01:28:46.023] NULL [01:28:46.023] options(future.plan = NULL) [01:28:46.023] if (is.na(NA_character_)) [01:28:46.023] Sys.unsetenv("R_FUTURE_PLAN") [01:28:46.023] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [01:28:46.023] future::plan(list(function (..., workers = "localhost", [01:28:46.023] envir = parent.frame()) [01:28:46.023] strategy(..., workers = workers, envir = envir)), [01:28:46.023] .cleanup = FALSE, .init = FALSE) [01:28:46.023] } [01:28:46.023] } [01:28:46.023] } [01:28:46.023] }) [01:28:46.023] if (TRUE) { [01:28:46.023] base::sink(type = "output", split = FALSE) [01:28:46.023] if (TRUE) { [01:28:46.023] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [01:28:46.023] } [01:28:46.023] else { [01:28:46.023] ...future.result["stdout"] <- base::list(NULL) [01:28:46.023] } [01:28:46.023] base::close(...future.stdout) [01:28:46.023] ...future.stdout <- NULL [01:28:46.023] } [01:28:46.023] ...future.result$conditions <- ...future.conditions [01:28:46.023] ...future.result$finished <- base::Sys.time() [01:28:46.023] ...future.result [01:28:46.023] } [01:28:46.112] ClusterFuture started [01:28:46.112] result() for ClusterFuture ... [01:28:46.113] receiveMessageFromWorker() for ClusterFuture ... [01:28:46.113] - Validating connection of ClusterFuture [01:28:46.175] - received message: FutureResult [01:28:46.176] - Received FutureResult [01:28:46.178] - Erased future from FutureRegistry [01:28:46.179] result() for ClusterFuture ... [01:28:46.179] - result already collected: FutureResult [01:28:46.179] result() for ClusterFuture ... done [01:28:46.179] receiveMessageFromWorker() for ClusterFuture ... done [01:28:46.180] result() for ClusterFuture ... done [01:28:46.180] result() for ClusterFuture ... [01:28:46.180] - result already collected: FutureResult [01:28:46.180] result() for ClusterFuture ... done [01:28:46.180] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... DONE [01:28:46.181] plan(): nbrOfWorkers() = 1 > f <- future({ Sys.sleep(5); 1 }) [01:28:46.181] getGlobalsAndPackages() ... [01:28:46.181] Searching for globals... [01:28:46.189] - globals found: [2] '{', 'Sys.sleep' [01:28:46.189] Searching for globals ... DONE [01:28:46.190] Resolving globals: FALSE [01:28:46.190] [01:28:46.191] [01:28:46.191] getGlobalsAndPackages() ... DONE [01:28:46.191] run() for 'Future' ... [01:28:46.192] - state: 'created' [01:28:46.192] - Future backend: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [01:28:46.198] - Future class: 'ClusterFuture', 'MultiprocessFuture', 'Future', 'environment' [01:28:46.199] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... [01:28:46.199] - Field: 'node' [01:28:46.199] - Field: 'label' [01:28:46.199] - Field: 'local' [01:28:46.199] - Field: 'owner' [01:28:46.200] - Field: 'envir' [01:28:46.200] - Field: 'workers' [01:28:46.200] - Field: 'packages' [01:28:46.200] - Field: 'gc' [01:28:46.200] - Field: 'conditions' [01:28:46.201] - Field: 'persistent' [01:28:46.201] - Field: 'expr' [01:28:46.201] - Field: 'uuid' [01:28:46.201] - Field: 'seed' [01:28:46.201] - Field: 'version' [01:28:46.202] - Field: 'result' [01:28:46.202] - Field: 'asynchronous' [01:28:46.202] - Field: 'calls' [01:28:46.202] - Field: 'globals' [01:28:46.202] - Field: 'stdout' [01:28:46.203] - Field: 'earlySignal' [01:28:46.203] - Field: 'lazy' [01:28:46.203] - Field: 'state' [01:28:46.203] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... done [01:28:46.203] - Launch lazy future ... [01:28:46.204] Packages needed by the future expression (n = 0): [01:28:46.204] Packages needed by future strategies (n = 0): [01:28:46.204] { [01:28:46.204] { [01:28:46.204] { [01:28:46.204] ...future.startTime <- base::Sys.time() [01:28:46.204] { [01:28:46.204] { [01:28:46.204] { [01:28:46.204] base::local({ [01:28:46.204] has_future <- base::requireNamespace("future", [01:28:46.204] quietly = TRUE) [01:28:46.204] if (has_future) { [01:28:46.204] ns <- base::getNamespace("future") [01:28:46.204] version <- ns[[".package"]][["version"]] [01:28:46.204] if (is.null(version)) [01:28:46.204] version <- utils::packageVersion("future") [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] version <- NULL [01:28:46.204] } [01:28:46.204] if (!has_future || version < "1.8.0") { [01:28:46.204] info <- base::c(r_version = base::gsub("R version ", [01:28:46.204] "", base::R.version$version.string), [01:28:46.204] platform = base::sprintf("%s (%s-bit)", [01:28:46.204] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [01:28:46.204] os = base::paste(base::Sys.info()[base::c("sysname", [01:28:46.204] "release", "version")], collapse = " "), [01:28:46.204] hostname = base::Sys.info()[["nodename"]]) [01:28:46.204] info <- base::sprintf("%s: %s", base::names(info), [01:28:46.204] info) [01:28:46.204] info <- base::paste(info, collapse = "; ") [01:28:46.204] if (!has_future) { [01:28:46.204] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [01:28:46.204] info) [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [01:28:46.204] info, version) [01:28:46.204] } [01:28:46.204] base::stop(msg) [01:28:46.204] } [01:28:46.204] }) [01:28:46.204] } [01:28:46.204] options(future.plan = NULL) [01:28:46.204] Sys.unsetenv("R_FUTURE_PLAN") [01:28:46.204] future::plan("default", .cleanup = FALSE, .init = FALSE) [01:28:46.204] } [01:28:46.204] ...future.workdir <- getwd() [01:28:46.204] } [01:28:46.204] ...future.oldOptions <- base::as.list(base::.Options) [01:28:46.204] ...future.oldEnvVars <- base::Sys.getenv() [01:28:46.204] } [01:28:46.204] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [01:28:46.204] future.globals.maxSize = NULL, future.globals.method = NULL, [01:28:46.204] future.globals.onMissing = NULL, future.globals.onReference = NULL, [01:28:46.204] future.globals.resolve = NULL, future.resolve.recursive = NULL, [01:28:46.204] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [01:28:46.204] future.stdout.windows.reencode = NULL, width = 80L) [01:28:46.204] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [01:28:46.204] base::names(...future.oldOptions)) [01:28:46.204] } [01:28:46.204] if (FALSE) { [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] if (TRUE) { [01:28:46.204] ...future.stdout <- base::rawConnection(base::raw(0L), [01:28:46.204] open = "w") [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [01:28:46.204] windows = "NUL", "/dev/null"), open = "w") [01:28:46.204] } [01:28:46.204] base::sink(...future.stdout, type = "output", split = FALSE) [01:28:46.204] base::on.exit(if (!base::is.null(...future.stdout)) { [01:28:46.204] base::sink(type = "output", split = FALSE) [01:28:46.204] base::close(...future.stdout) [01:28:46.204] }, add = TRUE) [01:28:46.204] } [01:28:46.204] ...future.frame <- base::sys.nframe() [01:28:46.204] ...future.conditions <- base::list() [01:28:46.204] ...future.rng <- base::globalenv()$.Random.seed [01:28:46.204] if (FALSE) { [01:28:46.204] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [01:28:46.204] "...future.value", "...future.globalenv.names", ".Random.seed") [01:28:46.204] } [01:28:46.204] ...future.result <- base::tryCatch({ [01:28:46.204] base::withCallingHandlers({ [01:28:46.204] ...future.value <- base::withVisible(base::local({ [01:28:46.204] ...future.makeSendCondition <- base::local({ [01:28:46.204] sendCondition <- NULL [01:28:46.204] function(frame = 1L) { [01:28:46.204] if (is.function(sendCondition)) [01:28:46.204] return(sendCondition) [01:28:46.204] ns <- getNamespace("parallel") [01:28:46.204] if (exists("sendData", mode = "function", [01:28:46.204] envir = ns)) { [01:28:46.204] parallel_sendData <- get("sendData", mode = "function", [01:28:46.204] envir = ns) [01:28:46.204] envir <- sys.frame(frame) [01:28:46.204] master <- NULL [01:28:46.204] while (!identical(envir, .GlobalEnv) && [01:28:46.204] !identical(envir, emptyenv())) { [01:28:46.204] if (exists("master", mode = "list", envir = envir, [01:28:46.204] inherits = FALSE)) { [01:28:46.204] master <- get("master", mode = "list", [01:28:46.204] envir = envir, inherits = FALSE) [01:28:46.204] if (inherits(master, c("SOCKnode", [01:28:46.204] "SOCK0node"))) { [01:28:46.204] sendCondition <<- function(cond) { [01:28:46.204] data <- list(type = "VALUE", value = cond, [01:28:46.204] success = TRUE) [01:28:46.204] parallel_sendData(master, data) [01:28:46.204] } [01:28:46.204] return(sendCondition) [01:28:46.204] } [01:28:46.204] } [01:28:46.204] frame <- frame + 1L [01:28:46.204] envir <- sys.frame(frame) [01:28:46.204] } [01:28:46.204] } [01:28:46.204] sendCondition <<- function(cond) NULL [01:28:46.204] } [01:28:46.204] }) [01:28:46.204] withCallingHandlers({ [01:28:46.204] { [01:28:46.204] Sys.sleep(5) [01:28:46.204] 1 [01:28:46.204] } [01:28:46.204] }, immediateCondition = function(cond) { [01:28:46.204] sendCondition <- ...future.makeSendCondition() [01:28:46.204] sendCondition(cond) [01:28:46.204] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.204] { [01:28:46.204] inherits <- base::inherits [01:28:46.204] invokeRestart <- base::invokeRestart [01:28:46.204] is.null <- base::is.null [01:28:46.204] muffled <- FALSE [01:28:46.204] if (inherits(cond, "message")) { [01:28:46.204] muffled <- grepl(pattern, "muffleMessage") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleMessage") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "warning")) { [01:28:46.204] muffled <- grepl(pattern, "muffleWarning") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleWarning") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "condition")) { [01:28:46.204] if (!is.null(pattern)) { [01:28:46.204] computeRestarts <- base::computeRestarts [01:28:46.204] grepl <- base::grepl [01:28:46.204] restarts <- computeRestarts(cond) [01:28:46.204] for (restart in restarts) { [01:28:46.204] name <- restart$name [01:28:46.204] if (is.null(name)) [01:28:46.204] next [01:28:46.204] if (!grepl(pattern, name)) [01:28:46.204] next [01:28:46.204] invokeRestart(restart) [01:28:46.204] muffled <- TRUE [01:28:46.204] break [01:28:46.204] } [01:28:46.204] } [01:28:46.204] } [01:28:46.204] invisible(muffled) [01:28:46.204] } [01:28:46.204] muffleCondition(cond) [01:28:46.204] }) [01:28:46.204] })) [01:28:46.204] future::FutureResult(value = ...future.value$value, [01:28:46.204] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [01:28:46.204] ...future.rng), globalenv = if (FALSE) [01:28:46.204] list(added = base::setdiff(base::names(base::.GlobalEnv), [01:28:46.204] ...future.globalenv.names)) [01:28:46.204] else NULL, started = ...future.startTime, version = "1.8") [01:28:46.204] }, condition = base::local({ [01:28:46.204] c <- base::c [01:28:46.204] inherits <- base::inherits [01:28:46.204] invokeRestart <- base::invokeRestart [01:28:46.204] length <- base::length [01:28:46.204] list <- base::list [01:28:46.204] seq.int <- base::seq.int [01:28:46.204] signalCondition <- base::signalCondition [01:28:46.204] sys.calls <- base::sys.calls [01:28:46.204] `[[` <- base::`[[` [01:28:46.204] `+` <- base::`+` [01:28:46.204] `<<-` <- base::`<<-` [01:28:46.204] sysCalls <- function(calls = sys.calls(), from = 1L) { [01:28:46.204] calls[seq.int(from = from + 12L, to = length(calls) - [01:28:46.204] 3L)] [01:28:46.204] } [01:28:46.204] function(cond) { [01:28:46.204] is_error <- inherits(cond, "error") [01:28:46.204] ignore <- !is_error && !is.null(NULL) && inherits(cond, [01:28:46.204] NULL) [01:28:46.204] if (is_error) { [01:28:46.204] sessionInformation <- function() { [01:28:46.204] list(r = base::R.Version(), locale = base::Sys.getlocale(), [01:28:46.204] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [01:28:46.204] search = base::search(), system = base::Sys.info()) [01:28:46.204] } [01:28:46.204] ...future.conditions[[length(...future.conditions) + [01:28:46.204] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [01:28:46.204] cond$call), session = sessionInformation(), [01:28:46.204] timestamp = base::Sys.time(), signaled = 0L) [01:28:46.204] signalCondition(cond) [01:28:46.204] } [01:28:46.204] else if (!ignore && TRUE && inherits(cond, c("condition", [01:28:46.204] "immediateCondition"))) { [01:28:46.204] signal <- TRUE && inherits(cond, "immediateCondition") [01:28:46.204] ...future.conditions[[length(...future.conditions) + [01:28:46.204] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [01:28:46.204] if (TRUE && !signal) { [01:28:46.204] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.204] { [01:28:46.204] inherits <- base::inherits [01:28:46.204] invokeRestart <- base::invokeRestart [01:28:46.204] is.null <- base::is.null [01:28:46.204] muffled <- FALSE [01:28:46.204] if (inherits(cond, "message")) { [01:28:46.204] muffled <- grepl(pattern, "muffleMessage") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleMessage") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "warning")) { [01:28:46.204] muffled <- grepl(pattern, "muffleWarning") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleWarning") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "condition")) { [01:28:46.204] if (!is.null(pattern)) { [01:28:46.204] computeRestarts <- base::computeRestarts [01:28:46.204] grepl <- base::grepl [01:28:46.204] restarts <- computeRestarts(cond) [01:28:46.204] for (restart in restarts) { [01:28:46.204] name <- restart$name [01:28:46.204] if (is.null(name)) [01:28:46.204] next [01:28:46.204] if (!grepl(pattern, name)) [01:28:46.204] next [01:28:46.204] invokeRestart(restart) [01:28:46.204] muffled <- TRUE [01:28:46.204] break [01:28:46.204] } [01:28:46.204] } [01:28:46.204] } [01:28:46.204] invisible(muffled) [01:28:46.204] } [01:28:46.204] muffleCondition(cond, pattern = "^muffle") [01:28:46.204] } [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] if (TRUE) { [01:28:46.204] muffleCondition <- function (cond, pattern = "^muffle") [01:28:46.204] { [01:28:46.204] inherits <- base::inherits [01:28:46.204] invokeRestart <- base::invokeRestart [01:28:46.204] is.null <- base::is.null [01:28:46.204] muffled <- FALSE [01:28:46.204] if (inherits(cond, "message")) { [01:28:46.204] muffled <- grepl(pattern, "muffleMessage") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleMessage") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "warning")) { [01:28:46.204] muffled <- grepl(pattern, "muffleWarning") [01:28:46.204] if (muffled) [01:28:46.204] invokeRestart("muffleWarning") [01:28:46.204] } [01:28:46.204] else if (inherits(cond, "condition")) { [01:28:46.204] if (!is.null(pattern)) { [01:28:46.204] computeRestarts <- base::computeRestarts [01:28:46.204] grepl <- base::grepl [01:28:46.204] restarts <- computeRestarts(cond) [01:28:46.204] for (restart in restarts) { [01:28:46.204] name <- restart$name [01:28:46.204] if (is.null(name)) [01:28:46.204] next [01:28:46.204] if (!grepl(pattern, name)) [01:28:46.204] next [01:28:46.204] invokeRestart(restart) [01:28:46.204] muffled <- TRUE [01:28:46.204] break [01:28:46.204] } [01:28:46.204] } [01:28:46.204] } [01:28:46.204] invisible(muffled) [01:28:46.204] } [01:28:46.204] muffleCondition(cond, pattern = "^muffle") [01:28:46.204] } [01:28:46.204] } [01:28:46.204] } [01:28:46.204] })) [01:28:46.204] }, error = function(ex) { [01:28:46.204] base::structure(base::list(value = NULL, visible = NULL, [01:28:46.204] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [01:28:46.204] ...future.rng), started = ...future.startTime, [01:28:46.204] finished = Sys.time(), session_uuid = NA_character_, [01:28:46.204] version = "1.8"), class = "FutureResult") [01:28:46.204] }, finally = { [01:28:46.204] if (!identical(...future.workdir, getwd())) [01:28:46.204] setwd(...future.workdir) [01:28:46.204] { [01:28:46.204] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [01:28:46.204] ...future.oldOptions$nwarnings <- NULL [01:28:46.204] } [01:28:46.204] base::options(...future.oldOptions) [01:28:46.204] if (.Platform$OS.type == "windows") { [01:28:46.204] old_names <- names(...future.oldEnvVars) [01:28:46.204] envs <- base::Sys.getenv() [01:28:46.204] names <- names(envs) [01:28:46.204] common <- intersect(names, old_names) [01:28:46.204] added <- setdiff(names, old_names) [01:28:46.204] removed <- setdiff(old_names, names) [01:28:46.204] changed <- common[...future.oldEnvVars[common] != [01:28:46.204] envs[common]] [01:28:46.204] NAMES <- toupper(changed) [01:28:46.204] args <- list() [01:28:46.204] for (kk in seq_along(NAMES)) { [01:28:46.204] name <- changed[[kk]] [01:28:46.204] NAME <- NAMES[[kk]] [01:28:46.204] if (name != NAME && is.element(NAME, old_names)) [01:28:46.204] next [01:28:46.204] args[[name]] <- ...future.oldEnvVars[[name]] [01:28:46.204] } [01:28:46.204] NAMES <- toupper(added) [01:28:46.204] for (kk in seq_along(NAMES)) { [01:28:46.204] name <- added[[kk]] [01:28:46.204] NAME <- NAMES[[kk]] [01:28:46.204] if (name != NAME && is.element(NAME, old_names)) [01:28:46.204] next [01:28:46.204] args[[name]] <- "" [01:28:46.204] } [01:28:46.204] NAMES <- toupper(removed) [01:28:46.204] for (kk in seq_along(NAMES)) { [01:28:46.204] name <- removed[[kk]] [01:28:46.204] NAME <- NAMES[[kk]] [01:28:46.204] if (name != NAME && is.element(NAME, old_names)) [01:28:46.204] next [01:28:46.204] args[[name]] <- ...future.oldEnvVars[[name]] [01:28:46.204] } [01:28:46.204] if (length(args) > 0) [01:28:46.204] base::do.call(base::Sys.setenv, args = args) [01:28:46.204] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [01:28:46.204] } [01:28:46.204] { [01:28:46.204] if (base::length(...future.futureOptionsAdded) > [01:28:46.204] 0L) { [01:28:46.204] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [01:28:46.204] base::names(opts) <- ...future.futureOptionsAdded [01:28:46.204] base::options(opts) [01:28:46.204] } [01:28:46.204] { [01:28:46.204] NULL [01:28:46.204] options(future.plan = NULL) [01:28:46.204] if (is.na(NA_character_)) [01:28:46.204] Sys.unsetenv("R_FUTURE_PLAN") [01:28:46.204] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [01:28:46.204] future::plan(list(function (..., workers = "localhost", [01:28:46.204] envir = parent.frame()) [01:28:46.204] strategy(..., workers = workers, envir = envir)), [01:28:46.204] .cleanup = FALSE, .init = FALSE) [01:28:46.204] } [01:28:46.204] } [01:28:46.204] } [01:28:46.204] }) [01:28:46.204] if (TRUE) { [01:28:46.204] base::sink(type = "output", split = FALSE) [01:28:46.204] if (TRUE) { [01:28:46.204] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [01:28:46.204] } [01:28:46.204] else { [01:28:46.204] ...future.result["stdout"] <- base::list(NULL) [01:28:46.204] } [01:28:46.204] base::close(...future.stdout) [01:28:46.204] ...future.stdout <- NULL [01:28:46.204] } [01:28:46.204] ...future.result$conditions <- ...future.conditions [01:28:46.204] ...future.result$finished <- base::Sys.time() [01:28:46.204] ...future.result [01:28:46.204] } [01:28:46.210] ClusterFuture started [01:28:46.210] - Launch lazy future ... done [01:28:46.211] run() for 'ClusterFuture' ... done > > res <- tryCatch({ + requestNode(function() { }, workers = f$workers, timeout = 0.5, delta = 0.1) + }, error = identity) [01:28:46.211] Poll #1 (0): usedNodes() = 1, workers = 1 [01:28:46.326] Poll #2 (0.11 secs): usedNodes() = 1, workers = 1 [01:28:46.438] Poll #3 (0.23 secs): usedNodes() = 1, workers = 1 [01:28:46.545] Poll #4 (0.33 secs): usedNodes() = 1, workers = 1 [01:28:46.654] Poll #5 (0.44 secs): usedNodes() = 1, workers = 1 [01:28:46.764] TIMEOUT: All 1 cluster nodes are still occupied after 0.55 secs (polled 6 times) > stopifnot(inherits(res, "error")) > > v <- value(f) [01:28:46.764] result() for ClusterFuture ... [01:28:46.765] receiveMessageFromWorker() for ClusterFuture ... [01:28:46.765] - Validating connection of ClusterFuture [01:28:51.374] - received message: FutureResult [01:28:51.374] - Received FutureResult [01:28:51.375] - Erased future from FutureRegistry [01:28:51.375] result() for ClusterFuture ... [01:28:51.375] - result already collected: FutureResult [01:28:51.375] result() for ClusterFuture ... done [01:28:51.375] receiveMessageFromWorker() for ClusterFuture ... done [01:28:51.375] result() for ClusterFuture ... done [01:28:51.376] result() for ClusterFuture ... [01:28:51.376] - result already collected: FutureResult [01:28:51.376] result() for ClusterFuture ... done > print(v) [1] 1 > stopifnot(v == 1L) > > message("*** requestNode() - timeout ... DONE") *** requestNode() - timeout ... DONE > > message("*** requestNode() ... DONE") *** requestNode() ... DONE > > source("incl/end.R") [01:28:51.377] plan(): Setting new future strategy stack: [01:28:51.377] List of future strategies: [01:28:51.377] 1. FutureStrategy: [01:28:51.377] - args: function (..., envir = parent.frame(), workers = "") [01:28:51.377] - tweaked: FALSE [01:28:51.377] - call: future::plan(oplan) [01:28:51.378] plan(): nbrOfWorkers() = 1 Failed to undo environment variables: - Expected environment variables: [n=204] '!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', 'RTOOLS43_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_RTOOLS43_PATH', 'R_SCRIPT_LEGACY', 'R_SHARE_DIR', '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_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_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_SUBDIRS_STRICT_', '_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_USE_ALIASES_FROM_CRAN_', '_R_CLASS_MATRIX_ARRAY_', '_R_INSTALL_TIME_PATCHES_', '_R_S3_METHOD_LOOKUP_BASEENV_AFTER_GLOBALENV_', '_R_SHLIB_BUILD_OBJECTS_SYMBOL_TABLES_', '__R_CHECK_DOC_FILES_NOTE_IF_ALL_SPECIAL__', 'maj.version', 'nextArg--timingsnextArg--install' - Environment variables still there: [n=0] - Environment variables missing: [n=1] 'MAKEFLAGS' Differences environment variable by environment variable: List of 3 $ name : chr "MAKEFLAGS" $ expected: 'Dlist' chr "" $ actual : 'Dlist' chr NA > > proc.time() user system elapsed 0.65 0.03 6.82