R Under development (unstable) (2024-07-28 r86931 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:28:54.683] plan(): Setting new future strategy stack: [17:28:54.686] List of future strategies: [17:28:54.686] 1. sequential: [17:28:54.686] - args: function (..., envir = parent.frame(), workers = "") [17:28:54.686] - tweaked: FALSE [17:28:54.686] - call: future::plan("sequential") [17:28:54.714] plan(): nbrOfWorkers() = 1 > > message("*** requestNode() ...") *** requestNode() ... > > message("*** requestNode() - exceptions ...") *** requestNode() - exceptions ... > > workers <- makeClusterPSOCK(2L) [17:28:54.726] [local output] makeClusterPSOCK() ... [17:28:54.776] [local output] Workers: [n = 2] 'localhost', 'localhost' [17:28:54.785] [local output] Base port: 20273 [17:28:54.785] [local output] Getting setup options for 2 cluster nodes ... [17:28:54.786] [local output] - Node #1 of 2 ... [17:28:54.786] [local output] localMachine=TRUE => revtunnel=FALSE [17:28:54.788] Testing if worker's PID can be inferred: '"D:/RCompile/recent/R/bin/x64/Rscript" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f10795a33c3.pid\")), silent = TRUE)" -e "file.exists(\"D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f10795a33c3.pid\")"' [17:28:55.237] - Possible to infer worker's PID: TRUE [17:28:55.238] [local output] Rscript port: 20273 [17:28:55.239] [local output] - Node #2 of 2 ... [17:28:55.240] [local output] localMachine=TRUE => revtunnel=FALSE [17:28:55.241] [local output] Rscript port: 20273 [17:28:55.242] [local output] Getting setup options for 2 cluster nodes ... done [17:28:55.242] [local output] - Parallel setup requested for some PSOCK nodes [17:28:55.243] [local output] Setting up PSOCK nodes in parallel [17:28:55.244] List of 36 [17:28:55.244] $ worker : chr "localhost" [17:28:55.244] ..- attr(*, "localhost")= logi TRUE [17:28:55.244] $ master : chr "localhost" [17:28:55.244] $ port : int 20273 [17:28:55.244] $ connectTimeout : num 120 [17:28:55.244] $ timeout : num 120 [17:28:55.244] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [17:28:55.244] $ homogeneous : logi TRUE [17:28:55.244] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:28432:CRANWIN3:CRA"| __truncated__ [17:28:55.244] $ rscript_envs : NULL [17:28:55.244] $ rscript_libs : NULL [17:28:55.244] $ rscript_startup : NULL [17:28:55.244] $ rscript_sh : chr [1:2] "cmd" "cmd" [17:28:55.244] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:28:55.244] $ methods : logi TRUE [17:28:55.244] $ socketOptions : chr "no-delay" [17:28:55.244] $ useXDR : logi FALSE [17:28:55.244] $ outfile : chr "/dev/null" [17:28:55.244] $ renice : int NA [17:28:55.244] $ rshcmd : NULL [17:28:55.244] $ user : chr(0) [17:28:55.244] $ revtunnel : logi FALSE [17:28:55.244] $ rshlogfile : NULL [17:28:55.244] $ rshopts : chr(0) [17:28:55.244] $ rank : int 1 [17:28:55.244] $ manual : logi FALSE [17:28:55.244] $ dryrun : logi FALSE [17:28:55.244] $ quiet : logi FALSE [17:28:55.244] $ setup_strategy : chr "parallel" [17:28:55.244] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:28:55.244] $ pidfile : chr "D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f10795a33c3.pid" [17:28:55.244] $ rshcmd_label : NULL [17:28:55.244] $ rsh_call : NULL [17:28:55.244] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:28:55.244] $ localMachine : logi TRUE [17:28:55.244] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [17:28:55.244] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [17:28:55.244] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [17:28:55.244] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [17:28:55.244] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [17:28:55.244] "cmd", "sh", "none"), default_packages = c("datasets", "utils", [17:28:55.244] "grDevices", "graphics", "stats", if (methods) "methods"), methods = TRUE, [17:28:55.244] socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [17:28:55.244] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [17:28:55.244] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [17:28:55.244] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [17:28:55.244] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [17:28:55.244] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [17:28:55.244] "parallel"), action = c("launch", "options"), verbose = FALSE) [17:28:55.244] $ arguments :List of 28 [17:28:55.244] ..$ worker : chr "localhost" [17:28:55.244] ..$ master : NULL [17:28:55.244] ..$ port : int 20273 [17:28:55.244] ..$ connectTimeout : num 120 [17:28:55.244] ..$ timeout : num 120 [17:28:55.244] ..$ rscript : NULL [17:28:55.244] ..$ homogeneous : NULL [17:28:55.244] ..$ rscript_args : NULL [17:28:55.244] ..$ rscript_envs : NULL [17:28:55.244] ..$ rscript_libs : NULL [17:28:55.244] ..$ rscript_startup : NULL [17:28:55.244] ..$ rscript_sh : chr "auto" [17:28:55.244] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:28:55.244] ..$ methods : logi TRUE [17:28:55.244] ..$ socketOptions : chr "no-delay" [17:28:55.244] ..$ useXDR : logi FALSE [17:28:55.244] ..$ outfile : chr "/dev/null" [17:28:55.244] ..$ renice : int NA [17:28:55.244] ..$ rshcmd : NULL [17:28:55.244] ..$ user : NULL [17:28:55.244] ..$ revtunnel : logi NA [17:28:55.244] ..$ rshlogfile : NULL [17:28:55.244] ..$ rshopts : NULL [17:28:55.244] ..$ rank : int 1 [17:28:55.244] ..$ manual : logi FALSE [17:28:55.244] ..$ dryrun : logi FALSE [17:28:55.244] ..$ quiet : logi FALSE [17:28:55.244] ..$ setup_strategy : chr "parallel" [17:28:55.244] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [17:28:55.279] [local output] System call to launch all workers: [17:28:55.279] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:28432:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f10795a33c3.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=20273 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [17:28:55.280] [local output] Starting PSOCK main server [17:28:55.288] [local output] Workers launched [17:28:55.289] [local output] Waiting for workers to connect back [17:28:55.289] - [local output] 0 workers out of 2 ready [17:28:55.565] - [local output] 0 workers out of 2 ready [17:28:55.565] - [local output] 1 workers out of 2 ready [17:28:55.566] - [local output] 1 workers out of 2 ready [17:28:55.567] - [local output] 2 workers out of 2 ready [17:28:55.567] [local output] Launching of 2 workers completed [17:28:55.567] [local output] Number of nodes in cluster: 2 [17:28:55.568] [local output] Collecting session information from 2 workers [17:28:55.569] [local output] - Worker #1 of 2 [17:28:55.570] [local output] - Worker #2 of 2 [17:28:55.570] [local output] makeClusterPSOCK() ... done > print(workers) Socket cluster with 2 nodes where 2 nodes are on host 'localhost' (R Under development (unstable) (2024-07-28 r86931 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:28:55.585] plan(): Setting new future strategy stack: [17:28:55.586] List of future strategies: [17:28:55.586] 1. cluster: [17:28:55.586] - args: function (..., workers = "localhost", envir = parent.frame()) [17:28:55.586] - tweaked: TRUE [17:28:55.586] - call: plan(cluster, workers = "localhost") [17:28:55.605] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [17:28:55.605] cluster: [17:28:55.605] - args: function (..., workers = "localhost", envir = parent.frame()) [17:28:55.605] - tweaked: TRUE [17:28:55.605] - call: plan(cluster, workers = "localhost") [17:28:55.607] getGlobalsAndPackages() ... [17:28:55.608] Not searching for globals [17:28:55.608] - globals: [0] [17:28:55.608] getGlobalsAndPackages() ... DONE [17:28:55.610] [local output] makeClusterPSOCK() ... [17:28:55.610] [local output] Workers: [n = 1] 'localhost' [17:28:55.613] [local output] Base port: 28283 [17:28:55.613] [local output] Getting setup options for 1 cluster nodes ... [17:28:55.614] [local output] - Node #1 of 1 ... [17:28:55.614] [local output] localMachine=TRUE => revtunnel=FALSE [17:28:55.616] [local output] Rscript port: 28283 [17:28:55.617] [local output] Getting setup options for 1 cluster nodes ... done [17:28:55.617] [local output] - Parallel setup requested for some PSOCK nodes [17:28:55.617] [local output] Setting up PSOCK nodes in parallel [17:28:55.618] List of 36 [17:28:55.618] $ worker : chr "localhost" [17:28:55.618] ..- attr(*, "localhost")= logi TRUE [17:28:55.618] $ master : chr "localhost" [17:28:55.618] $ port : int 28283 [17:28:55.618] $ connectTimeout : num 120 [17:28:55.618] $ timeout : num 120 [17:28:55.618] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [17:28:55.618] $ homogeneous : logi TRUE [17:28:55.618] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:28432:CRANWIN3:CRA"| __truncated__ [17:28:55.618] $ rscript_envs : NULL [17:28:55.618] $ rscript_libs : NULL [17:28:55.618] $ rscript_startup : NULL [17:28:55.618] $ rscript_sh : chr [1:2] "cmd" "cmd" [17:28:55.618] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:28:55.618] $ methods : logi TRUE [17:28:55.618] $ socketOptions : chr "no-delay" [17:28:55.618] $ useXDR : logi FALSE [17:28:55.618] $ outfile : chr "/dev/null" [17:28:55.618] $ renice : int NA [17:28:55.618] $ rshcmd : NULL [17:28:55.618] $ user : chr(0) [17:28:55.618] $ revtunnel : logi FALSE [17:28:55.618] $ rshlogfile : NULL [17:28:55.618] $ rshopts : chr(0) [17:28:55.618] $ rank : int 1 [17:28:55.618] $ manual : logi FALSE [17:28:55.618] $ dryrun : logi FALSE [17:28:55.618] $ quiet : logi FALSE [17:28:55.618] $ setup_strategy : chr "parallel" [17:28:55.618] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:28:55.618] $ pidfile : chr "D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f105308585a.pid" [17:28:55.618] $ rshcmd_label : NULL [17:28:55.618] $ rsh_call : NULL [17:28:55.618] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [17:28:55.618] $ localMachine : logi TRUE [17:28:55.618] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [17:28:55.618] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [17:28:55.618] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [17:28:55.618] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [17:28:55.618] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [17:28:55.618] "cmd", "sh", "none"), default_packages = c("datasets", "utils", [17:28:55.618] "grDevices", "graphics", "stats", if (methods) "methods"), methods = TRUE, [17:28:55.618] socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [17:28:55.618] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [17:28:55.618] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [17:28:55.618] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [17:28:55.618] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [17:28:55.618] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [17:28:55.618] "parallel"), action = c("launch", "options"), verbose = FALSE) [17:28:55.618] $ arguments :List of 28 [17:28:55.618] ..$ worker : chr "localhost" [17:28:55.618] ..$ master : NULL [17:28:55.618] ..$ port : int 28283 [17:28:55.618] ..$ connectTimeout : num 120 [17:28:55.618] ..$ timeout : num 120 [17:28:55.618] ..$ rscript : NULL [17:28:55.618] ..$ homogeneous : NULL [17:28:55.618] ..$ rscript_args : NULL [17:28:55.618] ..$ rscript_envs : NULL [17:28:55.618] ..$ rscript_libs : NULL [17:28:55.618] ..$ rscript_startup : NULL [17:28:55.618] ..$ rscript_sh : chr "auto" [17:28:55.618] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [17:28:55.618] ..$ methods : logi TRUE [17:28:55.618] ..$ socketOptions : chr "no-delay" [17:28:55.618] ..$ useXDR : logi FALSE [17:28:55.618] ..$ outfile : chr "/dev/null" [17:28:55.618] ..$ renice : int NA [17:28:55.618] ..$ rshcmd : NULL [17:28:55.618] ..$ user : NULL [17:28:55.618] ..$ revtunnel : logi NA [17:28:55.618] ..$ rshlogfile : NULL [17:28:55.618] ..$ rshopts : NULL [17:28:55.618] ..$ rank : int 1 [17:28:55.618] ..$ manual : logi FALSE [17:28:55.618] ..$ dryrun : logi FALSE [17:28:55.618] ..$ quiet : logi FALSE [17:28:55.618] ..$ setup_strategy : chr "parallel" [17:28:55.618] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [17:28:55.645] [local output] System call to launch all workers: [17:28:55.646] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:28432:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpYFxKY4/worker.rank=1.parallelly.parent=28432.6f105308585a.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=28283 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [17:28:55.646] [local output] Starting PSOCK main server [17:28:55.652] [local output] Workers launched [17:28:55.653] [local output] Waiting for workers to connect back [17:28:55.653] - [local output] 0 workers out of 1 ready [17:28:55.913] - [local output] 0 workers out of 1 ready [17:28:55.914] - [local output] 1 workers out of 1 ready [17:28:55.915] [local output] Launching of 1 workers completed [17:28:55.915] [local output] Number of nodes in cluster: 1 [17:28:55.915] [local output] Collecting session information from 1 workers [17:28:55.916] [local output] - Worker #1 of 1 [17:28:55.917] [local output] makeClusterPSOCK() ... done [17:28:55.928] Packages needed by the future expression (n = 0): [17:28:55.928] Packages needed by future strategies (n = 0): [17:28:55.930] { [17:28:55.930] { [17:28:55.930] { [17:28:55.930] ...future.startTime <- base::Sys.time() [17:28:55.930] { [17:28:55.930] { [17:28:55.930] { [17:28:55.930] base::local({ [17:28:55.930] has_future <- base::requireNamespace("future", [17:28:55.930] quietly = TRUE) [17:28:55.930] if (has_future) { [17:28:55.930] ns <- base::getNamespace("future") [17:28:55.930] version <- ns[[".package"]][["version"]] [17:28:55.930] if (is.null(version)) [17:28:55.930] version <- utils::packageVersion("future") [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] version <- NULL [17:28:55.930] } [17:28:55.930] if (!has_future || version < "1.8.0") { [17:28:55.930] info <- base::c(r_version = base::gsub("R version ", [17:28:55.930] "", base::R.version$version.string), [17:28:55.930] platform = base::sprintf("%s (%s-bit)", [17:28:55.930] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [17:28:55.930] os = base::paste(base::Sys.info()[base::c("sysname", [17:28:55.930] "release", "version")], collapse = " "), [17:28:55.930] hostname = base::Sys.info()[["nodename"]]) [17:28:55.930] info <- base::sprintf("%s: %s", base::names(info), [17:28:55.930] info) [17:28:55.930] info <- base::paste(info, collapse = "; ") [17:28:55.930] if (!has_future) { [17:28:55.930] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [17:28:55.930] info) [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [17:28:55.930] info, version) [17:28:55.930] } [17:28:55.930] base::stop(msg) [17:28:55.930] } [17:28:55.930] }) [17:28:55.930] } [17:28:55.930] ...future.strategy.old <- future::plan("list") [17:28:55.930] options(future.plan = NULL) [17:28:55.930] Sys.unsetenv("R_FUTURE_PLAN") [17:28:55.930] future::plan("default", .cleanup = FALSE, .init = FALSE) [17:28:55.930] } [17:28:55.930] ...future.workdir <- getwd() [17:28:55.930] } [17:28:55.930] ...future.oldOptions <- base::as.list(base::.Options) [17:28:55.930] ...future.oldEnvVars <- base::Sys.getenv() [17:28:55.930] } [17:28:55.930] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [17:28:55.930] future.globals.maxSize = NULL, future.globals.method = NULL, [17:28:55.930] future.globals.onMissing = NULL, future.globals.onReference = NULL, [17:28:55.930] future.globals.resolve = NULL, future.resolve.recursive = NULL, [17:28:55.930] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [17:28:55.930] future.stdout.windows.reencode = NULL, width = 80L) [17:28:55.930] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [17:28:55.930] base::names(...future.oldOptions)) [17:28:55.930] } [17:28:55.930] if (FALSE) { [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] if (TRUE) { [17:28:55.930] ...future.stdout <- base::rawConnection(base::raw(0L), [17:28:55.930] open = "w") [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [17:28:55.930] windows = "NUL", "/dev/null"), open = "w") [17:28:55.930] } [17:28:55.930] base::sink(...future.stdout, type = "output", split = FALSE) [17:28:55.930] base::on.exit(if (!base::is.null(...future.stdout)) { [17:28:55.930] base::sink(type = "output", split = FALSE) [17:28:55.930] base::close(...future.stdout) [17:28:55.930] }, add = TRUE) [17:28:55.930] } [17:28:55.930] ...future.frame <- base::sys.nframe() [17:28:55.930] ...future.conditions <- base::list() [17:28:55.930] ...future.rng <- base::globalenv()$.Random.seed [17:28:55.930] if (FALSE) { [17:28:55.930] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [17:28:55.930] "...future.value", "...future.globalenv.names", ".Random.seed") [17:28:55.930] } [17:28:55.930] ...future.result <- base::tryCatch({ [17:28:55.930] base::withCallingHandlers({ [17:28:55.930] ...future.value <- base::withVisible(base::local({ [17:28:55.930] ...future.makeSendCondition <- base::local({ [17:28:55.930] sendCondition <- NULL [17:28:55.930] function(frame = 1L) { [17:28:55.930] if (is.function(sendCondition)) [17:28:55.930] return(sendCondition) [17:28:55.930] ns <- getNamespace("parallel") [17:28:55.930] if (exists("sendData", mode = "function", [17:28:55.930] envir = ns)) { [17:28:55.930] parallel_sendData <- get("sendData", mode = "function", [17:28:55.930] envir = ns) [17:28:55.930] envir <- sys.frame(frame) [17:28:55.930] master <- NULL [17:28:55.930] while (!identical(envir, .GlobalEnv) && [17:28:55.930] !identical(envir, emptyenv())) { [17:28:55.930] if (exists("master", mode = "list", envir = envir, [17:28:55.930] inherits = FALSE)) { [17:28:55.930] master <- get("master", mode = "list", [17:28:55.930] envir = envir, inherits = FALSE) [17:28:55.930] if (inherits(master, c("SOCKnode", [17:28:55.930] "SOCK0node"))) { [17:28:55.930] sendCondition <<- function(cond) { [17:28:55.930] data <- list(type = "VALUE", value = cond, [17:28:55.930] success = TRUE) [17:28:55.930] parallel_sendData(master, data) [17:28:55.930] } [17:28:55.930] return(sendCondition) [17:28:55.930] } [17:28:55.930] } [17:28:55.930] frame <- frame + 1L [17:28:55.930] envir <- sys.frame(frame) [17:28:55.930] } [17:28:55.930] } [17:28:55.930] sendCondition <<- function(cond) NULL [17:28:55.930] } [17:28:55.930] }) [17:28:55.930] withCallingHandlers({ [17:28:55.930] NA [17:28:55.930] }, immediateCondition = function(cond) { [17:28:55.930] sendCondition <- ...future.makeSendCondition() [17:28:55.930] sendCondition(cond) [17:28:55.930] muffleCondition <- function (cond, pattern = "^muffle") [17:28:55.930] { [17:28:55.930] inherits <- base::inherits [17:28:55.930] invokeRestart <- base::invokeRestart [17:28:55.930] is.null <- base::is.null [17:28:55.930] muffled <- FALSE [17:28:55.930] if (inherits(cond, "message")) { [17:28:55.930] muffled <- grepl(pattern, "muffleMessage") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleMessage") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "warning")) { [17:28:55.930] muffled <- grepl(pattern, "muffleWarning") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleWarning") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "condition")) { [17:28:55.930] if (!is.null(pattern)) { [17:28:55.930] computeRestarts <- base::computeRestarts [17:28:55.930] grepl <- base::grepl [17:28:55.930] restarts <- computeRestarts(cond) [17:28:55.930] for (restart in restarts) { [17:28:55.930] name <- restart$name [17:28:55.930] if (is.null(name)) [17:28:55.930] next [17:28:55.930] if (!grepl(pattern, name)) [17:28:55.930] next [17:28:55.930] invokeRestart(restart) [17:28:55.930] muffled <- TRUE [17:28:55.930] break [17:28:55.930] } [17:28:55.930] } [17:28:55.930] } [17:28:55.930] invisible(muffled) [17:28:55.930] } [17:28:55.930] muffleCondition(cond) [17:28:55.930] }) [17:28:55.930] })) [17:28:55.930] future::FutureResult(value = ...future.value$value, [17:28:55.930] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [17:28:55.930] ...future.rng), globalenv = if (FALSE) [17:28:55.930] list(added = base::setdiff(base::names(base::.GlobalEnv), [17:28:55.930] ...future.globalenv.names)) [17:28:55.930] else NULL, started = ...future.startTime, version = "1.8") [17:28:55.930] }, condition = base::local({ [17:28:55.930] c <- base::c [17:28:55.930] inherits <- base::inherits [17:28:55.930] invokeRestart <- base::invokeRestart [17:28:55.930] length <- base::length [17:28:55.930] list <- base::list [17:28:55.930] seq.int <- base::seq.int [17:28:55.930] signalCondition <- base::signalCondition [17:28:55.930] sys.calls <- base::sys.calls [17:28:55.930] `[[` <- base::`[[` [17:28:55.930] `+` <- base::`+` [17:28:55.930] `<<-` <- base::`<<-` [17:28:55.930] sysCalls <- function(calls = sys.calls(), from = 1L) { [17:28:55.930] calls[seq.int(from = from + 12L, to = length(calls) - [17:28:55.930] 3L)] [17:28:55.930] } [17:28:55.930] function(cond) { [17:28:55.930] is_error <- inherits(cond, "error") [17:28:55.930] ignore <- !is_error && !is.null(NULL) && inherits(cond, [17:28:55.930] NULL) [17:28:55.930] if (is_error) { [17:28:55.930] sessionInformation <- function() { [17:28:55.930] list(r = base::R.Version(), locale = base::Sys.getlocale(), [17:28:55.930] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [17:28:55.930] search = base::search(), system = base::Sys.info()) [17:28:55.930] } [17:28:55.930] ...future.conditions[[length(...future.conditions) + [17:28:55.930] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [17:28:55.930] cond$call), session = sessionInformation(), [17:28:55.930] timestamp = base::Sys.time(), signaled = 0L) [17:28:55.930] signalCondition(cond) [17:28:55.930] } [17:28:55.930] else if (!ignore && TRUE && inherits(cond, c("condition", [17:28:55.930] "immediateCondition"))) { [17:28:55.930] signal <- TRUE && inherits(cond, "immediateCondition") [17:28:55.930] ...future.conditions[[length(...future.conditions) + [17:28:55.930] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [17:28:55.930] if (TRUE && !signal) { [17:28:55.930] muffleCondition <- function (cond, pattern = "^muffle") [17:28:55.930] { [17:28:55.930] inherits <- base::inherits [17:28:55.930] invokeRestart <- base::invokeRestart [17:28:55.930] is.null <- base::is.null [17:28:55.930] muffled <- FALSE [17:28:55.930] if (inherits(cond, "message")) { [17:28:55.930] muffled <- grepl(pattern, "muffleMessage") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleMessage") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "warning")) { [17:28:55.930] muffled <- grepl(pattern, "muffleWarning") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleWarning") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "condition")) { [17:28:55.930] if (!is.null(pattern)) { [17:28:55.930] computeRestarts <- base::computeRestarts [17:28:55.930] grepl <- base::grepl [17:28:55.930] restarts <- computeRestarts(cond) [17:28:55.930] for (restart in restarts) { [17:28:55.930] name <- restart$name [17:28:55.930] if (is.null(name)) [17:28:55.930] next [17:28:55.930] if (!grepl(pattern, name)) [17:28:55.930] next [17:28:55.930] invokeRestart(restart) [17:28:55.930] muffled <- TRUE [17:28:55.930] break [17:28:55.930] } [17:28:55.930] } [17:28:55.930] } [17:28:55.930] invisible(muffled) [17:28:55.930] } [17:28:55.930] muffleCondition(cond, pattern = "^muffle") [17:28:55.930] } [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] if (TRUE) { [17:28:55.930] muffleCondition <- function (cond, pattern = "^muffle") [17:28:55.930] { [17:28:55.930] inherits <- base::inherits [17:28:55.930] invokeRestart <- base::invokeRestart [17:28:55.930] is.null <- base::is.null [17:28:55.930] muffled <- FALSE [17:28:55.930] if (inherits(cond, "message")) { [17:28:55.930] muffled <- grepl(pattern, "muffleMessage") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleMessage") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "warning")) { [17:28:55.930] muffled <- grepl(pattern, "muffleWarning") [17:28:55.930] if (muffled) [17:28:55.930] invokeRestart("muffleWarning") [17:28:55.930] } [17:28:55.930] else if (inherits(cond, "condition")) { [17:28:55.930] if (!is.null(pattern)) { [17:28:55.930] computeRestarts <- base::computeRestarts [17:28:55.930] grepl <- base::grepl [17:28:55.930] restarts <- computeRestarts(cond) [17:28:55.930] for (restart in restarts) { [17:28:55.930] name <- restart$name [17:28:55.930] if (is.null(name)) [17:28:55.930] next [17:28:55.930] if (!grepl(pattern, name)) [17:28:55.930] next [17:28:55.930] invokeRestart(restart) [17:28:55.930] muffled <- TRUE [17:28:55.930] break [17:28:55.930] } [17:28:55.930] } [17:28:55.930] } [17:28:55.930] invisible(muffled) [17:28:55.930] } [17:28:55.930] muffleCondition(cond, pattern = "^muffle") [17:28:55.930] } [17:28:55.930] } [17:28:55.930] } [17:28:55.930] })) [17:28:55.930] }, error = function(ex) { [17:28:55.930] base::structure(base::list(value = NULL, visible = NULL, [17:28:55.930] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [17:28:55.930] ...future.rng), started = ...future.startTime, [17:28:55.930] finished = Sys.time(), session_uuid = NA_character_, [17:28:55.930] version = "1.8"), class = "FutureResult") [17:28:55.930] }, finally = { [17:28:55.930] if (!identical(...future.workdir, getwd())) [17:28:55.930] setwd(...future.workdir) [17:28:55.930] { [17:28:55.930] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [17:28:55.930] ...future.oldOptions$nwarnings <- NULL [17:28:55.930] } [17:28:55.930] base::options(...future.oldOptions) [17:28:55.930] if (.Platform$OS.type == "windows") { [17:28:55.930] old_names <- names(...future.oldEnvVars) [17:28:55.930] envs <- base::Sys.getenv() [17:28:55.930] names <- names(envs) [17:28:55.930] common <- intersect(names, old_names) [17:28:55.930] added <- setdiff(names, old_names) [17:28:55.930] removed <- setdiff(old_names, names) [17:28:55.930] changed <- common[...future.oldEnvVars[common] != [17:28:55.930] envs[common]] [17:28:55.930] NAMES <- toupper(changed) [17:28:55.930] args <- list() [17:28:55.930] for (kk in seq_along(NAMES)) { [17:28:55.930] name <- changed[[kk]] [17:28:55.930] NAME <- NAMES[[kk]] [17:28:55.930] if (name != NAME && is.element(NAME, old_names)) [17:28:55.930] next [17:28:55.930] args[[name]] <- ...future.oldEnvVars[[name]] [17:28:55.930] } [17:28:55.930] NAMES <- toupper(added) [17:28:55.930] for (kk in seq_along(NAMES)) { [17:28:55.930] name <- added[[kk]] [17:28:55.930] NAME <- NAMES[[kk]] [17:28:55.930] if (name != NAME && is.element(NAME, old_names)) [17:28:55.930] next [17:28:55.930] args[[name]] <- "" [17:28:55.930] } [17:28:55.930] NAMES <- toupper(removed) [17:28:55.930] for (kk in seq_along(NAMES)) { [17:28:55.930] name <- removed[[kk]] [17:28:55.930] NAME <- NAMES[[kk]] [17:28:55.930] if (name != NAME && is.element(NAME, old_names)) [17:28:55.930] next [17:28:55.930] args[[name]] <- ...future.oldEnvVars[[name]] [17:28:55.930] } [17:28:55.930] if (length(args) > 0) [17:28:55.930] base::do.call(base::Sys.setenv, args = args) [17:28:55.930] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [17:28:55.930] } [17:28:55.930] { [17:28:55.930] if (base::length(...future.futureOptionsAdded) > [17:28:55.930] 0L) { [17:28:55.930] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [17:28:55.930] base::names(opts) <- ...future.futureOptionsAdded [17:28:55.930] base::options(opts) [17:28:55.930] } [17:28:55.930] { [17:28:55.930] NULL [17:28:55.930] options(future.plan = NULL) [17:28:55.930] if (is.na(NA_character_)) [17:28:55.930] Sys.unsetenv("R_FUTURE_PLAN") [17:28:55.930] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [17:28:55.930] future::plan(...future.strategy.old, .cleanup = FALSE, [17:28:55.930] .init = FALSE) [17:28:55.930] } [17:28:55.930] } [17:28:55.930] } [17:28:55.930] }) [17:28:55.930] if (TRUE) { [17:28:55.930] base::sink(type = "output", split = FALSE) [17:28:55.930] if (TRUE) { [17:28:55.930] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [17:28:55.930] } [17:28:55.930] else { [17:28:55.930] ...future.result["stdout"] <- base::list(NULL) [17:28:55.930] } [17:28:55.930] base::close(...future.stdout) [17:28:55.930] ...future.stdout <- NULL [17:28:55.930] } [17:28:55.930] ...future.result$conditions <- ...future.conditions [17:28:55.930] ...future.result$finished <- base::Sys.time() [17:28:55.930] ...future.result [17:28:55.930] } [17:28:56.080] ClusterFuture started [17:28:56.081] result() for ClusterFuture ... [17:28:56.082] receiveMessageFromWorker() for ClusterFuture ... [17:28:56.082] - Validating connection of ClusterFuture [17:28:56.165] - received message: FutureResult [17:28:56.166] - Received FutureResult [17:28:56.169] - Erased future from FutureRegistry [17:28:56.169] result() for ClusterFuture ... [17:28:56.170] - result already collected: FutureResult [17:28:56.170] result() for ClusterFuture ... done [17:28:56.170] receiveMessageFromWorker() for ClusterFuture ... done [17:28:56.171] result() for ClusterFuture ... done [17:28:56.171] result() for ClusterFuture ... [17:28:56.171] - result already collected: FutureResult [17:28:56.171] result() for ClusterFuture ... done [17:28:56.172] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... DONE [17:28:56.172] plan(): nbrOfWorkers() = 1 > f <- future({ Sys.sleep(5); 1 }) [17:28:56.173] getGlobalsAndPackages() ... [17:28:56.173] Searching for globals... [17:28:56.182] - globals found: [2] '{', 'Sys.sleep' [17:28:56.182] Searching for globals ... DONE [17:28:56.182] Resolving globals: FALSE [17:28:56.183] [17:28:56.183] [17:28:56.184] getGlobalsAndPackages() ... DONE [17:28:56.184] run() for 'Future' ... [17:28:56.184] - state: 'created' [17:28:56.185] - Future backend: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [17:28:56.192] - Future class: 'ClusterFuture', 'MultiprocessFuture', 'Future', 'environment' [17:28:56.192] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... [17:28:56.192] - Field: 'node' [17:28:56.193] - Field: 'label' [17:28:56.193] - Field: 'local' [17:28:56.193] - Field: 'owner' [17:28:56.193] - Field: 'envir' [17:28:56.193] - Field: 'workers' [17:28:56.194] - Field: 'packages' [17:28:56.194] - Field: 'gc' [17:28:56.194] - Field: 'conditions' [17:28:56.194] - Field: 'persistent' [17:28:56.194] - Field: 'expr' [17:28:56.194] - Field: 'uuid' [17:28:56.195] - Field: 'seed' [17:28:56.195] - Field: 'version' [17:28:56.195] - Field: 'result' [17:28:56.195] - Field: 'asynchronous' [17:28:56.195] - Field: 'calls' [17:28:56.196] - Field: 'globals' [17:28:56.196] - Field: 'stdout' [17:28:56.196] - Field: 'earlySignal' [17:28:56.196] - Field: 'lazy' [17:28:56.196] - Field: 'state' [17:28:56.196] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... done [17:28:56.197] - Launch lazy future ... [17:28:56.197] Packages needed by the future expression (n = 0): [17:28:56.197] Packages needed by future strategies (n = 0): [17:28:56.198] { [17:28:56.198] { [17:28:56.198] { [17:28:56.198] ...future.startTime <- base::Sys.time() [17:28:56.198] { [17:28:56.198] { [17:28:56.198] { [17:28:56.198] base::local({ [17:28:56.198] has_future <- base::requireNamespace("future", [17:28:56.198] quietly = TRUE) [17:28:56.198] if (has_future) { [17:28:56.198] ns <- base::getNamespace("future") [17:28:56.198] version <- ns[[".package"]][["version"]] [17:28:56.198] if (is.null(version)) [17:28:56.198] version <- utils::packageVersion("future") [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] version <- NULL [17:28:56.198] } [17:28:56.198] if (!has_future || version < "1.8.0") { [17:28:56.198] info <- base::c(r_version = base::gsub("R version ", [17:28:56.198] "", base::R.version$version.string), [17:28:56.198] platform = base::sprintf("%s (%s-bit)", [17:28:56.198] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [17:28:56.198] os = base::paste(base::Sys.info()[base::c("sysname", [17:28:56.198] "release", "version")], collapse = " "), [17:28:56.198] hostname = base::Sys.info()[["nodename"]]) [17:28:56.198] info <- base::sprintf("%s: %s", base::names(info), [17:28:56.198] info) [17:28:56.198] info <- base::paste(info, collapse = "; ") [17:28:56.198] if (!has_future) { [17:28:56.198] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [17:28:56.198] info) [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [17:28:56.198] info, version) [17:28:56.198] } [17:28:56.198] base::stop(msg) [17:28:56.198] } [17:28:56.198] }) [17:28:56.198] } [17:28:56.198] ...future.strategy.old <- future::plan("list") [17:28:56.198] options(future.plan = NULL) [17:28:56.198] Sys.unsetenv("R_FUTURE_PLAN") [17:28:56.198] future::plan("default", .cleanup = FALSE, .init = FALSE) [17:28:56.198] } [17:28:56.198] ...future.workdir <- getwd() [17:28:56.198] } [17:28:56.198] ...future.oldOptions <- base::as.list(base::.Options) [17:28:56.198] ...future.oldEnvVars <- base::Sys.getenv() [17:28:56.198] } [17:28:56.198] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [17:28:56.198] future.globals.maxSize = NULL, future.globals.method = NULL, [17:28:56.198] future.globals.onMissing = NULL, future.globals.onReference = NULL, [17:28:56.198] future.globals.resolve = NULL, future.resolve.recursive = NULL, [17:28:56.198] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [17:28:56.198] future.stdout.windows.reencode = NULL, width = 80L) [17:28:56.198] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [17:28:56.198] base::names(...future.oldOptions)) [17:28:56.198] } [17:28:56.198] if (FALSE) { [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] if (TRUE) { [17:28:56.198] ...future.stdout <- base::rawConnection(base::raw(0L), [17:28:56.198] open = "w") [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [17:28:56.198] windows = "NUL", "/dev/null"), open = "w") [17:28:56.198] } [17:28:56.198] base::sink(...future.stdout, type = "output", split = FALSE) [17:28:56.198] base::on.exit(if (!base::is.null(...future.stdout)) { [17:28:56.198] base::sink(type = "output", split = FALSE) [17:28:56.198] base::close(...future.stdout) [17:28:56.198] }, add = TRUE) [17:28:56.198] } [17:28:56.198] ...future.frame <- base::sys.nframe() [17:28:56.198] ...future.conditions <- base::list() [17:28:56.198] ...future.rng <- base::globalenv()$.Random.seed [17:28:56.198] if (FALSE) { [17:28:56.198] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [17:28:56.198] "...future.value", "...future.globalenv.names", ".Random.seed") [17:28:56.198] } [17:28:56.198] ...future.result <- base::tryCatch({ [17:28:56.198] base::withCallingHandlers({ [17:28:56.198] ...future.value <- base::withVisible(base::local({ [17:28:56.198] ...future.makeSendCondition <- base::local({ [17:28:56.198] sendCondition <- NULL [17:28:56.198] function(frame = 1L) { [17:28:56.198] if (is.function(sendCondition)) [17:28:56.198] return(sendCondition) [17:28:56.198] ns <- getNamespace("parallel") [17:28:56.198] if (exists("sendData", mode = "function", [17:28:56.198] envir = ns)) { [17:28:56.198] parallel_sendData <- get("sendData", mode = "function", [17:28:56.198] envir = ns) [17:28:56.198] envir <- sys.frame(frame) [17:28:56.198] master <- NULL [17:28:56.198] while (!identical(envir, .GlobalEnv) && [17:28:56.198] !identical(envir, emptyenv())) { [17:28:56.198] if (exists("master", mode = "list", envir = envir, [17:28:56.198] inherits = FALSE)) { [17:28:56.198] master <- get("master", mode = "list", [17:28:56.198] envir = envir, inherits = FALSE) [17:28:56.198] if (inherits(master, c("SOCKnode", [17:28:56.198] "SOCK0node"))) { [17:28:56.198] sendCondition <<- function(cond) { [17:28:56.198] data <- list(type = "VALUE", value = cond, [17:28:56.198] success = TRUE) [17:28:56.198] parallel_sendData(master, data) [17:28:56.198] } [17:28:56.198] return(sendCondition) [17:28:56.198] } [17:28:56.198] } [17:28:56.198] frame <- frame + 1L [17:28:56.198] envir <- sys.frame(frame) [17:28:56.198] } [17:28:56.198] } [17:28:56.198] sendCondition <<- function(cond) NULL [17:28:56.198] } [17:28:56.198] }) [17:28:56.198] withCallingHandlers({ [17:28:56.198] { [17:28:56.198] Sys.sleep(5) [17:28:56.198] 1 [17:28:56.198] } [17:28:56.198] }, immediateCondition = function(cond) { [17:28:56.198] sendCondition <- ...future.makeSendCondition() [17:28:56.198] sendCondition(cond) [17:28:56.198] muffleCondition <- function (cond, pattern = "^muffle") [17:28:56.198] { [17:28:56.198] inherits <- base::inherits [17:28:56.198] invokeRestart <- base::invokeRestart [17:28:56.198] is.null <- base::is.null [17:28:56.198] muffled <- FALSE [17:28:56.198] if (inherits(cond, "message")) { [17:28:56.198] muffled <- grepl(pattern, "muffleMessage") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleMessage") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "warning")) { [17:28:56.198] muffled <- grepl(pattern, "muffleWarning") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleWarning") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "condition")) { [17:28:56.198] if (!is.null(pattern)) { [17:28:56.198] computeRestarts <- base::computeRestarts [17:28:56.198] grepl <- base::grepl [17:28:56.198] restarts <- computeRestarts(cond) [17:28:56.198] for (restart in restarts) { [17:28:56.198] name <- restart$name [17:28:56.198] if (is.null(name)) [17:28:56.198] next [17:28:56.198] if (!grepl(pattern, name)) [17:28:56.198] next [17:28:56.198] invokeRestart(restart) [17:28:56.198] muffled <- TRUE [17:28:56.198] break [17:28:56.198] } [17:28:56.198] } [17:28:56.198] } [17:28:56.198] invisible(muffled) [17:28:56.198] } [17:28:56.198] muffleCondition(cond) [17:28:56.198] }) [17:28:56.198] })) [17:28:56.198] future::FutureResult(value = ...future.value$value, [17:28:56.198] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [17:28:56.198] ...future.rng), globalenv = if (FALSE) [17:28:56.198] list(added = base::setdiff(base::names(base::.GlobalEnv), [17:28:56.198] ...future.globalenv.names)) [17:28:56.198] else NULL, started = ...future.startTime, version = "1.8") [17:28:56.198] }, condition = base::local({ [17:28:56.198] c <- base::c [17:28:56.198] inherits <- base::inherits [17:28:56.198] invokeRestart <- base::invokeRestart [17:28:56.198] length <- base::length [17:28:56.198] list <- base::list [17:28:56.198] seq.int <- base::seq.int [17:28:56.198] signalCondition <- base::signalCondition [17:28:56.198] sys.calls <- base::sys.calls [17:28:56.198] `[[` <- base::`[[` [17:28:56.198] `+` <- base::`+` [17:28:56.198] `<<-` <- base::`<<-` [17:28:56.198] sysCalls <- function(calls = sys.calls(), from = 1L) { [17:28:56.198] calls[seq.int(from = from + 12L, to = length(calls) - [17:28:56.198] 3L)] [17:28:56.198] } [17:28:56.198] function(cond) { [17:28:56.198] is_error <- inherits(cond, "error") [17:28:56.198] ignore <- !is_error && !is.null(NULL) && inherits(cond, [17:28:56.198] NULL) [17:28:56.198] if (is_error) { [17:28:56.198] sessionInformation <- function() { [17:28:56.198] list(r = base::R.Version(), locale = base::Sys.getlocale(), [17:28:56.198] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [17:28:56.198] search = base::search(), system = base::Sys.info()) [17:28:56.198] } [17:28:56.198] ...future.conditions[[length(...future.conditions) + [17:28:56.198] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [17:28:56.198] cond$call), session = sessionInformation(), [17:28:56.198] timestamp = base::Sys.time(), signaled = 0L) [17:28:56.198] signalCondition(cond) [17:28:56.198] } [17:28:56.198] else if (!ignore && TRUE && inherits(cond, c("condition", [17:28:56.198] "immediateCondition"))) { [17:28:56.198] signal <- TRUE && inherits(cond, "immediateCondition") [17:28:56.198] ...future.conditions[[length(...future.conditions) + [17:28:56.198] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [17:28:56.198] if (TRUE && !signal) { [17:28:56.198] muffleCondition <- function (cond, pattern = "^muffle") [17:28:56.198] { [17:28:56.198] inherits <- base::inherits [17:28:56.198] invokeRestart <- base::invokeRestart [17:28:56.198] is.null <- base::is.null [17:28:56.198] muffled <- FALSE [17:28:56.198] if (inherits(cond, "message")) { [17:28:56.198] muffled <- grepl(pattern, "muffleMessage") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleMessage") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "warning")) { [17:28:56.198] muffled <- grepl(pattern, "muffleWarning") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleWarning") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "condition")) { [17:28:56.198] if (!is.null(pattern)) { [17:28:56.198] computeRestarts <- base::computeRestarts [17:28:56.198] grepl <- base::grepl [17:28:56.198] restarts <- computeRestarts(cond) [17:28:56.198] for (restart in restarts) { [17:28:56.198] name <- restart$name [17:28:56.198] if (is.null(name)) [17:28:56.198] next [17:28:56.198] if (!grepl(pattern, name)) [17:28:56.198] next [17:28:56.198] invokeRestart(restart) [17:28:56.198] muffled <- TRUE [17:28:56.198] break [17:28:56.198] } [17:28:56.198] } [17:28:56.198] } [17:28:56.198] invisible(muffled) [17:28:56.198] } [17:28:56.198] muffleCondition(cond, pattern = "^muffle") [17:28:56.198] } [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] if (TRUE) { [17:28:56.198] muffleCondition <- function (cond, pattern = "^muffle") [17:28:56.198] { [17:28:56.198] inherits <- base::inherits [17:28:56.198] invokeRestart <- base::invokeRestart [17:28:56.198] is.null <- base::is.null [17:28:56.198] muffled <- FALSE [17:28:56.198] if (inherits(cond, "message")) { [17:28:56.198] muffled <- grepl(pattern, "muffleMessage") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleMessage") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "warning")) { [17:28:56.198] muffled <- grepl(pattern, "muffleWarning") [17:28:56.198] if (muffled) [17:28:56.198] invokeRestart("muffleWarning") [17:28:56.198] } [17:28:56.198] else if (inherits(cond, "condition")) { [17:28:56.198] if (!is.null(pattern)) { [17:28:56.198] computeRestarts <- base::computeRestarts [17:28:56.198] grepl <- base::grepl [17:28:56.198] restarts <- computeRestarts(cond) [17:28:56.198] for (restart in restarts) { [17:28:56.198] name <- restart$name [17:28:56.198] if (is.null(name)) [17:28:56.198] next [17:28:56.198] if (!grepl(pattern, name)) [17:28:56.198] next [17:28:56.198] invokeRestart(restart) [17:28:56.198] muffled <- TRUE [17:28:56.198] break [17:28:56.198] } [17:28:56.198] } [17:28:56.198] } [17:28:56.198] invisible(muffled) [17:28:56.198] } [17:28:56.198] muffleCondition(cond, pattern = "^muffle") [17:28:56.198] } [17:28:56.198] } [17:28:56.198] } [17:28:56.198] })) [17:28:56.198] }, error = function(ex) { [17:28:56.198] base::structure(base::list(value = NULL, visible = NULL, [17:28:56.198] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [17:28:56.198] ...future.rng), started = ...future.startTime, [17:28:56.198] finished = Sys.time(), session_uuid = NA_character_, [17:28:56.198] version = "1.8"), class = "FutureResult") [17:28:56.198] }, finally = { [17:28:56.198] if (!identical(...future.workdir, getwd())) [17:28:56.198] setwd(...future.workdir) [17:28:56.198] { [17:28:56.198] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [17:28:56.198] ...future.oldOptions$nwarnings <- NULL [17:28:56.198] } [17:28:56.198] base::options(...future.oldOptions) [17:28:56.198] if (.Platform$OS.type == "windows") { [17:28:56.198] old_names <- names(...future.oldEnvVars) [17:28:56.198] envs <- base::Sys.getenv() [17:28:56.198] names <- names(envs) [17:28:56.198] common <- intersect(names, old_names) [17:28:56.198] added <- setdiff(names, old_names) [17:28:56.198] removed <- setdiff(old_names, names) [17:28:56.198] changed <- common[...future.oldEnvVars[common] != [17:28:56.198] envs[common]] [17:28:56.198] NAMES <- toupper(changed) [17:28:56.198] args <- list() [17:28:56.198] for (kk in seq_along(NAMES)) { [17:28:56.198] name <- changed[[kk]] [17:28:56.198] NAME <- NAMES[[kk]] [17:28:56.198] if (name != NAME && is.element(NAME, old_names)) [17:28:56.198] next [17:28:56.198] args[[name]] <- ...future.oldEnvVars[[name]] [17:28:56.198] } [17:28:56.198] NAMES <- toupper(added) [17:28:56.198] for (kk in seq_along(NAMES)) { [17:28:56.198] name <- added[[kk]] [17:28:56.198] NAME <- NAMES[[kk]] [17:28:56.198] if (name != NAME && is.element(NAME, old_names)) [17:28:56.198] next [17:28:56.198] args[[name]] <- "" [17:28:56.198] } [17:28:56.198] NAMES <- toupper(removed) [17:28:56.198] for (kk in seq_along(NAMES)) { [17:28:56.198] name <- removed[[kk]] [17:28:56.198] NAME <- NAMES[[kk]] [17:28:56.198] if (name != NAME && is.element(NAME, old_names)) [17:28:56.198] next [17:28:56.198] args[[name]] <- ...future.oldEnvVars[[name]] [17:28:56.198] } [17:28:56.198] if (length(args) > 0) [17:28:56.198] base::do.call(base::Sys.setenv, args = args) [17:28:56.198] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [17:28:56.198] } [17:28:56.198] { [17:28:56.198] if (base::length(...future.futureOptionsAdded) > [17:28:56.198] 0L) { [17:28:56.198] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [17:28:56.198] base::names(opts) <- ...future.futureOptionsAdded [17:28:56.198] base::options(opts) [17:28:56.198] } [17:28:56.198] { [17:28:56.198] NULL [17:28:56.198] options(future.plan = NULL) [17:28:56.198] if (is.na(NA_character_)) [17:28:56.198] Sys.unsetenv("R_FUTURE_PLAN") [17:28:56.198] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [17:28:56.198] future::plan(...future.strategy.old, .cleanup = FALSE, [17:28:56.198] .init = FALSE) [17:28:56.198] } [17:28:56.198] } [17:28:56.198] } [17:28:56.198] }) [17:28:56.198] if (TRUE) { [17:28:56.198] base::sink(type = "output", split = FALSE) [17:28:56.198] if (TRUE) { [17:28:56.198] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [17:28:56.198] } [17:28:56.198] else { [17:28:56.198] ...future.result["stdout"] <- base::list(NULL) [17:28:56.198] } [17:28:56.198] base::close(...future.stdout) [17:28:56.198] ...future.stdout <- NULL [17:28:56.198] } [17:28:56.198] ...future.result$conditions <- ...future.conditions [17:28:56.198] ...future.result$finished <- base::Sys.time() [17:28:56.198] ...future.result [17:28:56.198] } [17:28:56.207] ClusterFuture started [17:28:56.207] - Launch lazy future ... done [17:28:56.208] run() for 'ClusterFuture' ... done > > res <- tryCatch({ + requestNode(function() { }, workers = f$workers, timeout = 0.5, delta = 0.1) + }, error = identity) [17:28:56.208] Poll #1 (0): usedNodes() = 1, workers = 1 [17:28:56.315] Poll #2 (0.11 secs): usedNodes() = 1, workers = 1 [17:28:56.426] Poll #3 (0.22 secs): usedNodes() = 1, workers = 1 [17:28:56.534] Poll #4 (0.33 secs): usedNodes() = 1, workers = 1 [17:28:56.643] Poll #5 (0.43 secs): usedNodes() = 1, workers = 1 [17:28:56.753] TIMEOUT: All 1 cluster nodes are still occupied after 0.54 secs (polled 6 times) > stopifnot(inherits(res, "error")) > > v <- value(f) [17:28:56.754] result() for ClusterFuture ... [17:28:56.754] receiveMessageFromWorker() for ClusterFuture ... [17:28:56.754] - Validating connection of ClusterFuture [17:29:01.378] - received message: FutureResult [17:29:01.379] - Received FutureResult [17:29:01.379] - Erased future from FutureRegistry [17:29:01.380] result() for ClusterFuture ... [17:29:01.380] - result already collected: FutureResult [17:29:01.380] result() for ClusterFuture ... done [17:29:01.381] receiveMessageFromWorker() for ClusterFuture ... done [17:29:01.381] result() for ClusterFuture ... done [17:29:01.381] result() for ClusterFuture ... [17:29:01.382] - result already collected: FutureResult [17:29:01.382] 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:29:01.384] plan(): Setting new future strategy stack: [17:29:01.385] List of future strategies: [17:29:01.385] 1. FutureStrategy: [17:29:01.385] - args: function (..., envir = parent.frame(), workers = "") [17:29:01.385] - tweaked: FALSE [17:29:01.385] - call: future::plan(oplan) [17:29:01.393] plan(): nbrOfWorkers() = 1 Failed to undo environment variables: - Expected environment variables: [n=205] '!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_NOTE_MISSING_PACKAGE_ANCHORS_', '_R_CHECK_XREFS_USE_ALIASES_FROM_CRAN_', '_R_CLASS_MATRIX_ARRAY_', '_R_DEPRECATED_IS_R_', '_R_S3_METHOD_LOOKUP_BASEENV_AFTER_GLOBALENV_', '_R_SHLIB_BUILD_OBJECTS_SYMBOL_TABLES_', '__R_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.54 0.20 7.15