R Under development (unstable) (2023-06-30 r84625 ucrt) -- "Unsuffered Consequences" Copyright (C) 2023 The R Foundation for Statistical Computing Platform: x86_64-w64-mingw32/x64 R is free software and comes with ABSOLUTELY NO WARRANTY. You are welcome to redistribute it under certain conditions. Type 'license()' or 'licence()' for distribution details. R is a collaborative project with many contributors. Type 'contributors()' for more information and 'citation()' on how to cite R or R packages in publications. Type 'demo()' for some demos, 'help()' for on-line help, or 'help.start()' for an HTML browser interface to help. Type 'q()' to quit R. > source("incl/start.R") [18:02:34.783] plan(): Setting new future strategy stack: [18:02:34.784] List of future strategies: [18:02:34.784] 1. sequential: [18:02:34.784] - args: function (..., envir = parent.frame()) [18:02:34.784] - tweaked: FALSE [18:02:34.784] - call: future::plan("sequential") [18:02:34.800] plan(): nbrOfWorkers() = 1 > > message("*** requestNode() ...") *** requestNode() ... > > message("*** requestNode() - exceptions ...") *** requestNode() - exceptions ... > > workers <- makeClusterPSOCK(2L) [18:02:34.808] [local output] makeClusterPSOCK() ... [18:02:34.835] [local output] Workers: [n = 2] 'localhost', 'localhost' [18:02:34.842] [local output] Base port: 28560 [18:02:34.842] [local output] Getting setup options for 2 cluster nodes ... [18:02:34.842] [local output] - Node 1 of 2 ... [18:02:34.843] [local output] localMachine=TRUE => revtunnel=FALSE [18:02:34.844] Testing if worker's PID can be inferred: '"D:/RCompile/recent/R/bin/x64/Rscript" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50554e23ff.pid\")), silent = TRUE)" -e "file.exists(\"D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50554e23ff.pid\")"' [18:02:35.287] - Possible to infer worker's PID: TRUE [18:02:35.287] [local output] Rscript port: 28560 [18:02:35.288] [local output] - Node 2 of 2 ... [18:02:35.288] [local output] localMachine=TRUE => revtunnel=FALSE [18:02:35.289] [local output] Rscript port: 28560 [18:02:35.290] [local output] Getting setup options for 2 cluster nodes ... done [18:02:35.290] [local output] - Parallel setup requested for some PSOCK nodes [18:02:35.291] [local output] Setting up PSOCK nodes in parallel [18:02:35.291] List of 36 [18:02:35.291] $ worker : chr "localhost" [18:02:35.291] ..- attr(*, "localhost")= logi TRUE [18:02:35.291] $ master : chr "localhost" [18:02:35.291] $ port : int 28560 [18:02:35.291] $ connectTimeout : num 120 [18:02:35.291] $ timeout : num 120 [18:02:35.291] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [18:02:35.291] $ homogeneous : logi TRUE [18:02:35.291] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:72528:CRANWIN3:CRA"| __truncated__ [18:02:35.291] $ rscript_envs : NULL [18:02:35.291] $ rscript_libs : NULL [18:02:35.291] $ rscript_startup : NULL [18:02:35.291] $ rscript_sh : chr "cmd" [18:02:35.291] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [18:02:35.291] $ methods : logi TRUE [18:02:35.291] $ socketOptions : chr "no-delay" [18:02:35.291] $ useXDR : logi FALSE [18:02:35.291] $ outfile : chr "/dev/null" [18:02:35.291] $ renice : int NA [18:02:35.291] $ rshcmd : NULL [18:02:35.291] $ user : chr(0) [18:02:35.291] $ revtunnel : logi FALSE [18:02:35.291] $ rshlogfile : NULL [18:02:35.291] $ rshopts : chr(0) [18:02:35.291] $ rank : int 1 [18:02:35.291] $ manual : logi FALSE [18:02:35.291] $ dryrun : logi FALSE [18:02:35.291] $ quiet : logi FALSE [18:02:35.291] $ setup_strategy : chr "parallel" [18:02:35.291] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [18:02:35.291] $ pidfile : chr "D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50554e23ff.pid" [18:02:35.291] $ rshcmd_label : NULL [18:02:35.291] $ rsh_call : NULL [18:02:35.291] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [18:02:35.291] $ localMachine : logi TRUE [18:02:35.291] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [18:02:35.291] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [18:02:35.291] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [18:02:35.291] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [18:02:35.291] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [18:02:35.291] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [18:02:35.291] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [18:02:35.291] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [18:02:35.291] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [18:02:35.291] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [18:02:35.291] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [18:02:35.291] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [18:02:35.291] "parallel"), action = c("launch", "options"), verbose = FALSE) [18:02:35.291] $ arguments :List of 28 [18:02:35.291] ..$ worker : chr "localhost" [18:02:35.291] ..$ master : NULL [18:02:35.291] ..$ port : int 28560 [18:02:35.291] ..$ connectTimeout : num 120 [18:02:35.291] ..$ timeout : num 120 [18:02:35.291] ..$ rscript : NULL [18:02:35.291] ..$ homogeneous : NULL [18:02:35.291] ..$ rscript_args : NULL [18:02:35.291] ..$ rscript_envs : NULL [18:02:35.291] ..$ rscript_libs : NULL [18:02:35.291] ..$ rscript_startup : NULL [18:02:35.291] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [18:02:35.291] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [18:02:35.291] ..$ methods : logi TRUE [18:02:35.291] ..$ socketOptions : chr "no-delay" [18:02:35.291] ..$ useXDR : logi FALSE [18:02:35.291] ..$ outfile : chr "/dev/null" [18:02:35.291] ..$ renice : int NA [18:02:35.291] ..$ rshcmd : NULL [18:02:35.291] ..$ user : NULL [18:02:35.291] ..$ revtunnel : logi NA [18:02:35.291] ..$ rshlogfile : NULL [18:02:35.291] ..$ rshopts : NULL [18:02:35.291] ..$ rank : int 1 [18:02:35.291] ..$ manual : logi FALSE [18:02:35.291] ..$ dryrun : logi FALSE [18:02:35.291] ..$ quiet : logi FALSE [18:02:35.291] ..$ setup_strategy : chr "parallel" [18:02:35.291] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [18:02:35.315] [local output] System call to launch all workers: [18:02:35.315] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:72528:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50554e23ff.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=28560 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [18:02:35.315] [local output] Starting PSOCK main server [18:02:35.323] [local output] Workers launched [18:02:35.324] [local output] Waiting for workers to connect back [18:02:35.324] - [local output] 0 workers out of 2 ready [18:02:35.494] - [local output] 0 workers out of 2 ready [18:02:35.495] - [local output] 1 workers out of 2 ready [18:02:35.495] - [local output] 2 workers out of 2 ready [18:02:35.495] [local output] Launching of workers completed [18:02:35.496] [local output] Collecting session information from workers [18:02:35.497] [local output] - Worker #1 of 2 [18:02:35.497] [local output] - Worker #2 of 2 [18:02:35.497] [local output] makeClusterPSOCK() ... done > print(workers) Socket cluster with 2 nodes where 2 nodes are on host 'localhost' (R Under development (unstable) (2023-06-30 r84625 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") [18:02:35.504] plan(): Setting new future strategy stack: [18:02:35.505] List of future strategies: [18:02:35.505] 1. cluster: [18:02:35.505] - args: function (..., workers = "localhost", envir = parent.frame()) [18:02:35.505] - tweaked: TRUE [18:02:35.505] - call: plan(cluster, workers = "localhost") [18:02:35.521] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... [18:02:35.521] cluster: [18:02:35.521] - args: function (..., workers = "localhost", envir = parent.frame()) [18:02:35.521] - tweaked: TRUE [18:02:35.521] - call: plan(cluster, workers = "localhost") [18:02:35.523] getGlobalsAndPackages() ... [18:02:35.523] Not searching for globals [18:02:35.523] - globals: [0] [18:02:35.524] getGlobalsAndPackages() ... DONE [18:02:35.525] [local output] makeClusterPSOCK() ... [18:02:35.525] [local output] Workers: [n = 1] 'localhost' [18:02:35.528] [local output] Base port: 25704 [18:02:35.528] [local output] Getting setup options for 1 cluster nodes ... [18:02:35.528] [local output] - Node 1 of 1 ... [18:02:35.529] [local output] localMachine=TRUE => revtunnel=FALSE [18:02:35.530] [local output] Rscript port: 25704 [18:02:35.531] [local output] Getting setup options for 1 cluster nodes ... done [18:02:35.531] [local output] - Parallel setup requested for some PSOCK nodes [18:02:35.531] [local output] Setting up PSOCK nodes in parallel [18:02:35.532] List of 36 [18:02:35.532] $ worker : chr "localhost" [18:02:35.532] ..- attr(*, "localhost")= logi TRUE [18:02:35.532] $ master : chr "localhost" [18:02:35.532] $ port : int 25704 [18:02:35.532] $ connectTimeout : num 120 [18:02:35.532] $ timeout : num 120 [18:02:35.532] $ rscript : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\"" [18:02:35.532] $ homogeneous : logi TRUE [18:02:35.532] $ rscript_args : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e \"#label=requestNode.R:72528:CRANWIN3:CRA"| __truncated__ [18:02:35.532] $ rscript_envs : NULL [18:02:35.532] $ rscript_libs : NULL [18:02:35.532] $ rscript_startup : NULL [18:02:35.532] $ rscript_sh : chr "cmd" [18:02:35.532] $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [18:02:35.532] $ methods : logi TRUE [18:02:35.532] $ socketOptions : chr "no-delay" [18:02:35.532] $ useXDR : logi FALSE [18:02:35.532] $ outfile : chr "/dev/null" [18:02:35.532] $ renice : int NA [18:02:35.532] $ rshcmd : NULL [18:02:35.532] $ user : chr(0) [18:02:35.532] $ revtunnel : logi FALSE [18:02:35.532] $ rshlogfile : NULL [18:02:35.532] $ rshopts : chr(0) [18:02:35.532] $ rank : int 1 [18:02:35.532] $ manual : logi FALSE [18:02:35.532] $ dryrun : logi FALSE [18:02:35.532] $ quiet : logi FALSE [18:02:35.532] $ setup_strategy : chr "parallel" [18:02:35.532] $ local_cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [18:02:35.532] $ pidfile : chr "D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50104d2275.pid" [18:02:35.532] $ rshcmd_label : NULL [18:02:35.532] $ rsh_call : NULL [18:02:35.532] $ cmd : chr "\"D:/RCompile/recent/R/bin/x64/Rscript\" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "| __truncated__ [18:02:35.532] $ localMachine : logi TRUE [18:02:35.532] $ make_fcn :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), [18:02:35.532] master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", [18:02:35.532] 2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", [18:02:35.532] 30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, [18:02:35.532] rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", [18:02:35.532] "cmd", "sh"), default_packages = c("datasets", "utils", "grDevices", [18:02:35.532] "graphics", "stats", if (methods) "methods"), methods = TRUE, socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", [18:02:35.532] "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", [18:02:35.532] FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", [18:02:35.532] NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", [18:02:35.532] NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, [18:02:35.532] setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", [18:02:35.532] "parallel"), action = c("launch", "options"), verbose = FALSE) [18:02:35.532] $ arguments :List of 28 [18:02:35.532] ..$ worker : chr "localhost" [18:02:35.532] ..$ master : NULL [18:02:35.532] ..$ port : int 25704 [18:02:35.532] ..$ connectTimeout : num 120 [18:02:35.532] ..$ timeout : num 120 [18:02:35.532] ..$ rscript : NULL [18:02:35.532] ..$ homogeneous : NULL [18:02:35.532] ..$ rscript_args : NULL [18:02:35.532] ..$ rscript_envs : NULL [18:02:35.532] ..$ rscript_libs : NULL [18:02:35.532] ..$ rscript_startup : NULL [18:02:35.532] ..$ rscript_sh : chr [1:3] "auto" "cmd" "sh" [18:02:35.532] ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ... [18:02:35.532] ..$ methods : logi TRUE [18:02:35.532] ..$ socketOptions : chr "no-delay" [18:02:35.532] ..$ useXDR : logi FALSE [18:02:35.532] ..$ outfile : chr "/dev/null" [18:02:35.532] ..$ renice : int NA [18:02:35.532] ..$ rshcmd : NULL [18:02:35.532] ..$ user : NULL [18:02:35.532] ..$ revtunnel : logi NA [18:02:35.532] ..$ rshlogfile : NULL [18:02:35.532] ..$ rshopts : NULL [18:02:35.532] ..$ rank : int 1 [18:02:35.532] ..$ manual : logi FALSE [18:02:35.532] ..$ dryrun : logi FALSE [18:02:35.532] ..$ quiet : logi FALSE [18:02:35.532] ..$ setup_strategy : chr "parallel" [18:02:35.532] - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions" [18:02:35.556] [local output] System call to launch all workers: [18:02:35.556] [local output] "D:/RCompile/recent/R/bin/x64/Rscript" --default-packages=datasets,utils,grDevices,graphics,stats,methods -e "#label=requestNode.R:72528:CRANWIN3:CRAN" -e "try(suppressWarnings(cat(Sys.getpid(),file=\"D:/temp/RtmpAdTpCC/worker.rank=1.parallelly.parent=72528.11b50104d2275.pid\")), silent = TRUE)" -e "options(socketOptions = \"no-delay\")" -e "workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()" MASTER=localhost PORT=25704 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel [18:02:35.557] [local output] Starting PSOCK main server [18:02:35.561] [local output] Workers launched [18:02:35.561] [local output] Waiting for workers to connect back [18:02:35.562] - [local output] 0 workers out of 1 ready [18:02:35.727] - [local output] 0 workers out of 1 ready [18:02:35.728] - [local output] 1 workers out of 1 ready [18:02:35.728] [local output] Launching of workers completed [18:02:35.728] [local output] Collecting session information from workers [18:02:35.729] [local output] - Worker #1 of 1 [18:02:35.729] [local output] makeClusterPSOCK() ... done [18:02:35.736] Packages needed by the future expression (n = 0): [18:02:35.737] Packages needed by future strategies (n = 0): [18:02:35.738] { [18:02:35.738] { [18:02:35.738] { [18:02:35.738] ...future.startTime <- base::Sys.time() [18:02:35.738] { [18:02:35.738] { [18:02:35.738] { [18:02:35.738] base::local({ [18:02:35.738] has_future <- base::requireNamespace("future", [18:02:35.738] quietly = TRUE) [18:02:35.738] if (has_future) { [18:02:35.738] ns <- base::getNamespace("future") [18:02:35.738] version <- ns[[".package"]][["version"]] [18:02:35.738] if (is.null(version)) [18:02:35.738] version <- utils::packageVersion("future") [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] version <- NULL [18:02:35.738] } [18:02:35.738] if (!has_future || version < "1.8.0") { [18:02:35.738] info <- base::c(r_version = base::gsub("R version ", [18:02:35.738] "", base::R.version$version.string), [18:02:35.738] platform = base::sprintf("%s (%s-bit)", [18:02:35.738] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [18:02:35.738] os = base::paste(base::Sys.info()[base::c("sysname", [18:02:35.738] "release", "version")], collapse = " "), [18:02:35.738] hostname = base::Sys.info()[["nodename"]]) [18:02:35.738] info <- base::sprintf("%s: %s", base::names(info), [18:02:35.738] info) [18:02:35.738] info <- base::paste(info, collapse = "; ") [18:02:35.738] if (!has_future) { [18:02:35.738] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [18:02:35.738] info) [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [18:02:35.738] info, version) [18:02:35.738] } [18:02:35.738] base::stop(msg) [18:02:35.738] } [18:02:35.738] }) [18:02:35.738] } [18:02:35.738] options(future.plan = NULL) [18:02:35.738] Sys.unsetenv("R_FUTURE_PLAN") [18:02:35.738] future::plan("default", .cleanup = FALSE, .init = FALSE) [18:02:35.738] } [18:02:35.738] ...future.workdir <- getwd() [18:02:35.738] } [18:02:35.738] ...future.oldOptions <- base::as.list(base::.Options) [18:02:35.738] ...future.oldEnvVars <- base::Sys.getenv() [18:02:35.738] } [18:02:35.738] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [18:02:35.738] future.globals.maxSize = NULL, future.globals.method = NULL, [18:02:35.738] future.globals.onMissing = NULL, future.globals.onReference = NULL, [18:02:35.738] future.globals.resolve = NULL, future.resolve.recursive = NULL, [18:02:35.738] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [18:02:35.738] future.stdout.windows.reencode = NULL, width = 80L) [18:02:35.738] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [18:02:35.738] base::names(...future.oldOptions)) [18:02:35.738] } [18:02:35.738] if (FALSE) { [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] if (TRUE) { [18:02:35.738] ...future.stdout <- base::rawConnection(base::raw(0L), [18:02:35.738] open = "w") [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [18:02:35.738] windows = "NUL", "/dev/null"), open = "w") [18:02:35.738] } [18:02:35.738] base::sink(...future.stdout, type = "output", split = FALSE) [18:02:35.738] base::on.exit(if (!base::is.null(...future.stdout)) { [18:02:35.738] base::sink(type = "output", split = FALSE) [18:02:35.738] base::close(...future.stdout) [18:02:35.738] }, add = TRUE) [18:02:35.738] } [18:02:35.738] ...future.frame <- base::sys.nframe() [18:02:35.738] ...future.conditions <- base::list() [18:02:35.738] ...future.rng <- base::globalenv()$.Random.seed [18:02:35.738] if (FALSE) { [18:02:35.738] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [18:02:35.738] "...future.value", "...future.globalenv.names", ".Random.seed") [18:02:35.738] } [18:02:35.738] ...future.result <- base::tryCatch({ [18:02:35.738] base::withCallingHandlers({ [18:02:35.738] ...future.value <- base::withVisible(base::local({ [18:02:35.738] ...future.makeSendCondition <- local({ [18:02:35.738] sendCondition <- NULL [18:02:35.738] function(frame = 1L) { [18:02:35.738] if (is.function(sendCondition)) [18:02:35.738] return(sendCondition) [18:02:35.738] ns <- getNamespace("parallel") [18:02:35.738] if (exists("sendData", mode = "function", [18:02:35.738] envir = ns)) { [18:02:35.738] parallel_sendData <- get("sendData", mode = "function", [18:02:35.738] envir = ns) [18:02:35.738] envir <- sys.frame(frame) [18:02:35.738] master <- NULL [18:02:35.738] while (!identical(envir, .GlobalEnv) && [18:02:35.738] !identical(envir, emptyenv())) { [18:02:35.738] if (exists("master", mode = "list", envir = envir, [18:02:35.738] inherits = FALSE)) { [18:02:35.738] master <- get("master", mode = "list", [18:02:35.738] envir = envir, inherits = FALSE) [18:02:35.738] if (inherits(master, c("SOCKnode", [18:02:35.738] "SOCK0node"))) { [18:02:35.738] sendCondition <<- function(cond) { [18:02:35.738] data <- list(type = "VALUE", value = cond, [18:02:35.738] success = TRUE) [18:02:35.738] parallel_sendData(master, data) [18:02:35.738] } [18:02:35.738] return(sendCondition) [18:02:35.738] } [18:02:35.738] } [18:02:35.738] frame <- frame + 1L [18:02:35.738] envir <- sys.frame(frame) [18:02:35.738] } [18:02:35.738] } [18:02:35.738] sendCondition <<- function(cond) NULL [18:02:35.738] } [18:02:35.738] }) [18:02:35.738] withCallingHandlers({ [18:02:35.738] NA [18:02:35.738] }, immediateCondition = function(cond) { [18:02:35.738] sendCondition <- ...future.makeSendCondition() [18:02:35.738] sendCondition(cond) [18:02:35.738] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.738] { [18:02:35.738] inherits <- base::inherits [18:02:35.738] invokeRestart <- base::invokeRestart [18:02:35.738] is.null <- base::is.null [18:02:35.738] muffled <- FALSE [18:02:35.738] if (inherits(cond, "message")) { [18:02:35.738] muffled <- grepl(pattern, "muffleMessage") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleMessage") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "warning")) { [18:02:35.738] muffled <- grepl(pattern, "muffleWarning") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleWarning") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "condition")) { [18:02:35.738] if (!is.null(pattern)) { [18:02:35.738] computeRestarts <- base::computeRestarts [18:02:35.738] grepl <- base::grepl [18:02:35.738] restarts <- computeRestarts(cond) [18:02:35.738] for (restart in restarts) { [18:02:35.738] name <- restart$name [18:02:35.738] if (is.null(name)) [18:02:35.738] next [18:02:35.738] if (!grepl(pattern, name)) [18:02:35.738] next [18:02:35.738] invokeRestart(restart) [18:02:35.738] muffled <- TRUE [18:02:35.738] break [18:02:35.738] } [18:02:35.738] } [18:02:35.738] } [18:02:35.738] invisible(muffled) [18:02:35.738] } [18:02:35.738] muffleCondition(cond) [18:02:35.738] }) [18:02:35.738] })) [18:02:35.738] future::FutureResult(value = ...future.value$value, [18:02:35.738] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [18:02:35.738] ...future.rng), globalenv = if (FALSE) [18:02:35.738] list(added = base::setdiff(base::names(base::.GlobalEnv), [18:02:35.738] ...future.globalenv.names)) [18:02:35.738] else NULL, started = ...future.startTime, version = "1.8") [18:02:35.738] }, condition = base::local({ [18:02:35.738] c <- base::c [18:02:35.738] inherits <- base::inherits [18:02:35.738] invokeRestart <- base::invokeRestart [18:02:35.738] length <- base::length [18:02:35.738] list <- base::list [18:02:35.738] seq.int <- base::seq.int [18:02:35.738] signalCondition <- base::signalCondition [18:02:35.738] sys.calls <- base::sys.calls [18:02:35.738] `[[` <- base::`[[` [18:02:35.738] `+` <- base::`+` [18:02:35.738] `<<-` <- base::`<<-` [18:02:35.738] sysCalls <- function(calls = sys.calls(), from = 1L) { [18:02:35.738] calls[seq.int(from = from + 12L, to = length(calls) - [18:02:35.738] 3L)] [18:02:35.738] } [18:02:35.738] function(cond) { [18:02:35.738] is_error <- inherits(cond, "error") [18:02:35.738] ignore <- !is_error && !is.null(NULL) && inherits(cond, [18:02:35.738] NULL) [18:02:35.738] if (is_error) { [18:02:35.738] sessionInformation <- function() { [18:02:35.738] list(r = base::R.Version(), locale = base::Sys.getlocale(), [18:02:35.738] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [18:02:35.738] search = base::search(), system = base::Sys.info()) [18:02:35.738] } [18:02:35.738] ...future.conditions[[length(...future.conditions) + [18:02:35.738] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [18:02:35.738] cond$call), session = sessionInformation(), [18:02:35.738] timestamp = base::Sys.time(), signaled = 0L) [18:02:35.738] signalCondition(cond) [18:02:35.738] } [18:02:35.738] else if (!ignore && TRUE && inherits(cond, c("condition", [18:02:35.738] "immediateCondition"))) { [18:02:35.738] signal <- TRUE && inherits(cond, "immediateCondition") [18:02:35.738] ...future.conditions[[length(...future.conditions) + [18:02:35.738] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [18:02:35.738] if (TRUE && !signal) { [18:02:35.738] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.738] { [18:02:35.738] inherits <- base::inherits [18:02:35.738] invokeRestart <- base::invokeRestart [18:02:35.738] is.null <- base::is.null [18:02:35.738] muffled <- FALSE [18:02:35.738] if (inherits(cond, "message")) { [18:02:35.738] muffled <- grepl(pattern, "muffleMessage") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleMessage") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "warning")) { [18:02:35.738] muffled <- grepl(pattern, "muffleWarning") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleWarning") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "condition")) { [18:02:35.738] if (!is.null(pattern)) { [18:02:35.738] computeRestarts <- base::computeRestarts [18:02:35.738] grepl <- base::grepl [18:02:35.738] restarts <- computeRestarts(cond) [18:02:35.738] for (restart in restarts) { [18:02:35.738] name <- restart$name [18:02:35.738] if (is.null(name)) [18:02:35.738] next [18:02:35.738] if (!grepl(pattern, name)) [18:02:35.738] next [18:02:35.738] invokeRestart(restart) [18:02:35.738] muffled <- TRUE [18:02:35.738] break [18:02:35.738] } [18:02:35.738] } [18:02:35.738] } [18:02:35.738] invisible(muffled) [18:02:35.738] } [18:02:35.738] muffleCondition(cond, pattern = "^muffle") [18:02:35.738] } [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] if (TRUE) { [18:02:35.738] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.738] { [18:02:35.738] inherits <- base::inherits [18:02:35.738] invokeRestart <- base::invokeRestart [18:02:35.738] is.null <- base::is.null [18:02:35.738] muffled <- FALSE [18:02:35.738] if (inherits(cond, "message")) { [18:02:35.738] muffled <- grepl(pattern, "muffleMessage") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleMessage") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "warning")) { [18:02:35.738] muffled <- grepl(pattern, "muffleWarning") [18:02:35.738] if (muffled) [18:02:35.738] invokeRestart("muffleWarning") [18:02:35.738] } [18:02:35.738] else if (inherits(cond, "condition")) { [18:02:35.738] if (!is.null(pattern)) { [18:02:35.738] computeRestarts <- base::computeRestarts [18:02:35.738] grepl <- base::grepl [18:02:35.738] restarts <- computeRestarts(cond) [18:02:35.738] for (restart in restarts) { [18:02:35.738] name <- restart$name [18:02:35.738] if (is.null(name)) [18:02:35.738] next [18:02:35.738] if (!grepl(pattern, name)) [18:02:35.738] next [18:02:35.738] invokeRestart(restart) [18:02:35.738] muffled <- TRUE [18:02:35.738] break [18:02:35.738] } [18:02:35.738] } [18:02:35.738] } [18:02:35.738] invisible(muffled) [18:02:35.738] } [18:02:35.738] muffleCondition(cond, pattern = "^muffle") [18:02:35.738] } [18:02:35.738] } [18:02:35.738] } [18:02:35.738] })) [18:02:35.738] }, error = function(ex) { [18:02:35.738] base::structure(base::list(value = NULL, visible = NULL, [18:02:35.738] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [18:02:35.738] ...future.rng), started = ...future.startTime, [18:02:35.738] finished = Sys.time(), session_uuid = NA_character_, [18:02:35.738] version = "1.8"), class = "FutureResult") [18:02:35.738] }, finally = { [18:02:35.738] if (!identical(...future.workdir, getwd())) [18:02:35.738] setwd(...future.workdir) [18:02:35.738] { [18:02:35.738] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [18:02:35.738] ...future.oldOptions$nwarnings <- NULL [18:02:35.738] } [18:02:35.738] base::options(...future.oldOptions) [18:02:35.738] if (.Platform$OS.type == "windows") { [18:02:35.738] old_names <- names(...future.oldEnvVars) [18:02:35.738] envs <- base::Sys.getenv() [18:02:35.738] names <- names(envs) [18:02:35.738] common <- intersect(names, old_names) [18:02:35.738] added <- setdiff(names, old_names) [18:02:35.738] removed <- setdiff(old_names, names) [18:02:35.738] changed <- common[...future.oldEnvVars[common] != [18:02:35.738] envs[common]] [18:02:35.738] NAMES <- toupper(changed) [18:02:35.738] args <- list() [18:02:35.738] for (kk in seq_along(NAMES)) { [18:02:35.738] name <- changed[[kk]] [18:02:35.738] NAME <- NAMES[[kk]] [18:02:35.738] if (name != NAME && is.element(NAME, old_names)) [18:02:35.738] next [18:02:35.738] args[[name]] <- ...future.oldEnvVars[[name]] [18:02:35.738] } [18:02:35.738] NAMES <- toupper(added) [18:02:35.738] for (kk in seq_along(NAMES)) { [18:02:35.738] name <- added[[kk]] [18:02:35.738] NAME <- NAMES[[kk]] [18:02:35.738] if (name != NAME && is.element(NAME, old_names)) [18:02:35.738] next [18:02:35.738] args[[name]] <- "" [18:02:35.738] } [18:02:35.738] NAMES <- toupper(removed) [18:02:35.738] for (kk in seq_along(NAMES)) { [18:02:35.738] name <- removed[[kk]] [18:02:35.738] NAME <- NAMES[[kk]] [18:02:35.738] if (name != NAME && is.element(NAME, old_names)) [18:02:35.738] next [18:02:35.738] args[[name]] <- ...future.oldEnvVars[[name]] [18:02:35.738] } [18:02:35.738] if (length(args) > 0) [18:02:35.738] base::do.call(base::Sys.setenv, args = args) [18:02:35.738] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [18:02:35.738] } [18:02:35.738] { [18:02:35.738] if (base::length(...future.futureOptionsAdded) > [18:02:35.738] 0L) { [18:02:35.738] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [18:02:35.738] base::names(opts) <- ...future.futureOptionsAdded [18:02:35.738] base::options(opts) [18:02:35.738] } [18:02:35.738] { [18:02:35.738] NULL [18:02:35.738] options(future.plan = NULL) [18:02:35.738] if (is.na(NA_character_)) [18:02:35.738] Sys.unsetenv("R_FUTURE_PLAN") [18:02:35.738] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [18:02:35.738] future::plan(list(function (..., workers = "localhost", [18:02:35.738] envir = parent.frame()) [18:02:35.738] strategy(..., workers = workers, envir = envir)), [18:02:35.738] .cleanup = FALSE, .init = FALSE) [18:02:35.738] } [18:02:35.738] } [18:02:35.738] } [18:02:35.738] }) [18:02:35.738] if (TRUE) { [18:02:35.738] base::sink(type = "output", split = FALSE) [18:02:35.738] if (TRUE) { [18:02:35.738] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [18:02:35.738] } [18:02:35.738] else { [18:02:35.738] ...future.result["stdout"] <- base::list(NULL) [18:02:35.738] } [18:02:35.738] base::close(...future.stdout) [18:02:35.738] ...future.stdout <- NULL [18:02:35.738] } [18:02:35.738] ...future.result$conditions <- ...future.conditions [18:02:35.738] ...future.result$finished <- base::Sys.time() [18:02:35.738] ...future.result [18:02:35.738] } [18:02:35.820] ClusterFuture started [18:02:35.821] result() for ClusterFuture ... [18:02:35.821] receiveMessageFromWorker() for ClusterFuture ... [18:02:35.821] - Validating connection of ClusterFuture [18:02:35.874] - received message: FutureResult [18:02:35.874] - Received FutureResult [18:02:35.877] - Erased future from FutureRegistry [18:02:35.878] result() for ClusterFuture ... [18:02:35.878] - result already collected: FutureResult [18:02:35.878] result() for ClusterFuture ... done [18:02:35.878] receiveMessageFromWorker() for ClusterFuture ... done [18:02:35.878] result() for ClusterFuture ... done [18:02:35.878] result() for ClusterFuture ... [18:02:35.878] - result already collected: FutureResult [18:02:35.879] result() for ClusterFuture ... done [18:02:35.879] plan(): plan_init() of 'tweaked', 'cluster', 'multiprocess', 'future', 'function' ... DONE [18:02:35.879] plan(): nbrOfWorkers() = 1 > f <- future({ Sys.sleep(5); 1 }) [18:02:35.879] getGlobalsAndPackages() ... [18:02:35.880] Searching for globals... [18:02:35.887] - globals found: [2] '{', 'Sys.sleep' [18:02:35.888] Searching for globals ... DONE [18:02:35.888] Resolving globals: FALSE [18:02:35.888] [18:02:35.889] [18:02:35.889] getGlobalsAndPackages() ... DONE [18:02:35.889] run() for 'Future' ... [18:02:35.890] - state: 'created' [18:02:35.890] - Future backend: 'FutureStrategy', 'tweaked', 'cluster', 'multiprocess', 'future', 'function' [18:02:35.896] - Future class: 'ClusterFuture', 'MultiprocessFuture', 'Future', 'environment' [18:02:35.896] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... [18:02:35.896] - Field: 'node' [18:02:35.897] - Field: 'label' [18:02:35.897] - Field: 'local' [18:02:35.897] - Field: 'owner' [18:02:35.897] - Field: 'envir' [18:02:35.897] - Field: 'workers' [18:02:35.898] - Field: 'packages' [18:02:35.898] - Field: 'gc' [18:02:35.898] - Field: 'conditions' [18:02:35.898] - Field: 'persistent' [18:02:35.898] - Field: 'expr' [18:02:35.898] - Field: 'uuid' [18:02:35.899] - Field: 'seed' [18:02:35.899] - Field: 'version' [18:02:35.899] - Field: 'result' [18:02:35.899] - Field: 'asynchronous' [18:02:35.899] - Field: 'calls' [18:02:35.900] - Field: 'globals' [18:02:35.900] - Field: 'stdout' [18:02:35.900] - Field: 'earlySignal' [18:02:35.900] - Field: 'lazy' [18:02:35.900] - Field: 'state' [18:02:35.900] - Copy elements of temporary 'ClusterFuture' to final 'Future' object ... done [18:02:35.901] - Launch lazy future ... [18:02:35.901] Packages needed by the future expression (n = 0): [18:02:35.901] Packages needed by future strategies (n = 0): [18:02:35.902] { [18:02:35.902] { [18:02:35.902] { [18:02:35.902] ...future.startTime <- base::Sys.time() [18:02:35.902] { [18:02:35.902] { [18:02:35.902] { [18:02:35.902] base::local({ [18:02:35.902] has_future <- base::requireNamespace("future", [18:02:35.902] quietly = TRUE) [18:02:35.902] if (has_future) { [18:02:35.902] ns <- base::getNamespace("future") [18:02:35.902] version <- ns[[".package"]][["version"]] [18:02:35.902] if (is.null(version)) [18:02:35.902] version <- utils::packageVersion("future") [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] version <- NULL [18:02:35.902] } [18:02:35.902] if (!has_future || version < "1.8.0") { [18:02:35.902] info <- base::c(r_version = base::gsub("R version ", [18:02:35.902] "", base::R.version$version.string), [18:02:35.902] platform = base::sprintf("%s (%s-bit)", [18:02:35.902] base::R.version$platform, 8 * base::.Machine$sizeof.pointer), [18:02:35.902] os = base::paste(base::Sys.info()[base::c("sysname", [18:02:35.902] "release", "version")], collapse = " "), [18:02:35.902] hostname = base::Sys.info()[["nodename"]]) [18:02:35.902] info <- base::sprintf("%s: %s", base::names(info), [18:02:35.902] info) [18:02:35.902] info <- base::paste(info, collapse = "; ") [18:02:35.902] if (!has_future) { [18:02:35.902] msg <- base::sprintf("Package 'future' is not installed on worker (%s)", [18:02:35.902] info) [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] msg <- base::sprintf("Package 'future' on worker (%s) must be of version >= 1.8.0: %s", [18:02:35.902] info, version) [18:02:35.902] } [18:02:35.902] base::stop(msg) [18:02:35.902] } [18:02:35.902] }) [18:02:35.902] } [18:02:35.902] options(future.plan = NULL) [18:02:35.902] Sys.unsetenv("R_FUTURE_PLAN") [18:02:35.902] future::plan("default", .cleanup = FALSE, .init = FALSE) [18:02:35.902] } [18:02:35.902] ...future.workdir <- getwd() [18:02:35.902] } [18:02:35.902] ...future.oldOptions <- base::as.list(base::.Options) [18:02:35.902] ...future.oldEnvVars <- base::Sys.getenv() [18:02:35.902] } [18:02:35.902] base::options(future.startup.script = FALSE, future.globals.onMissing = NULL, [18:02:35.902] future.globals.maxSize = NULL, future.globals.method = NULL, [18:02:35.902] future.globals.onMissing = NULL, future.globals.onReference = NULL, [18:02:35.902] future.globals.resolve = NULL, future.resolve.recursive = NULL, [18:02:35.902] future.rng.onMisuse = NULL, future.rng.onMisuse.keepFuture = NULL, [18:02:35.902] future.stdout.windows.reencode = NULL, width = 80L) [18:02:35.902] ...future.futureOptionsAdded <- base::setdiff(base::names(base::.Options), [18:02:35.902] base::names(...future.oldOptions)) [18:02:35.902] } [18:02:35.902] if (FALSE) { [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] if (TRUE) { [18:02:35.902] ...future.stdout <- base::rawConnection(base::raw(0L), [18:02:35.902] open = "w") [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] ...future.stdout <- base::file(base::switch(.Platform$OS.type, [18:02:35.902] windows = "NUL", "/dev/null"), open = "w") [18:02:35.902] } [18:02:35.902] base::sink(...future.stdout, type = "output", split = FALSE) [18:02:35.902] base::on.exit(if (!base::is.null(...future.stdout)) { [18:02:35.902] base::sink(type = "output", split = FALSE) [18:02:35.902] base::close(...future.stdout) [18:02:35.902] }, add = TRUE) [18:02:35.902] } [18:02:35.902] ...future.frame <- base::sys.nframe() [18:02:35.902] ...future.conditions <- base::list() [18:02:35.902] ...future.rng <- base::globalenv()$.Random.seed [18:02:35.902] if (FALSE) { [18:02:35.902] ...future.globalenv.names <- c(base::names(base::.GlobalEnv), [18:02:35.902] "...future.value", "...future.globalenv.names", ".Random.seed") [18:02:35.902] } [18:02:35.902] ...future.result <- base::tryCatch({ [18:02:35.902] base::withCallingHandlers({ [18:02:35.902] ...future.value <- base::withVisible(base::local({ [18:02:35.902] ...future.makeSendCondition <- local({ [18:02:35.902] sendCondition <- NULL [18:02:35.902] function(frame = 1L) { [18:02:35.902] if (is.function(sendCondition)) [18:02:35.902] return(sendCondition) [18:02:35.902] ns <- getNamespace("parallel") [18:02:35.902] if (exists("sendData", mode = "function", [18:02:35.902] envir = ns)) { [18:02:35.902] parallel_sendData <- get("sendData", mode = "function", [18:02:35.902] envir = ns) [18:02:35.902] envir <- sys.frame(frame) [18:02:35.902] master <- NULL [18:02:35.902] while (!identical(envir, .GlobalEnv) && [18:02:35.902] !identical(envir, emptyenv())) { [18:02:35.902] if (exists("master", mode = "list", envir = envir, [18:02:35.902] inherits = FALSE)) { [18:02:35.902] master <- get("master", mode = "list", [18:02:35.902] envir = envir, inherits = FALSE) [18:02:35.902] if (inherits(master, c("SOCKnode", [18:02:35.902] "SOCK0node"))) { [18:02:35.902] sendCondition <<- function(cond) { [18:02:35.902] data <- list(type = "VALUE", value = cond, [18:02:35.902] success = TRUE) [18:02:35.902] parallel_sendData(master, data) [18:02:35.902] } [18:02:35.902] return(sendCondition) [18:02:35.902] } [18:02:35.902] } [18:02:35.902] frame <- frame + 1L [18:02:35.902] envir <- sys.frame(frame) [18:02:35.902] } [18:02:35.902] } [18:02:35.902] sendCondition <<- function(cond) NULL [18:02:35.902] } [18:02:35.902] }) [18:02:35.902] withCallingHandlers({ [18:02:35.902] { [18:02:35.902] Sys.sleep(5) [18:02:35.902] 1 [18:02:35.902] } [18:02:35.902] }, immediateCondition = function(cond) { [18:02:35.902] sendCondition <- ...future.makeSendCondition() [18:02:35.902] sendCondition(cond) [18:02:35.902] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.902] { [18:02:35.902] inherits <- base::inherits [18:02:35.902] invokeRestart <- base::invokeRestart [18:02:35.902] is.null <- base::is.null [18:02:35.902] muffled <- FALSE [18:02:35.902] if (inherits(cond, "message")) { [18:02:35.902] muffled <- grepl(pattern, "muffleMessage") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleMessage") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "warning")) { [18:02:35.902] muffled <- grepl(pattern, "muffleWarning") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleWarning") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "condition")) { [18:02:35.902] if (!is.null(pattern)) { [18:02:35.902] computeRestarts <- base::computeRestarts [18:02:35.902] grepl <- base::grepl [18:02:35.902] restarts <- computeRestarts(cond) [18:02:35.902] for (restart in restarts) { [18:02:35.902] name <- restart$name [18:02:35.902] if (is.null(name)) [18:02:35.902] next [18:02:35.902] if (!grepl(pattern, name)) [18:02:35.902] next [18:02:35.902] invokeRestart(restart) [18:02:35.902] muffled <- TRUE [18:02:35.902] break [18:02:35.902] } [18:02:35.902] } [18:02:35.902] } [18:02:35.902] invisible(muffled) [18:02:35.902] } [18:02:35.902] muffleCondition(cond) [18:02:35.902] }) [18:02:35.902] })) [18:02:35.902] future::FutureResult(value = ...future.value$value, [18:02:35.902] visible = ...future.value$visible, rng = !identical(base::globalenv()$.Random.seed, [18:02:35.902] ...future.rng), globalenv = if (FALSE) [18:02:35.902] list(added = base::setdiff(base::names(base::.GlobalEnv), [18:02:35.902] ...future.globalenv.names)) [18:02:35.902] else NULL, started = ...future.startTime, version = "1.8") [18:02:35.902] }, condition = base::local({ [18:02:35.902] c <- base::c [18:02:35.902] inherits <- base::inherits [18:02:35.902] invokeRestart <- base::invokeRestart [18:02:35.902] length <- base::length [18:02:35.902] list <- base::list [18:02:35.902] seq.int <- base::seq.int [18:02:35.902] signalCondition <- base::signalCondition [18:02:35.902] sys.calls <- base::sys.calls [18:02:35.902] `[[` <- base::`[[` [18:02:35.902] `+` <- base::`+` [18:02:35.902] `<<-` <- base::`<<-` [18:02:35.902] sysCalls <- function(calls = sys.calls(), from = 1L) { [18:02:35.902] calls[seq.int(from = from + 12L, to = length(calls) - [18:02:35.902] 3L)] [18:02:35.902] } [18:02:35.902] function(cond) { [18:02:35.902] is_error <- inherits(cond, "error") [18:02:35.902] ignore <- !is_error && !is.null(NULL) && inherits(cond, [18:02:35.902] NULL) [18:02:35.902] if (is_error) { [18:02:35.902] sessionInformation <- function() { [18:02:35.902] list(r = base::R.Version(), locale = base::Sys.getlocale(), [18:02:35.902] rngkind = base::RNGkind(), namespaces = base::loadedNamespaces(), [18:02:35.902] search = base::search(), system = base::Sys.info()) [18:02:35.902] } [18:02:35.902] ...future.conditions[[length(...future.conditions) + [18:02:35.902] 1L]] <<- list(condition = cond, calls = c(sysCalls(from = ...future.frame), [18:02:35.902] cond$call), session = sessionInformation(), [18:02:35.902] timestamp = base::Sys.time(), signaled = 0L) [18:02:35.902] signalCondition(cond) [18:02:35.902] } [18:02:35.902] else if (!ignore && TRUE && inherits(cond, c("condition", [18:02:35.902] "immediateCondition"))) { [18:02:35.902] signal <- TRUE && inherits(cond, "immediateCondition") [18:02:35.902] ...future.conditions[[length(...future.conditions) + [18:02:35.902] 1L]] <<- list(condition = cond, signaled = base::as.integer(signal)) [18:02:35.902] if (TRUE && !signal) { [18:02:35.902] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.902] { [18:02:35.902] inherits <- base::inherits [18:02:35.902] invokeRestart <- base::invokeRestart [18:02:35.902] is.null <- base::is.null [18:02:35.902] muffled <- FALSE [18:02:35.902] if (inherits(cond, "message")) { [18:02:35.902] muffled <- grepl(pattern, "muffleMessage") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleMessage") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "warning")) { [18:02:35.902] muffled <- grepl(pattern, "muffleWarning") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleWarning") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "condition")) { [18:02:35.902] if (!is.null(pattern)) { [18:02:35.902] computeRestarts <- base::computeRestarts [18:02:35.902] grepl <- base::grepl [18:02:35.902] restarts <- computeRestarts(cond) [18:02:35.902] for (restart in restarts) { [18:02:35.902] name <- restart$name [18:02:35.902] if (is.null(name)) [18:02:35.902] next [18:02:35.902] if (!grepl(pattern, name)) [18:02:35.902] next [18:02:35.902] invokeRestart(restart) [18:02:35.902] muffled <- TRUE [18:02:35.902] break [18:02:35.902] } [18:02:35.902] } [18:02:35.902] } [18:02:35.902] invisible(muffled) [18:02:35.902] } [18:02:35.902] muffleCondition(cond, pattern = "^muffle") [18:02:35.902] } [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] if (TRUE) { [18:02:35.902] muffleCondition <- function (cond, pattern = "^muffle") [18:02:35.902] { [18:02:35.902] inherits <- base::inherits [18:02:35.902] invokeRestart <- base::invokeRestart [18:02:35.902] is.null <- base::is.null [18:02:35.902] muffled <- FALSE [18:02:35.902] if (inherits(cond, "message")) { [18:02:35.902] muffled <- grepl(pattern, "muffleMessage") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleMessage") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "warning")) { [18:02:35.902] muffled <- grepl(pattern, "muffleWarning") [18:02:35.902] if (muffled) [18:02:35.902] invokeRestart("muffleWarning") [18:02:35.902] } [18:02:35.902] else if (inherits(cond, "condition")) { [18:02:35.902] if (!is.null(pattern)) { [18:02:35.902] computeRestarts <- base::computeRestarts [18:02:35.902] grepl <- base::grepl [18:02:35.902] restarts <- computeRestarts(cond) [18:02:35.902] for (restart in restarts) { [18:02:35.902] name <- restart$name [18:02:35.902] if (is.null(name)) [18:02:35.902] next [18:02:35.902] if (!grepl(pattern, name)) [18:02:35.902] next [18:02:35.902] invokeRestart(restart) [18:02:35.902] muffled <- TRUE [18:02:35.902] break [18:02:35.902] } [18:02:35.902] } [18:02:35.902] } [18:02:35.902] invisible(muffled) [18:02:35.902] } [18:02:35.902] muffleCondition(cond, pattern = "^muffle") [18:02:35.902] } [18:02:35.902] } [18:02:35.902] } [18:02:35.902] })) [18:02:35.902] }, error = function(ex) { [18:02:35.902] base::structure(base::list(value = NULL, visible = NULL, [18:02:35.902] conditions = ...future.conditions, rng = !identical(base::globalenv()$.Random.seed, [18:02:35.902] ...future.rng), started = ...future.startTime, [18:02:35.902] finished = Sys.time(), session_uuid = NA_character_, [18:02:35.902] version = "1.8"), class = "FutureResult") [18:02:35.902] }, finally = { [18:02:35.902] if (!identical(...future.workdir, getwd())) [18:02:35.902] setwd(...future.workdir) [18:02:35.902] { [18:02:35.902] if (identical(getOption("nwarnings"), ...future.oldOptions$nwarnings)) { [18:02:35.902] ...future.oldOptions$nwarnings <- NULL [18:02:35.902] } [18:02:35.902] base::options(...future.oldOptions) [18:02:35.902] if (.Platform$OS.type == "windows") { [18:02:35.902] old_names <- names(...future.oldEnvVars) [18:02:35.902] envs <- base::Sys.getenv() [18:02:35.902] names <- names(envs) [18:02:35.902] common <- intersect(names, old_names) [18:02:35.902] added <- setdiff(names, old_names) [18:02:35.902] removed <- setdiff(old_names, names) [18:02:35.902] changed <- common[...future.oldEnvVars[common] != [18:02:35.902] envs[common]] [18:02:35.902] NAMES <- toupper(changed) [18:02:35.902] args <- list() [18:02:35.902] for (kk in seq_along(NAMES)) { [18:02:35.902] name <- changed[[kk]] [18:02:35.902] NAME <- NAMES[[kk]] [18:02:35.902] if (name != NAME && is.element(NAME, old_names)) [18:02:35.902] next [18:02:35.902] args[[name]] <- ...future.oldEnvVars[[name]] [18:02:35.902] } [18:02:35.902] NAMES <- toupper(added) [18:02:35.902] for (kk in seq_along(NAMES)) { [18:02:35.902] name <- added[[kk]] [18:02:35.902] NAME <- NAMES[[kk]] [18:02:35.902] if (name != NAME && is.element(NAME, old_names)) [18:02:35.902] next [18:02:35.902] args[[name]] <- "" [18:02:35.902] } [18:02:35.902] NAMES <- toupper(removed) [18:02:35.902] for (kk in seq_along(NAMES)) { [18:02:35.902] name <- removed[[kk]] [18:02:35.902] NAME <- NAMES[[kk]] [18:02:35.902] if (name != NAME && is.element(NAME, old_names)) [18:02:35.902] next [18:02:35.902] args[[name]] <- ...future.oldEnvVars[[name]] [18:02:35.902] } [18:02:35.902] if (length(args) > 0) [18:02:35.902] base::do.call(base::Sys.setenv, args = args) [18:02:35.902] args <- names <- old_names <- NAMES <- envs <- common <- added <- removed <- NULL [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] base::do.call(base::Sys.setenv, args = base::as.list(...future.oldEnvVars)) [18:02:35.902] } [18:02:35.902] { [18:02:35.902] if (base::length(...future.futureOptionsAdded) > [18:02:35.902] 0L) { [18:02:35.902] opts <- base::vector("list", length = base::length(...future.futureOptionsAdded)) [18:02:35.902] base::names(opts) <- ...future.futureOptionsAdded [18:02:35.902] base::options(opts) [18:02:35.902] } [18:02:35.902] { [18:02:35.902] NULL [18:02:35.902] options(future.plan = NULL) [18:02:35.902] if (is.na(NA_character_)) [18:02:35.902] Sys.unsetenv("R_FUTURE_PLAN") [18:02:35.902] else Sys.setenv(R_FUTURE_PLAN = NA_character_) [18:02:35.902] future::plan(list(function (..., workers = "localhost", [18:02:35.902] envir = parent.frame()) [18:02:35.902] strategy(..., workers = workers, envir = envir)), [18:02:35.902] .cleanup = FALSE, .init = FALSE) [18:02:35.902] } [18:02:35.902] } [18:02:35.902] } [18:02:35.902] }) [18:02:35.902] if (TRUE) { [18:02:35.902] base::sink(type = "output", split = FALSE) [18:02:35.902] if (TRUE) { [18:02:35.902] ...future.result$stdout <- base::rawToChar(base::rawConnectionValue(...future.stdout)) [18:02:35.902] } [18:02:35.902] else { [18:02:35.902] ...future.result["stdout"] <- base::list(NULL) [18:02:35.902] } [18:02:35.902] base::close(...future.stdout) [18:02:35.902] ...future.stdout <- NULL [18:02:35.902] } [18:02:35.902] ...future.result$conditions <- ...future.conditions [18:02:35.902] ...future.result$finished <- base::Sys.time() [18:02:35.902] ...future.result [18:02:35.902] } [18:02:35.907] ClusterFuture started [18:02:35.907] - Launch lazy future ... done [18:02:35.908] run() for 'ClusterFuture' ... done > > res <- tryCatch({ + requestNode(function() { }, workers = f$workers, timeout = 0.5, delta = 0.1) + }, error = identity) [18:02:35.908] Poll #1 (0): usedNodes() = 1, workers = 1 [18:02:36.021] Poll #2 (0.11 secs): usedNodes() = 1, workers = 1 [18:02:36.131] Poll #3 (0.22 secs): usedNodes() = 1, workers = 1 [18:02:36.240] Poll #4 (0.33 secs): usedNodes() = 1, workers = 1 [18:02:36.349] Poll #5 (0.44 secs): usedNodes() = 1, workers = 1 [18:02:36.459] TIMEOUT: All 1 cluster nodes are still occupied after 0.55 secs (polled 6 times) > stopifnot(inherits(res, "error")) > > v <- value(f) [18:02:36.459] result() for ClusterFuture ... [18:02:36.460] receiveMessageFromWorker() for ClusterFuture ... [18:02:36.460] - Validating connection of ClusterFuture [18:02:41.027] - received message: FutureResult [18:02:41.028] - Received FutureResult [18:02:41.028] - Erased future from FutureRegistry [18:02:41.028] result() for ClusterFuture ... [18:02:41.028] - result already collected: FutureResult [18:02:41.029] result() for ClusterFuture ... done [18:02:41.029] receiveMessageFromWorker() for ClusterFuture ... done [18:02:41.029] result() for ClusterFuture ... done [18:02:41.029] result() for ClusterFuture ... [18:02:41.029] - result already collected: FutureResult [18:02:41.029] 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") [18:02:41.031] plan(): Setting new future strategy stack: [18:02:41.031] List of future strategies: [18:02:41.031] 1. FutureStrategy: [18:02:41.031] - args: function (..., envir = parent.frame()) [18:02:41.031] - tweaked: FALSE [18:02:41.031] - call: future::plan(oplan) [18:02:41.032] plan(): nbrOfWorkers() = 1 Failed to undo environment variables: - Expected environment variables: [n=201] '!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', 'OMP_THREAD_LIMIT', 'OS', 'PATH', 'PATHEXT', 'PROCESSOR_ARCHITECTURE', 'PROCESSOR_IDENTIFIER', 'PROCESSOR_LEVEL', 'PROCESSOR_REVISION', 'PROGRAMFILES', 'PROMPT', 'PSModulePath', 'PUBLIC', 'PWD', 'ProgramData', 'ProgramFiles(x86)', 'ProgramW6432', 'RETICULATE_AUTOCONFIGURE', 'RTOOLS43_HOME', 'R_ARCH', 'R_BROWSER', 'R_BZIPCMD', 'R_CMD', 'R_COMPILED_BY', 'R_CRAN_WEB', 'R_CUSTOM_TOOLS_PATH', 'R_CUSTOM_TOOLS_SOFT', 'R_DOC_DIR', 'R_ENVIRON_USER', 'R_GSCMD', 'R_GZIPCMD', 'R_HOME', 'R_INCLUDE_DIR', 'R_INSTALL_TAR', 'R_LIBS', 'R_LIBS_SITE', 'R_LIBS_USER', 'R_MAX_NUM_DLLS', 'R_OSTYPE', 'R_PAPERSIZE', 'R_PAPERSIZE_USER', 'R_PARALLELLY_MAKENODEPSOCK_AUTOKILL', 'R_PARALLELLY_MAKENODEPSOCK_CONNECTTIMEOUT', 'R_PARALLELLY_MAKENODEPSOCK_RSCRIPT_LABEL', 'R_PARALLELLY_MAKENODEPSOCK_SESSIONINFO_PKGS', 'R_PARALLELLY_MAKENODEPSOCK_TIMEOUT', 'R_PARALLELLY_RANDOM_PORTS', 'R_PARALLEL_PORT', 'R_RD4PDF', 'R_RTOOLS43_PATH', 'R_SCRIPT_LEGACY', 'R_SHARE_DIR', 'R_TESTS', 'R_UNZIPCMD', 'R_USER', 'R_VERSION', 'R_ZIPCMD', 'SED', 'SHLVL', 'SYSTEMDRIVE', 'SYSTEMROOT', 'TAR', 'TAR_OPTIONS', 'TEMP', 'TERM', 'TEXINPUTS', 'TMP', 'TMPDIR', 'USERDOMAIN', 'USERDOMAIN_ROAMINGPROFILE', 'USERNAME', 'USERPROFILE', 'WINDIR', '_', '_R_CHECK_AUTOCONF_', '_R_CHECK_BOGUS_RETURN_', '_R_CHECK_BROWSER_NONINTERACTIVE_', '_R_CHECK_BUILD_VIGNETTES_SEPARATELY_', '_R_CHECK_CODETOOLS_PROFILE_', '_R_CHECK_CODE_ASSIGN_TO_GLOBALENV_', '_R_CHECK_CODE_ATTACH_', '_R_CHECK_CODE_CLASS_IS_STRING_', '_R_CHECK_CODE_DATA_INTO_GLOBALENV_', '_R_CHECK_CODE_USAGE_VIA_NAMESPACES_', '_R_CHECK_CODE_USAGE_WITHOUT_LOADING_', '_R_CHECK_CODE_USAGE_WITH_ONLY_BASE_ATTACHED_', '_R_CHECK_CODOC_VARIABLES_IN_USAGES_', '_R_CHECK_COMPACT_DATA2_', '_R_CHECK_COMPILATION_FLAGS_', '_R_CHECK_CONNECTIONS_LEFT_OPEN_', '_R_CHECK_CRAN_INCOMING_', '_R_CHECK_CRAN_INCOMING_CHECK_FILE_URIS_', '_R_CHECK_CRAN_INCOMING_CHECK_URLS_IN_PARALLEL_', '_R_CHECK_CRAN_INCOMING_NOTE_GNU_MAKE_', '_R_CHECK_CRAN_INCOMING_REMOTE_', '_R_CHECK_CRAN_INCOMING_USE_ASPELL_', '_R_CHECK_DATALIST_', '_R_CHECK_DEPRECATED_DEFUNCT_', '_R_CHECK_DOC_SIZES2_', '_R_CHECK_DOT_FIRSTLIB_', '_R_CHECK_DOT_INTERNAL_', '_R_CHECK_EXAMPLE_TIMING_THRESHOLD_', '_R_CHECK_EXECUTABLES_', '_R_CHECK_EXECUTABLES_EXCLUSIONS_', '_R_CHECK_FF_CALLS_', '_R_CHECK_FF_DUP_', '_R_CHECK_FORCE_SUGGESTS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_LEEWAY_', '_R_CHECK_HAVE_MYSQL_', '_R_CHECK_HAVE_ODBC_', '_R_CHECK_HAVE_PERL_', '_R_CHECK_HAVE_POSTGRES_', '_R_CHECK_INSTALL_DEPENDS_', '_R_CHECK_INTERNALS2_', '_R_CHECK_LENGTH_1_CONDITION_', '_R_CHECK_LICENSE_', '_R_CHECK_LIMIT_CORES_', '_R_CHECK_MATRIX_DATA_', '_R_CHECK_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_VALIDATE_RD2HTML_', '_R_CHECK_REPLACING_IMPORTS_', '_R_CHECK_R_DEPENDS_', '_R_CHECK_S3_METHODS_SHOW_POSSIBLE_ISSUES_', '_R_CHECK_SCREEN_DEVICE_', '_R_CHECK_SERIALIZATION_', '_R_CHECK_SHLIB_OPENMP_FLAGS_', '_R_CHECK_SRC_MINUS_W_IMPLICIT_', '_R_CHECK_SUBDIRS_NOCASE_', '_R_CHECK_SUBDIRS_STRICT_', '_R_CHECK_SUGGESTS_ONLY_', '_R_CHECK_SYSTEM_CLOCK_', '_R_CHECK_TESTS_NLINES_', '_R_CHECK_TEST_TIMING_', '_R_CHECK_TIMINGS_', '_R_CHECK_TOPLEVEL_FILES_', '_R_CHECK_UNDOC_USE_ALL_NAMES_', '_R_CHECK_UNSAFE_CALLS_', '_R_CHECK_URLS_SHOW_301_STATUS_', '_R_CHECK_VC_DIRS_', '_R_CHECK_VIGNETTES_NLINES_', '_R_CHECK_VIGNETTES_SKIP_RUN_MAYBE_', '_R_CHECK_VIGNETTE_TIMING_', '_R_CHECK_VIGNETTE_TITLES_', '_R_CHECK_WINDOWS_DEVICE_', '_R_CHECK_XREFS_USE_ALIASES_FROM_CRAN_', '_R_CLASS_MATRIX_ARRAY_', '_R_INSTALL_TIME_PATCHES_', '_R_S3_METHOD_LOOKUP_BASEENV_AFTER_GLOBALENV_', '_R_SHLIB_BUILD_OBJECTS_SYMBOL_TABLES_', '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.45 0.04 6.53