R Under development (unstable) (2024-03-25 r86192 ucrt) -- "Unsuffered Consequences" Copyright (C) 2024 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") [17:27:36.823] plan(): Setting new future strategy stack: [17:27:36.825] List of future strategies: [17:27:36.825] 1. sequential: [17:27:36.825] - args: function (..., envir = parent.frame(), workers = "") [17:27:36.825] - tweaked: FALSE [17:27:36.825] - call: future::plan("sequential") [17:27:36.840] plan(): nbrOfWorkers() = 1 > > message("*** requestNode() ...") *** requestNode() ... > > message("*** requestNode() - exceptions ...") *** requestNode() - exceptions ... > > workers <- makeClusterPSOCK(2L) [17:27:36.847] [local output] makeClusterPSOCK() ... [17:27:36.876] [local output] Workers: [n = 2] 'localhost', 'localhost' [17:27:36.882] [local output] Base port: 26103 [17:27:36.883] [local output] Getting setup options for 2 cluster nodes ... [17:27:36.883] [local output] - Node 1 of 2 ... [17:27:36.883] [local output] localMachine=TRUE => revtunnel=FALSE [17:27:36.885] Testing if worker's PID can be inferred: '"D:/RCompile/recent/R/bin/x64/Rscript" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c3e6d19d5.pid\")), silent = TRUE)" -e "file.exists(\"D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c3e6d19d5.pid\")"' [17:27:37.326] - Possible to infer worker's PID: TRUE [17:27:37.327] [local output] Rscript port: 26103 [17:27:37.327] [local output] - Node 2 of 2 ... [17:27:37.327] [local output] localMachine=TRUE => revtunnel=FALSE [17:27:37.329] [local output] Rscript port: 26103 [17:27:37.329] [local output] Getting setup options for 2 cluster nodes ... done [17:27:37.329] [local output] - Parallel setup requested for some PSOCK nodes [17:27:37.330] [local output] Setting up PSOCK nodes in parallel [17:27:37.330] List of 36 [17:27:37.330] $ worker : chr "localhost" [17:27:37.330] ..- attr(*, "localhost")= logi TRUE [17:27:37.330] $ master : chr "localhost" [17:27:37.330] $ port : int 26103 [17:27:37.330] $ connectTimeout : num 120 [17:27:37.330] $ timeout : num 120 [17:27:37.330] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [17:27:37.330] $ homogeneous : logi TRUE [17:27:37.330] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:151916:CRANWIN3:CR"| __truncated__ [17:27:37.330] $ rscript_envs : NULL [17:27:37.330] $ rscript_libs : NULL [17:27:37.330] $ rscript_startup : NULL [17:27:37.330] $ rscript_sh : chr "cmd" [17:27:37.330] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:27:37.330] $ methods : logi TRUE [17:27:37.330] $ socketOptions : chr "no-delay" [17:27:37.330] $ useXDR : logi FALSE [17:27:37.330] $ outfile : chr "/dev/null" [17:27:37.330] $ renice : int NA [17:27:37.330] $ rshcmd : NULL [17:27:37.330] $ user : chr(0) [17:27:37.330] $ revtunnel : logi FALSE [17:27:37.330] $ rshlogfile : NULL [17:27:37.330] $ rshopts : chr(0) [17:27:37.330] $ rank : int 1 [17:27:37.330] $ manual : logi FALSE [17:27:37.330] $ dryrun : logi FALSE [17:27:37.330] $ quiet : logi FALSE [17:27:37.330] $ setup_strategy : chr "parallel" [17:27:37.330] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:27:37.330] $ pidfile : chr "D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c3e6d19d5.pid" [17:27:37.330] $ rshcmd_label : NULL [17:27:37.330] $ rsh_call : NULL [17:27:37.330] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:27:37.330] $ localMachine : logi TRUE [17:27:37.330] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [17:27:37.330] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [17:27:37.330] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [17:27:37.330] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [17:27:37.330] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [17:27:37.330] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [17:27:37.330] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [17:27:37.330] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [17:27:37.330] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [17:27:37.330] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [17:27:37.330] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [17:27:37.330] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [17:27:37.330] "parallel"), action = c("launch", "options"), verbose = FALSE) [17:27:37.330] $ arguments :List of 28 [17:27:37.330] ..$ worker : chr "localhost" [17:27:37.330] ..$ master : NULL [17:27:37.330] ..$ port : int 26103 [17:27:37.330] ..$ connectTimeout : num 120 [17:27:37.330] ..$ timeout : num 120 [17:27:37.330] ..$ rscript : NULL [17:27:37.330] ..$ homogeneous : NULL [17:27:37.330] ..$ rscript_args : NULL [17:27:37.330] ..$ rscript_envs : NULL [17:27:37.330] ..$ rscript_libs : NULL [17:27:37.330] ..$ rscript_startup : NULL [17:27:37.330] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [17:27:37.330] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:27:37.330] ..$ methods : logi TRUE [17:27:37.330] ..$ socketOptions : chr "no-delay" [17:27:37.330] ..$ useXDR : logi FALSE [17:27:37.330] ..$ outfile : chr "/dev/null" [17:27:37.330] ..$ renice : int NA [17:27:37.330] ..$ rshcmd : NULL [17:27:37.330] ..$ user : NULL [17:27:37.330] ..$ revtunnel : logi NA [17:27:37.330] ..$ rshlogfile : NULL [17:27:37.330] ..$ rshopts : NULL [17:27:37.330] ..$ rank : int 1 [17:27:37.330] ..$ manual : logi FALSE [17:27:37.330] ..$ dryrun : logi FALSE [17:27:37.330] ..$ quiet : logi FALSE [17:27:37.330] ..$ setup_strategy : chr "parallel" [17:27:37.330] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [17:27:37.353] [local output] System call to launch all workers: [17:27:37.354] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:151916:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c3e6d19d5.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=26103 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [17:27:37.354] [local output] Starting PSOCK main server [17:27:37.360] [local output] Workers launched [17:27:37.360] [local output] Waiting for workers to connect back [17:27:37.360] - [local output] 0 workers out of 2 ready [17:27:37.522] - [local output] 0 workers out of 2 ready [17:27:37.522] - [local output] 1 workers out of 2 ready [17:27:37.526] - [local output] 1 workers out of 2 ready [17:27:37.526] - [local output] 2 workers out of 2 ready [17:27:37.527] [local output] Launching of workers completed [17:27:37.527] [local output] Collecting session information from workers [17:27:37.528] [local output] - Worker #1 of 2 [17:27:37.528] [local output] - Worker #2 of 2 [17:27:37.529] [local output] makeClusterPSOCK() ... done > print(workers) Socket cluster with 2 nodes where 2 nodes are on host 'localhost' (R Under development (unstable) (2024-03-25 r86192 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") [17:27:37.535] plan(): Setting new future strategy stack: [17:27:37.535] List of future strategies: [17:27:37.535] 1. cluster: [17:27:37.535] - args: function (..., workers = "localhost", envir = parent.frame()) [17:27:37.535] - tweaked: TRUE [17:27:37.535] - call: plan(cluster, workers = "localhost") [17:27:37.553] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [17:27:37.553] cluster: [17:27:37.553] - args: function (..., workers = "localhost", envir = parent.frame()) [17:27:37.553] - tweaked: TRUE [17:27:37.553] - call: plan(cluster, workers = "localhost") [17:27:37.555] getGlobalsAndPackages() ... [17:27:37.555] Not searching for globals [17:27:37.555] - globals: [0] [17:27:37.555] getGlobalsAndPackages() ... DONE [17:27:37.556] [local output] makeClusterPSOCK() ... [17:27:37.557] [local output] Workers: [n = 1] 'localhost' [17:27:37.559] [local output] Base port: 25419 [17:27:37.560] [local output] Getting setup options for 1 cluster nodes ... [17:27:37.560] [local output] - Node 1 of 1 ... [17:27:37.560] [local output] localMachine=TRUE => revtunnel=FALSE [17:27:37.562] [local output] Rscript port: 25419 [17:27:37.562] [local output] Getting setup options for 1 cluster nodes ... done [17:27:37.562] [local output] - Parallel setup requested for some PSOCK nodes [17:27:37.563] [local output] Setting up PSOCK nodes in parallel [17:27:37.563] List of 36 [17:27:37.563] $ worker : chr "localhost" [17:27:37.563] ..- attr(*, "localhost")= logi TRUE [17:27:37.563] $ master : chr "localhost" [17:27:37.563] $ port : int 25419 [17:27:37.563] $ connectTimeout : num 120 [17:27:37.563] $ timeout : num 120 [17:27:37.563] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [17:27:37.563] $ homogeneous : logi TRUE [17:27:37.563] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:151916:CRANWIN3:CR"| __truncated__ [17:27:37.563] $ rscript_envs : NULL [17:27:37.563] $ rscript_libs : NULL [17:27:37.563] $ rscript_startup : NULL [17:27:37.563] $ rscript_sh : chr "cmd" [17:27:37.563] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:27:37.563] $ methods : logi TRUE [17:27:37.563] $ socketOptions : chr "no-delay" [17:27:37.563] $ useXDR : logi FALSE [17:27:37.563] $ outfile : chr "/dev/null" [17:27:37.563] $ renice : int NA [17:27:37.563] $ rshcmd : NULL [17:27:37.563] $ user : chr(0) [17:27:37.563] $ revtunnel : logi FALSE [17:27:37.563] $ rshlogfile : NULL [17:27:37.563] $ rshopts : chr(0) [17:27:37.563] $ rank : int 1 [17:27:37.563] $ manual : logi FALSE [17:27:37.563] $ dryrun : logi FALSE [17:27:37.563] $ quiet : logi FALSE [17:27:37.563] $ setup_strategy : chr "parallel" [17:27:37.563] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:27:37.563] $ pidfile : chr "D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c7e9e4035.pid" [17:27:37.563] $ rshcmd_label : NULL [17:27:37.563] $ rsh_call : NULL [17:27:37.563] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:27:37.563] $ localMachine : logi TRUE [17:27:37.563] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [17:27:37.563] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [17:27:37.563] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [17:27:37.563] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [17:27:37.563] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [17:27:37.563] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [17:27:37.563] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [17:27:37.563] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [17:27:37.563] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [17:27:37.563] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [17:27:37.563] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [17:27:37.563] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [17:27:37.563] "parallel"), action = c("launch", "options"), verbose = FALSE) [17:27:37.563] $ arguments :List of 28 [17:27:37.563] ..$ worker : chr "localhost" [17:27:37.563] ..$ master : NULL [17:27:37.563] ..$ port : int 25419 [17:27:37.563] ..$ connectTimeout : num 120 [17:27:37.563] ..$ timeout : num 120 [17:27:37.563] ..$ rscript : NULL [17:27:37.563] ..$ homogeneous : NULL [17:27:37.563] ..$ rscript_args : NULL [17:27:37.563] ..$ rscript_envs : NULL [17:27:37.563] ..$ rscript_libs : NULL [17:27:37.563] ..$ rscript_startup : NULL [17:27:37.563] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [17:27:37.563] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:27:37.563] ..$ methods : logi TRUE [17:27:37.563] ..$ socketOptions : chr "no-delay" [17:27:37.563] ..$ useXDR : logi FALSE [17:27:37.563] ..$ outfile : chr "/dev/null" [17:27:37.563] ..$ renice : int NA [17:27:37.563] ..$ rshcmd : NULL [17:27:37.563] ..$ user : NULL [17:27:37.563] ..$ revtunnel : logi NA [17:27:37.563] ..$ rshlogfile : NULL [17:27:37.563] ..$ rshopts : NULL [17:27:37.563] ..$ rank : int 1 [17:27:37.563] ..$ manual : logi FALSE [17:27:37.563] ..$ dryrun : logi FALSE [17:27:37.563] ..$ quiet : logi FALSE [17:27:37.563] ..$ setup_strategy : chr "parallel" [17:27:37.563] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [17:27:37.587] [local output] System call to launch all workers: [17:27:37.588] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:151916:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpSwbpQ2/worker.rank=1.parallelly.parent=151916.2516c7e9e4035.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=25419 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [17:27:37.588] [local output] Starting PSOCK main server [17:27:37.592] [local output] Workers launched [17:27:37.592] [local output] Waiting for workers to connect back [17:27:37.592] - [local output] 0 workers out of 1 ready [17:27:37.751] - [local output] 0 workers out of 1 ready [17:27:37.751] - [local output] 1 workers out of 1 ready [17:27:37.751] [local output] Launching of workers completed [17:27:37.751] [local output] Collecting session information from workers [17:27:37.752] [local output] - Worker #1 of 1 [17:27:37.752] [local output] makeClusterPSOCK() ... done [17:27:37.760] Packages needed by the future expression (n = 0): [17:27:37.760] Packages needed by future strategies (n = 0): [17:27:37.761] { [17:27:37.761] { [17:27:37.761] { [17:27:37.761] ...future.startTime <- base::Sys.time() [17:27:37.761] { [17:27:37.761] { [17:27:37.761] { [17:27:37.761] base::local({ [17:27:37.761] has_future <- base::requireNamespace("future", [17:27:37.761] quietly = TRUE) [17:27:37.761] if (has_future) { [17:27:37.761] ns <- base::getNamespace("future") [17:27:37.761] version <- ns[[".package"]][["version"]] [17:27:37.761] if (is.null(version)) [17:27:37.761] version <- utils::packageVersion("future") [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] version <- NULL [17:27:37.761] } [17:27:37.761] if (!has_future || version < "1.8.0") { [17:27:37.761] info <- base::c(r_version = base::gsub("R version ", [17:27:37.761] "", base::R.version$version.string), [17:27:37.761] platform = base::sprintf("%s (%s-bit)", [17:27:37.761] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [17:27:37.761] os = base::paste(base::Sys.info()[base::c("sysname", [17:27:37.761] "release", "version")], collapse = " "), [17:27:37.761] hostname = base::Sys.info()[["nodename"]]) [17:27:37.761] info <- base::sprintf("%s: %s", base::names(info), [17:27:37.761] info) [17:27:37.761] info <- base::paste(info, collapse = "; ") [17:27:37.761] if (!has_future) { [17:27:37.761] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [17:27:37.761] info) [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [17:27:37.761] info, version) [17:27:37.761] } [17:27:37.761] base::stop(msg) [17:27:37.761] } [17:27:37.761] }) [17:27:37.761] } [17:27:37.761] ...future.strategy.old <- future::plan("list") [17:27:37.761] options(future.plan = NULL) [17:27:37.761] Sys.unsetenv("R_FUTURE_PLAN") [17:27:37.761] future::plan("default", .cleanup = FALSE, .init = FALSE) [17:27:37.761] } [17:27:37.761] ...future.workdir <- getwd() [17:27:37.761] } [17:27:37.761] ...future.oldOptions <- base::as.list(base::.Options) [17:27:37.761] ...future.oldEnvVars <- base::Sys.getenv() [17:27:37.761] } [17:27:37.761] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [17:27:37.761] future.globals.maxSize = NULL, future.globals.method = NULL, [17:27:37.761] future.globals.onMissing = NULL, future.globals.onReference = NULL, [17:27:37.761] future.globals.resolve = NULL, future.resolve.recursive = NULL, [17:27:37.761] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [17:27:37.761] future.stdout.windows.reencode = NULL, width = 80L) [17:27:37.761] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [17:27:37.761] base::names(...future.oldOptions)) [17:27:37.761] } [17:27:37.761] if (FALSE) { [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] if (TRUE) { [17:27:37.761] ...future.stdout <- base::rawConnection(base::raw(0L), [17:27:37.761] open = "w") [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [17:27:37.761] windows = "NUL", "/dev/null"), open = "w") [17:27:37.761] } [17:27:37.761] base::sink(...future.stdout, type = "output", split = FALSE) [17:27:37.761] base::on.exit(if (!base::is.null(...future.stdout)) { [17:27:37.761] base::sink(type = "output", split = FALSE) [17:27:37.761] base::close(...future.stdout) [17:27:37.761] }, add = TRUE) [17:27:37.761] } [17:27:37.761] ...future.frame <- base::sys.nframe() [17:27:37.761] ...future.conditions <- base::list() [17:27:37.761] ...future.rng <- base::globalenv()$.Random.seed [17:27:37.761] if (FALSE) { [17:27:37.761] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [17:27:37.761] "...future.value", "...future.globalenv.names", ".Random.seed") [17:27:37.761] } [17:27:37.761] ...future.result <- base::tryCatch({ [17:27:37.761] base::withCallingHandlers({ [17:27:37.761] ...future.value <- base::withVisible(base::local({ [17:27:37.761] ...future.makeSendCondition <- base::local({ [17:27:37.761] sendCondition <- NULL [17:27:37.761] function(frame = 1L) { [17:27:37.761] if (is.function(sendCondition)) [17:27:37.761] return(sendCondition) [17:27:37.761] ns <- getNamespace("parallel") [17:27:37.761] if (exists("sendData", mode = "function", [17:27:37.761] envir = ns)) { [17:27:37.761] parallel_sendData <- get("sendData", mode = "function", [17:27:37.761] envir = ns) [17:27:37.761] envir <- sys.frame(frame) [17:27:37.761] master <- NULL [17:27:37.761] while (!identical(envir, .GlobalEnv) && [17:27:37.761] !identical(envir, emptyenv())) { [17:27:37.761] if (exists("master", mode = "list", envir = envir, [17:27:37.761] inherits = FALSE)) { [17:27:37.761] master <- get("master", mode = "list", [17:27:37.761] envir = envir, inherits = FALSE) [17:27:37.761] if (inherits(master, c("SOCKnode", [17:27:37.761] "SOCK0node"))) { [17:27:37.761] sendCondition <<- function(cond) { [17:27:37.761] data <- list(type = "VALUE", value = cond, [17:27:37.761] success = TRUE) [17:27:37.761] parallel_sendData(master, data) [17:27:37.761] } [17:27:37.761] return(sendCondition) [17:27:37.761] } [17:27:37.761] } [17:27:37.761] frame <- frame + 1L [17:27:37.761] envir <- sys.frame(frame) [17:27:37.761] } [17:27:37.761] } [17:27:37.761] sendCondition <<- function(cond) NULL [17:27:37.761] } [17:27:37.761] }) [17:27:37.761] withCallingHandlers({ [17:27:37.761] NA [17:27:37.761] }, immediateCondition = function(cond) { [17:27:37.761] sendCondition <- ...future.makeSendCondition() [17:27:37.761] sendCondition(cond) [17:27:37.761] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.761] { [17:27:37.761] inherits <- base::inherits [17:27:37.761] invokeRestart <- base::invokeRestart [17:27:37.761] is.null <- base::is.null [17:27:37.761] muffled <- FALSE [17:27:37.761] if (inherits(cond, "message")) { [17:27:37.761] muffled <- grepl(pattern, "muffleMessage") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleMessage") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "warning")) { [17:27:37.761] muffled <- grepl(pattern, "muffleWarning") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleWarning") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "condition")) { [17:27:37.761] if (!is.null(pattern)) { [17:27:37.761] computeRestarts <- base::computeRestarts [17:27:37.761] grepl <- base::grepl [17:27:37.761] restarts <- computeRestarts(cond) [17:27:37.761] for (restart in restarts) { [17:27:37.761] name <- restart$name [17:27:37.761] if (is.null(name)) [17:27:37.761] next [17:27:37.761] if (!grepl(pattern, name)) [17:27:37.761] next [17:27:37.761] invokeRestart(restart) [17:27:37.761] muffled <- TRUE [17:27:37.761] break [17:27:37.761] } [17:27:37.761] } [17:27:37.761] } [17:27:37.761] invisible(muffled) [17:27:37.761] } [17:27:37.761] muffleCondition(cond) [17:27:37.761] }) [17:27:37.761] })) [17:27:37.761] future::FutureResult(value = ...future.value$value, [17:27:37.761] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [17:27:37.761] ...future.rng), globalenv = if (FALSE) [17:27:37.761] list(added = base::setdiff(base::names(base::.GlobalEnv), [17:27:37.761] ...future.globalenv.names)) [17:27:37.761] else NULL, started = ...future.startTime, version = "1.8") [17:27:37.761] }, condition = base::local({ [17:27:37.761] c <- base::c [17:27:37.761] inherits <- base::inherits [17:27:37.761] invokeRestart <- base::invokeRestart [17:27:37.761] length <- base::length [17:27:37.761] list <- base::list [17:27:37.761] seq.int <- base::seq.int [17:27:37.761] signalCondition <- base::signalCondition [17:27:37.761] sys.calls <- base::sys.calls [17:27:37.761] `[[` <- base::`[[` [17:27:37.761] `+` <- base::`+` [17:27:37.761] `<<-` <- base::`<<-` [17:27:37.761] sysCalls <- function(calls = sys.calls(), from = 1L) { [17:27:37.761] calls[seq.int(from = from + 12L, to = length(calls) - [17:27:37.761] 3L)] [17:27:37.761] } [17:27:37.761] function(cond) { [17:27:37.761] is_error <- inherits(cond, "error") [17:27:37.761] ignore <- !is_error && !is.null(NULL) && inherits(cond, [17:27:37.761] NULL) [17:27:37.761] if (is_error) { [17:27:37.761] sessionInformation <- function() { [17:27:37.761] list(r = base::R.Version(), locale = base::Sys.getlocale(), [17:27:37.761] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [17:27:37.761] search = base::search(), system = base::Sys.info()) [17:27:37.761] } [17:27:37.761] ...future.conditions[[length(...future.conditions) + [17:27:37.761] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [17:27:37.761] cond$call), session = sessionInformation(), [17:27:37.761] timestamp = base::Sys.time(), signaled = 0L) [17:27:37.761] signalCondition(cond) [17:27:37.761] } [17:27:37.761] else if (!ignore && TRUE && inherits(cond, c("condition", [17:27:37.761] "immediateCondition"))) { [17:27:37.761] signal <- TRUE && inherits(cond, "immediateCondition") [17:27:37.761] ...future.conditions[[length(...future.conditions) + [17:27:37.761] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [17:27:37.761] if (TRUE && !signal) { [17:27:37.761] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.761] { [17:27:37.761] inherits <- base::inherits [17:27:37.761] invokeRestart <- base::invokeRestart [17:27:37.761] is.null <- base::is.null [17:27:37.761] muffled <- FALSE [17:27:37.761] if (inherits(cond, "message")) { [17:27:37.761] muffled <- grepl(pattern, "muffleMessage") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleMessage") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "warning")) { [17:27:37.761] muffled <- grepl(pattern, "muffleWarning") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleWarning") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "condition")) { [17:27:37.761] if (!is.null(pattern)) { [17:27:37.761] computeRestarts <- base::computeRestarts [17:27:37.761] grepl <- base::grepl [17:27:37.761] restarts <- computeRestarts(cond) [17:27:37.761] for (restart in restarts) { [17:27:37.761] name <- restart$name [17:27:37.761] if (is.null(name)) [17:27:37.761] next [17:27:37.761] if (!grepl(pattern, name)) [17:27:37.761] next [17:27:37.761] invokeRestart(restart) [17:27:37.761] muffled <- TRUE [17:27:37.761] break [17:27:37.761] } [17:27:37.761] } [17:27:37.761] } [17:27:37.761] invisible(muffled) [17:27:37.761] } [17:27:37.761] muffleCondition(cond, pattern = "^muffle") [17:27:37.761] } [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] if (TRUE) { [17:27:37.761] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.761] { [17:27:37.761] inherits <- base::inherits [17:27:37.761] invokeRestart <- base::invokeRestart [17:27:37.761] is.null <- base::is.null [17:27:37.761] muffled <- FALSE [17:27:37.761] if (inherits(cond, "message")) { [17:27:37.761] muffled <- grepl(pattern, "muffleMessage") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleMessage") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "warning")) { [17:27:37.761] muffled <- grepl(pattern, "muffleWarning") [17:27:37.761] if (muffled) [17:27:37.761] invokeRestart("muffleWarning") [17:27:37.761] } [17:27:37.761] else if (inherits(cond, "condition")) { [17:27:37.761] if (!is.null(pattern)) { [17:27:37.761] computeRestarts <- base::computeRestarts [17:27:37.761] grepl <- base::grepl [17:27:37.761] restarts <- computeRestarts(cond) [17:27:37.761] for (restart in restarts) { [17:27:37.761] name <- restart$name [17:27:37.761] if (is.null(name)) [17:27:37.761] next [17:27:37.761] if (!grepl(pattern, name)) [17:27:37.761] next [17:27:37.761] invokeRestart(restart) [17:27:37.761] muffled <- TRUE [17:27:37.761] break [17:27:37.761] } [17:27:37.761] } [17:27:37.761] } [17:27:37.761] invisible(muffled) [17:27:37.761] } [17:27:37.761] muffleCondition(cond, pattern = "^muffle") [17:27:37.761] } [17:27:37.761] } [17:27:37.761] } [17:27:37.761] })) [17:27:37.761] }, error = function(ex) { [17:27:37.761] base::structure(base::list(value = NULL, visible = NULL, [17:27:37.761] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [17:27:37.761] ...future.rng), started = ...future.startTime, [17:27:37.761] finished = Sys.time(), session_uuid = NA_character_, [17:27:37.761] version = "1.8"), class = "FutureResult") [17:27:37.761] }, finally = { [17:27:37.761] if (!identical(...future.workdir, getwd())) [17:27:37.761] setwd(...future.workdir) [17:27:37.761] { [17:27:37.761] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [17:27:37.761] ...future.oldOptions$nwarnings <- NULL [17:27:37.761] } [17:27:37.761] base::options(...future.oldOptions) [17:27:37.761] if (.Platform$OS.type == "windows") { [17:27:37.761] old_names <- names(...future.oldEnvVars) [17:27:37.761] envs <- base::Sys.getenv() [17:27:37.761] names <- names(envs) [17:27:37.761] common <- intersect(names, old_names) [17:27:37.761] added <- setdiff(names, old_names) [17:27:37.761] removed <- setdiff(old_names, names) [17:27:37.761] changed <- common[...future.oldEnvVars[common] != [17:27:37.761] envs[common]] [17:27:37.761] NAMES <- toupper(changed) [17:27:37.761] args <- list() [17:27:37.761] for (kk in seq_along(NAMES)) { [17:27:37.761] name <- changed[[kk]] [17:27:37.761] NAME <- NAMES[[kk]] [17:27:37.761] if (name != NAME && is.element(NAME, old_names)) [17:27:37.761] next [17:27:37.761] args[[name]] <- ...future.oldEnvVars[[name]] [17:27:37.761] } [17:27:37.761] NAMES <- toupper(added) [17:27:37.761] for (kk in seq_along(NAMES)) { [17:27:37.761] name <- added[[kk]] [17:27:37.761] NAME <- NAMES[[kk]] [17:27:37.761] if (name != NAME && is.element(NAME, old_names)) [17:27:37.761] next [17:27:37.761] args[[name]] <- "" [17:27:37.761] } [17:27:37.761] NAMES <- toupper(removed) [17:27:37.761] for (kk in seq_along(NAMES)) { [17:27:37.761] name <- removed[[kk]] [17:27:37.761] NAME <- NAMES[[kk]] [17:27:37.761] if (name != NAME && is.element(NAME, old_names)) [17:27:37.761] next [17:27:37.761] args[[name]] <- ...future.oldEnvVars[[name]] [17:27:37.761] } [17:27:37.761] if (length(args) > 0) [17:27:37.761] base::do.call(base::Sys.setenv, args = args) [17:27:37.761] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [17:27:37.761] } [17:27:37.761] { [17:27:37.761] if (base::length(...future.futureOptionsAdded) > [17:27:37.761] 0L) { [17:27:37.761] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [17:27:37.761] base::names(opts) <- ...future.futureOptionsAdded [17:27:37.761] base::options(opts) [17:27:37.761] } [17:27:37.761] { [17:27:37.761] NULL [17:27:37.761] options(future.plan = NULL) [17:27:37.761] if (is.na(NA_character_)) [17:27:37.761] Sys.unsetenv("R_FUTURE_PLAN") [17:27:37.761] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [17:27:37.761] future::plan(...future.strategy.old, .cleanup = FALSE, [17:27:37.761] .init = FALSE) [17:27:37.761] } [17:27:37.761] } [17:27:37.761] } [17:27:37.761] }) [17:27:37.761] if (TRUE) { [17:27:37.761] base::sink(type = "output", split = FALSE) [17:27:37.761] if (TRUE) { [17:27:37.761] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [17:27:37.761] } [17:27:37.761] else { [17:27:37.761] ...future.result["stdout"] <- base::list(NULL) [17:27:37.761] } [17:27:37.761] base::close(...future.stdout) [17:27:37.761] ...future.stdout <- NULL [17:27:37.761] } [17:27:37.761] ...future.result$conditions <- ...future.conditions [17:27:37.761] ...future.result$finished <- base::Sys.time() [17:27:37.761] ...future.result [17:27:37.761] } [17:27:37.843] ClusterFuture started [17:27:37.844] result() for ClusterFuture ... [17:27:37.844] receiveMessageFromWorker() for ClusterFuture ... [17:27:37.845] - Validating connection of ClusterFuture [17:27:37.895] - received message: FutureResult [17:27:37.896] - Received FutureResult [17:27:37.899] - Erased future from FutureRegistry [17:27:37.899] result() for ClusterFuture ... [17:27:37.899] - result already collected: FutureResult [17:27:37.899] result() for ClusterFuture ... done [17:27:37.900] receiveMessageFromWorker() for ClusterFuture ... done [17:27:37.900] result() for ClusterFuture ... done [17:27:37.900] result() for ClusterFuture ... [17:27:37.900] - result already collected: FutureResult [17:27:37.900] result() for ClusterFuture ... done [17:27:37.900] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... DONE [17:27:37.901] plan(): nbrOfWorkers() = 1 > f <- future({ Sys.sleep(5); 1 }) [17:27:37.901] getGlobalsAndPackages() ... [17:27:37.901] Searching for globals... [17:27:37.909] - globals found: [2] '{', 'Sys.sleep' [17:27:37.909] Searching for globals ... DONE [17:27:37.909] Resolving globals: FALSE [17:27:37.910] [17:27:37.910] [17:27:37.910] getGlobalsAndPackages() ... DONE [17:27:37.910] run() for 'Future' ... [17:27:37.911] - state: 'created' [17:27:37.911] - Future backend: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [17:27:37.917] - Future class: 'ClusterFuture', 'MultiprocessFuture', 'Future', 'environment' [17:27:37.917] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... [17:27:37.918] - Field: 'node' [17:27:37.918] - Field: 'label' [17:27:37.918] - Field: 'local' [17:27:37.918] - Field: 'owner' [17:27:37.918] - Field: 'envir' [17:27:37.919] - Field: 'workers' [17:27:37.919] - Field: 'packages' [17:27:37.919] - Field: 'gc' [17:27:37.919] - Field: 'conditions' [17:27:37.919] - Field: 'persistent' [17:27:37.920] - Field: 'expr' [17:27:37.920] - Field: 'uuid' [17:27:37.920] - Field: 'seed' [17:27:37.920] - Field: 'version' [17:27:37.920] - Field: 'result' [17:27:37.921] - Field: 'asynchronous' [17:27:37.921] - Field: 'calls' [17:27:37.921] - Field: 'globals' [17:27:37.921] - Field: 'stdout' [17:27:37.921] - Field: 'earlySignal' [17:27:37.921] - Field: 'lazy' [17:27:37.922] - Field: 'state' [17:27:37.922] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... done [17:27:37.922] - Launch lazy future ... [17:27:37.922] Packages needed by the future expression (n = 0): [17:27:37.922] Packages needed by future strategies (n = 0): [17:27:37.923] { [17:27:37.923] { [17:27:37.923] { [17:27:37.923] ...future.startTime <- base::Sys.time() [17:27:37.923] { [17:27:37.923] { [17:27:37.923] { [17:27:37.923] base::local({ [17:27:37.923] has_future <- base::requireNamespace("future", [17:27:37.923] quietly = TRUE) [17:27:37.923] if (has_future) { [17:27:37.923] ns <- base::getNamespace("future") [17:27:37.923] version <- ns[[".package"]][["version"]] [17:27:37.923] if (is.null(version)) [17:27:37.923] version <- utils::packageVersion("future") [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] version <- NULL [17:27:37.923] } [17:27:37.923] if (!has_future || version < "1.8.0") { [17:27:37.923] info <- base::c(r_version = base::gsub("R version ", [17:27:37.923] "", base::R.version$version.string), [17:27:37.923] platform = base::sprintf("%s (%s-bit)", [17:27:37.923] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [17:27:37.923] os = base::paste(base::Sys.info()[base::c("sysname", [17:27:37.923] "release", "version")], collapse = " "), [17:27:37.923] hostname = base::Sys.info()[["nodename"]]) [17:27:37.923] info <- base::sprintf("%s: %s", base::names(info), [17:27:37.923] info) [17:27:37.923] info <- base::paste(info, collapse = "; ") [17:27:37.923] if (!has_future) { [17:27:37.923] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [17:27:37.923] info) [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [17:27:37.923] info, version) [17:27:37.923] } [17:27:37.923] base::stop(msg) [17:27:37.923] } [17:27:37.923] }) [17:27:37.923] } [17:27:37.923] ...future.strategy.old <- future::plan("list") [17:27:37.923] options(future.plan = NULL) [17:27:37.923] Sys.unsetenv("R_FUTURE_PLAN") [17:27:37.923] future::plan("default", .cleanup = FALSE, .init = FALSE) [17:27:37.923] } [17:27:37.923] ...future.workdir <- getwd() [17:27:37.923] } [17:27:37.923] ...future.oldOptions <- base::as.list(base::.Options) [17:27:37.923] ...future.oldEnvVars <- base::Sys.getenv() [17:27:37.923] } [17:27:37.923] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [17:27:37.923] future.globals.maxSize = NULL, future.globals.method = NULL, [17:27:37.923] future.globals.onMissing = NULL, future.globals.onReference = NULL, [17:27:37.923] future.globals.resolve = NULL, future.resolve.recursive = NULL, [17:27:37.923] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [17:27:37.923] future.stdout.windows.reencode = NULL, width = 80L) [17:27:37.923] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [17:27:37.923] base::names(...future.oldOptions)) [17:27:37.923] } [17:27:37.923] if (FALSE) { [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] if (TRUE) { [17:27:37.923] ...future.stdout <- base::rawConnection(base::raw(0L), [17:27:37.923] open = "w") [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [17:27:37.923] windows = "NUL", "/dev/null"), open = "w") [17:27:37.923] } [17:27:37.923] base::sink(...future.stdout, type = "output", split = FALSE) [17:27:37.923] base::on.exit(if (!base::is.null(...future.stdout)) { [17:27:37.923] base::sink(type = "output", split = FALSE) [17:27:37.923] base::close(...future.stdout) [17:27:37.923] }, add = TRUE) [17:27:37.923] } [17:27:37.923] ...future.frame <- base::sys.nframe() [17:27:37.923] ...future.conditions <- base::list() [17:27:37.923] ...future.rng <- base::globalenv()$.Random.seed [17:27:37.923] if (FALSE) { [17:27:37.923] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [17:27:37.923] "...future.value", "...future.globalenv.names", ".Random.seed") [17:27:37.923] } [17:27:37.923] ...future.result <- base::tryCatch({ [17:27:37.923] base::withCallingHandlers({ [17:27:37.923] ...future.value <- base::withVisible(base::local({ [17:27:37.923] ...future.makeSendCondition <- base::local({ [17:27:37.923] sendCondition <- NULL [17:27:37.923] function(frame = 1L) { [17:27:37.923] if (is.function(sendCondition)) [17:27:37.923] return(sendCondition) [17:27:37.923] ns <- getNamespace("parallel") [17:27:37.923] if (exists("sendData", mode = "function", [17:27:37.923] envir = ns)) { [17:27:37.923] parallel_sendData <- get("sendData", mode = "function", [17:27:37.923] envir = ns) [17:27:37.923] envir <- sys.frame(frame) [17:27:37.923] master <- NULL [17:27:37.923] while (!identical(envir, .GlobalEnv) && [17:27:37.923] !identical(envir, emptyenv())) { [17:27:37.923] if (exists("master", mode = "list", envir = envir, [17:27:37.923] inherits = FALSE)) { [17:27:37.923] master <- get("master", mode = "list", [17:27:37.923] envir = envir, inherits = FALSE) [17:27:37.923] if (inherits(master, c("SOCKnode", [17:27:37.923] "SOCK0node"))) { [17:27:37.923] sendCondition <<- function(cond) { [17:27:37.923] data <- list(type = "VALUE", value = cond, [17:27:37.923] success = TRUE) [17:27:37.923] parallel_sendData(master, data) [17:27:37.923] } [17:27:37.923] return(sendCondition) [17:27:37.923] } [17:27:37.923] } [17:27:37.923] frame <- frame + 1L [17:27:37.923] envir <- sys.frame(frame) [17:27:37.923] } [17:27:37.923] } [17:27:37.923] sendCondition <<- function(cond) NULL [17:27:37.923] } [17:27:37.923] }) [17:27:37.923] withCallingHandlers({ [17:27:37.923] { [17:27:37.923] Sys.sleep(5) [17:27:37.923] 1 [17:27:37.923] } [17:27:37.923] }, immediateCondition = function(cond) { [17:27:37.923] sendCondition <- ...future.makeSendCondition() [17:27:37.923] sendCondition(cond) [17:27:37.923] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.923] { [17:27:37.923] inherits <- base::inherits [17:27:37.923] invokeRestart <- base::invokeRestart [17:27:37.923] is.null <- base::is.null [17:27:37.923] muffled <- FALSE [17:27:37.923] if (inherits(cond, "message")) { [17:27:37.923] muffled <- grepl(pattern, "muffleMessage") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleMessage") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "warning")) { [17:27:37.923] muffled <- grepl(pattern, "muffleWarning") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleWarning") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "condition")) { [17:27:37.923] if (!is.null(pattern)) { [17:27:37.923] computeRestarts <- base::computeRestarts [17:27:37.923] grepl <- base::grepl [17:27:37.923] restarts <- computeRestarts(cond) [17:27:37.923] for (restart in restarts) { [17:27:37.923] name <- restart$name [17:27:37.923] if (is.null(name)) [17:27:37.923] next [17:27:37.923] if (!grepl(pattern, name)) [17:27:37.923] next [17:27:37.923] invokeRestart(restart) [17:27:37.923] muffled <- TRUE [17:27:37.923] break [17:27:37.923] } [17:27:37.923] } [17:27:37.923] } [17:27:37.923] invisible(muffled) [17:27:37.923] } [17:27:37.923] muffleCondition(cond) [17:27:37.923] }) [17:27:37.923] })) [17:27:37.923] future::FutureResult(value = ...future.value$value, [17:27:37.923] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [17:27:37.923] ...future.rng), globalenv = if (FALSE) [17:27:37.923] list(added = base::setdiff(base::names(base::.GlobalEnv), [17:27:37.923] ...future.globalenv.names)) [17:27:37.923] else NULL, started = ...future.startTime, version = "1.8") [17:27:37.923] }, condition = base::local({ [17:27:37.923] c <- base::c [17:27:37.923] inherits <- base::inherits [17:27:37.923] invokeRestart <- base::invokeRestart [17:27:37.923] length <- base::length [17:27:37.923] list <- base::list [17:27:37.923] seq.int <- base::seq.int [17:27:37.923] signalCondition <- base::signalCondition [17:27:37.923] sys.calls <- base::sys.calls [17:27:37.923] `[[` <- base::`[[` [17:27:37.923] `+` <- base::`+` [17:27:37.923] `<<-` <- base::`<<-` [17:27:37.923] sysCalls <- function(calls = sys.calls(), from = 1L) { [17:27:37.923] calls[seq.int(from = from + 12L, to = length(calls) - [17:27:37.923] 3L)] [17:27:37.923] } [17:27:37.923] function(cond) { [17:27:37.923] is_error <- inherits(cond, "error") [17:27:37.923] ignore <- !is_error && !is.null(NULL) && inherits(cond, [17:27:37.923] NULL) [17:27:37.923] if (is_error) { [17:27:37.923] sessionInformation <- function() { [17:27:37.923] list(r = base::R.Version(), locale = base::Sys.getlocale(), [17:27:37.923] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [17:27:37.923] search = base::search(), system = base::Sys.info()) [17:27:37.923] } [17:27:37.923] ...future.conditions[[length(...future.conditions) + [17:27:37.923] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [17:27:37.923] cond$call), session = sessionInformation(), [17:27:37.923] timestamp = base::Sys.time(), signaled = 0L) [17:27:37.923] signalCondition(cond) [17:27:37.923] } [17:27:37.923] else if (!ignore && TRUE && inherits(cond, c("condition", [17:27:37.923] "immediateCondition"))) { [17:27:37.923] signal <- TRUE && inherits(cond, "immediateCondition") [17:27:37.923] ...future.conditions[[length(...future.conditions) + [17:27:37.923] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [17:27:37.923] if (TRUE && !signal) { [17:27:37.923] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.923] { [17:27:37.923] inherits <- base::inherits [17:27:37.923] invokeRestart <- base::invokeRestart [17:27:37.923] is.null <- base::is.null [17:27:37.923] muffled <- FALSE [17:27:37.923] if (inherits(cond, "message")) { [17:27:37.923] muffled <- grepl(pattern, "muffleMessage") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleMessage") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "warning")) { [17:27:37.923] muffled <- grepl(pattern, "muffleWarning") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleWarning") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "condition")) { [17:27:37.923] if (!is.null(pattern)) { [17:27:37.923] computeRestarts <- base::computeRestarts [17:27:37.923] grepl <- base::grepl [17:27:37.923] restarts <- computeRestarts(cond) [17:27:37.923] for (restart in restarts) { [17:27:37.923] name <- restart$name [17:27:37.923] if (is.null(name)) [17:27:37.923] next [17:27:37.923] if (!grepl(pattern, name)) [17:27:37.923] next [17:27:37.923] invokeRestart(restart) [17:27:37.923] muffled <- TRUE [17:27:37.923] break [17:27:37.923] } [17:27:37.923] } [17:27:37.923] } [17:27:37.923] invisible(muffled) [17:27:37.923] } [17:27:37.923] muffleCondition(cond, pattern = "^muffle") [17:27:37.923] } [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] if (TRUE) { [17:27:37.923] muffleCondition <- function (cond, pattern = "^muffle") [17:27:37.923] { [17:27:37.923] inherits <- base::inherits [17:27:37.923] invokeRestart <- base::invokeRestart [17:27:37.923] is.null <- base::is.null [17:27:37.923] muffled <- FALSE [17:27:37.923] if (inherits(cond, "message")) { [17:27:37.923] muffled <- grepl(pattern, "muffleMessage") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleMessage") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "warning")) { [17:27:37.923] muffled <- grepl(pattern, "muffleWarning") [17:27:37.923] if (muffled) [17:27:37.923] invokeRestart("muffleWarning") [17:27:37.923] } [17:27:37.923] else if (inherits(cond, "condition")) { [17:27:37.923] if (!is.null(pattern)) { [17:27:37.923] computeRestarts <- base::computeRestarts [17:27:37.923] grepl <- base::grepl [17:27:37.923] restarts <- computeRestarts(cond) [17:27:37.923] for (restart in restarts) { [17:27:37.923] name <- restart$name [17:27:37.923] if (is.null(name)) [17:27:37.923] next [17:27:37.923] if (!grepl(pattern, name)) [17:27:37.923] next [17:27:37.923] invokeRestart(restart) [17:27:37.923] muffled <- TRUE [17:27:37.923] break [17:27:37.923] } [17:27:37.923] } [17:27:37.923] } [17:27:37.923] invisible(muffled) [17:27:37.923] } [17:27:37.923] muffleCondition(cond, pattern = "^muffle") [17:27:37.923] } [17:27:37.923] } [17:27:37.923] } [17:27:37.923] })) [17:27:37.923] }, error = function(ex) { [17:27:37.923] base::structure(base::list(value = NULL, visible = NULL, [17:27:37.923] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [17:27:37.923] ...future.rng), started = ...future.startTime, [17:27:37.923] finished = Sys.time(), session_uuid = NA_character_, [17:27:37.923] version = "1.8"), class = "FutureResult") [17:27:37.923] }, finally = { [17:27:37.923] if (!identical(...future.workdir, getwd())) [17:27:37.923] setwd(...future.workdir) [17:27:37.923] { [17:27:37.923] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [17:27:37.923] ...future.oldOptions$nwarnings <- NULL [17:27:37.923] } [17:27:37.923] base::options(...future.oldOptions) [17:27:37.923] if (.Platform$OS.type == "windows") { [17:27:37.923] old_names <- names(...future.oldEnvVars) [17:27:37.923] envs <- base::Sys.getenv() [17:27:37.923] names <- names(envs) [17:27:37.923] common <- intersect(names, old_names) [17:27:37.923] added <- setdiff(names, old_names) [17:27:37.923] removed <- setdiff(old_names, names) [17:27:37.923] changed <- common[...future.oldEnvVars[common] != [17:27:37.923] envs[common]] [17:27:37.923] NAMES <- toupper(changed) [17:27:37.923] args <- list() [17:27:37.923] for (kk in seq_along(NAMES)) { [17:27:37.923] name <- changed[[kk]] [17:27:37.923] NAME <- NAMES[[kk]] [17:27:37.923] if (name != NAME && is.element(NAME, old_names)) [17:27:37.923] next [17:27:37.923] args[[name]] <- ...future.oldEnvVars[[name]] [17:27:37.923] } [17:27:37.923] NAMES <- toupper(added) [17:27:37.923] for (kk in seq_along(NAMES)) { [17:27:37.923] name <- added[[kk]] [17:27:37.923] NAME <- NAMES[[kk]] [17:27:37.923] if (name != NAME && is.element(NAME, old_names)) [17:27:37.923] next [17:27:37.923] args[[name]] <- "" [17:27:37.923] } [17:27:37.923] NAMES <- toupper(removed) [17:27:37.923] for (kk in seq_along(NAMES)) { [17:27:37.923] name <- removed[[kk]] [17:27:37.923] NAME <- NAMES[[kk]] [17:27:37.923] if (name != NAME && is.element(NAME, old_names)) [17:27:37.923] next [17:27:37.923] args[[name]] <- ...future.oldEnvVars[[name]] [17:27:37.923] } [17:27:37.923] if (length(args) > 0) [17:27:37.923] base::do.call(base::Sys.setenv, args = args) [17:27:37.923] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [17:27:37.923] } [17:27:37.923] { [17:27:37.923] if (base::length(...future.futureOptionsAdded) > [17:27:37.923] 0L) { [17:27:37.923] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [17:27:37.923] base::names(opts) <- ...future.futureOptionsAdded [17:27:37.923] base::options(opts) [17:27:37.923] } [17:27:37.923] { [17:27:37.923] NULL [17:27:37.923] options(future.plan = NULL) [17:27:37.923] if (is.na(NA_character_)) [17:27:37.923] Sys.unsetenv("R_FUTURE_PLAN") [17:27:37.923] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [17:27:37.923] future::plan(...future.strategy.old, .cleanup = FALSE, [17:27:37.923] .init = FALSE) [17:27:37.923] } [17:27:37.923] } [17:27:37.923] } [17:27:37.923] }) [17:27:37.923] if (TRUE) { [17:27:37.923] base::sink(type = "output", split = FALSE) [17:27:37.923] if (TRUE) { [17:27:37.923] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [17:27:37.923] } [17:27:37.923] else { [17:27:37.923] ...future.result["stdout"] <- base::list(NULL) [17:27:37.923] } [17:27:37.923] base::close(...future.stdout) [17:27:37.923] ...future.stdout <- NULL [17:27:37.923] } [17:27:37.923] ...future.result$conditions <- ...future.conditions [17:27:37.923] ...future.result$finished <- base::Sys.time() [17:27:37.923] ...future.result [17:27:37.923] } [17:27:37.928] ClusterFuture started [17:27:37.929] - Launch lazy future ... done [17:27:37.929] run() for 'ClusterFuture' ... done > > res <- tryCatch({ + requestNode(function() { }, workers = f$workers, timeout = 0.5, delta = 0.1) + }, error = identity) [17:27:37.929] Poll #1 (0): usedNodes() = 1, workers = 1 [17:27:38.033] Poll #2 (0.1 secs): usedNodes() = 1, workers = 1 [17:27:38.143] Poll #3 (0.21 secs): usedNodes() = 1, workers = 1 [17:27:38.252] Poll #4 (0.32 secs): usedNodes() = 1, workers = 1 [17:27:38.361] Poll #5 (0.43 secs): usedNodes() = 1, workers = 1 [17:27:38.471] TIMEOUT: All 1 cluster nodes are still occupied after 0.54 secs (polled 6 times) > stopifnot(inherits(res, "error")) > > v <- value(f) [17:27:38.471] result() for ClusterFuture ... [17:27:38.471] receiveMessageFromWorker() for ClusterFuture ... [17:27:38.471] - Validating connection of ClusterFuture [17:27:43.055] - received message: FutureResult [17:27:43.055] - Received FutureResult [17:27:43.055] - Erased future from FutureRegistry [17:27:43.055] result() for ClusterFuture ... [17:27:43.055] - result already collected: FutureResult [17:27:43.056] result() for ClusterFuture ... done [17:27:43.056] receiveMessageFromWorker() for ClusterFuture ... done [17:27:43.056] result() for ClusterFuture ... done [17:27:43.056] result() for ClusterFuture ... [17:27:43.056] - result already collected: FutureResult [17:27:43.056] 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") [17:27:43.057] plan(): Setting new future strategy stack: [17:27:43.058] List of future strategies: [17:27:43.058] 1. FutureStrategy: [17:27:43.058] - args: function (..., envir = parent.frame(), workers = "") [17:27:43.058] - tweaked: FALSE [17:27:43.058] - call: future::plan(oplan) [17:27:43.059] 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', 'RTOOLS44_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_RTOOLS44_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_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_DEPRECATED_IS_R_', '_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.39 0.10 6.50