R Under development (unstable) (2026-03-12 r89611 ucrt) -- "Unsuffered Consequences" Copyright (C) 2026 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. > #! /usr/bin/env Rscript > ## This runs testme test script inst/testme/test-globals,S4methods.R > ## Don't edit - it was autogenerated by inst/testme/deploy.R > future:::testme("globals,S4methods") Test 'globals,S4methods' ... chr "none" > library(future) [22:17:40.805] Option 'future.startup.script': TRUE [22:17:40.807] Future startup scripts considered: '.future.R', '~/.future.R' [22:17:40.807] Future startup scripts found: > library(methods) > message("*** Globals - S4 methods ...") *** Globals - S4 methods ... > setGeneric("my_fcn", function(x) standardGeneric("my_fcn")) [1] "my_fcn" > setMethod("my_fcn", signature(x = "numeric"), function(x) { + -x + }) > org_my_fcn <- my_fcn > truth <- my_fcn(3) > for (strategy in supportedStrategies()) { + message("Type of future: ", strategy) + plan(strategy) + f <- future({ + my_fcn + .... [TRUNCATED] Type of future: sequential [22:17:40.830] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:40.830] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:17:40.831] | : all.equal() for FutureStrategyList ... [22:17:40.831] | : . New stack: [22:17:40.832] | : . List of 1 [22:17:40.832] | : . $ :function (..., envir = parent.frame()) [22:17:40.832] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.832] | : . ..- attr(*, "init")= logi TRUE [22:17:40.832] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.832] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.832] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.832] | : . ..- attr(*, "call")= language plan(strategy) [22:17:40.832] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:40.836] | : . Old stack: [22:17:40.836] | : . List of 1 [22:17:40.836] | : . $ :function (..., envir = parent.frame()) [22:17:40.836] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.836] | : . ..- attr(*, "init")= logi TRUE [22:17:40.836] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.836] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.836] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.836] | : . ..- attr(*, "call")= language plan(sequential) [22:17:40.836] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:40.840] | : . Not identical [22:17:40.840] | : . all.equal() for future ... [22:17:40.841] | : . ' List of 2 [22:17:40.841] | : . ' $ target :function (..., envir = parent.frame()) [22:17:40.841] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.841] | : . ' ..- attr(*, "init")= logi TRUE [22:17:40.841] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.841] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.841] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.841] | : . ' ..- attr(*, "call")= language plan(strategy) [22:17:40.841] | : . ' $ current:function (..., envir = parent.frame()) [22:17:40.841] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.841] | : . ' ..- attr(*, "init")= logi TRUE [22:17:40.841] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.841] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.841] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.841] | : . ' ..- attr(*, "call")= language plan(sequential) [22:17:40.847] | : . all.equal() for future ... done [22:17:40.848] | : all.equal() for FutureStrategyList ... done [22:17:40.848] | : plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:40.849] | : List of future strategies: [22:17:40.849] | : 1. sequential: [22:17:40.849] | : - args: function (..., envir = parent.frame(), workers = "") [22:17:40.849] | : - tweaked: FALSE [22:17:40.849] | : - call: plan(sequential) [22:17:40.850] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:17:40.850] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:40.851] future(..., label = NULL) ... [22:17:40.851] | lazy: TRUE [22:17:40.852] | stdout: TRUE [22:17:40.852] | conditions: [n=1] 'condition' [22:17:40.854] | getGlobalsAndPackages() ... [22:17:40.854] | : Searching for globals ... [22:17:40.897] | : . globals found: [7] '{', 'my_fcn', 'standardGeneric', 'NULL', 'stop', 'gettextf', 'x' [22:17:40.898] | : Searching for globals ... done [22:17:40.898] | : Resolving globals: FALSE [22:17:40.899] | : Search for packages associated with the globals ... [22:17:40.900] | : . Packages associated with globals: [1] 'base' [22:17:40.900] | : . Packages: [1] 'base' [22:17:40.901] | : Search for packages associated with the globals ... done [22:17:40.902] | : Packages after dropping 'base': [0] [22:17:40.902] | : globals: [1] 'my_fcn' [22:17:40.903] | : packages: [0] [22:17:40.903] | getGlobalsAndPackages() ... done [22:17:40.904] future(..., label = NULL) ... done [22:17:40.905] value() for Future () ... [22:17:40.905] | run() for 'Future' () ... [22:17:40.906] | : state: 'created' [22:17:40.906] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:40.907] | : . plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [22:17:40.907] | : . ' function (..., envir = parent.frame()) [22:17:40.907] | : . ' - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.907] | : . ' - attr(*, "init")= logi TRUE [22:17:40.907] | : . ' - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.907] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.907] | : . ' - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.907] | : . ' - attr(*, "call")= language plan(sequential) [22:17:40.910] | : . ' init: TRUE [22:17:40.911] | : . ' makeFutureBackend() ... [22:17:40.911] | : . ' , Backend function: <'NULL'> [22:17:40.912] | : . ' , Evaluator tweak arguments: [n=0] [22:17:40.912] | : . ' , list() [22:17:40.913] | : . ' , Evaluator formal arguments: [n=1] [22:17:40.913] | : . ' , Dotted pair list of 1 [22:17:40.913] | : . ' , $ envir: language parent.frame() [22:17:40.915] | : . ' , Arguments passed to the future-backend factory: [n=1] [22:17:40.915] | : . ' , List of 1 [22:17:40.915] | : . ' , $ envir: language parent.frame() [22:17:40.916] | : . ' , Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [22:17:40.917] | : . ' makeFutureBackend() ... done [22:17:40.917] | : . plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [22:17:40.918] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:40.919] | : Using SequentialFutureBackend ... [22:17:40.919] | : . Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:17:40.920] | : . Launching futures ... [22:17:40.920] | : . ' launchFuture() for 'SequentialFutureBackend', 'FutureBackend', 'environment' ... [22:17:40.921] | : . ' , getFutureData() ... [22:17:40.921] | : . ' , ; getFutureCore() ... [22:17:40.922] | : . ' , ; ` Packages needed by the future expression (n = 0): [22:17:40.922] | : . ' , ; getFutureCore() ... done [22:17:40.923] | : . ' , ; getFutureCapture() ... [22:17:40.923] | : . ' , ; getFutureCapture() ... done [22:17:40.924] | : . ' , ; getFutureContext() ... [22:17:40.925] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:40.925] | : . ' , ; ` | Getting stack without first backend: [n=0] [22:17:40.926] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:40.926] | : . ' , ; ` Packages needed by future backend (n = 0): [22:17:40.927] | : . ' , ; getFutureContext() ... done [22:17:40.927] | : . ' , getFutureData() ... done [22:17:40.955] | : . ' , plan("list", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:40.955] | : . ' , ; Getting full stack: [n=1] 'sequential', 'uniprocess', 'future', 'function' [22:17:40.956] | : . ' , plan("list", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:40.957] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:40.957] | : . ' , ; plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... [22:17:40.958] | : . ' , ; ` all.equal() for FutureStrategyList ... [22:17:40.958] | : . ' , ; ` | New stack: [22:17:40.959] | : . ' , ; ` | List of 1 [22:17:40.959] | : . ' , ; ` | $ :function (..., envir = parent.frame()) [22:17:40.959] | : . ' , ; ` | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.959] | : . ' , ; ` | ..- attr(*, "init")= logi TRUE [22:17:40.959] | : . ' , ; ` | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.959] | : . ' , ; ` | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.959] | : . ' , ; ` | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.959] | : . ' , ; ` | ..- attr(*, "call")= language plan(strategiesR, .cleanup = FALSE, .init = FALSE) [22:17:40.959] | : . ' , ; ` | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:40.962] | : . ' , ; ` | Old stack: [22:17:40.963] | : . ' , ; ` | List of 1 [22:17:40.963] | : . ' , ; ` | $ :function (..., envir = parent.frame()) [22:17:40.963] | : . ' , ; ` | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.963] | : . ' , ; ` | ..- attr(*, "init")= chr "done" [22:17:40.963] | : . ' , ; ` | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.963] | : . ' , ; ` | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.963] | : . ' , ; ` | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.963] | : . ' , ; ` | ..- attr(*, "call")= language plan(sequential) [22:17:40.963] | : . ' , ; ` | ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:40.963] | : . ' , ; ` | .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.963] | : . ' , ; ` | .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.963] | : . ' , ; ` | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:40.967] | : . ' , ; ` | Not identical [22:17:40.968] | : . ' , ; ` | all.equal() for future ... [22:17:40.968] | : . ' , ; ` | : List of 2 [22:17:40.968] | : . ' , ; ` | : $ target :function (..., envir = parent.frame()) [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "init")= logi TRUE [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.968] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "call")= language plan(strategiesR, .cleanup = FALSE, .init = FALSE) [22:17:40.968] | : . ' , ; ` | : $ current:function (..., envir = parent.frame()) [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.968] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:40.968] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:40.968] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.968] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.975] | : . ' , ; ` | all.equal() for future ... done [22:17:40.976] | : . ' , ; ` all.equal() for FutureStrategyList ... done [22:17:40.976] | : . ' , ; ` plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:40.977] | : . ' , ; ` List of future strategies: [22:17:40.977] | : . ' , ; ` 1. sequential: [22:17:40.977] | : . ' , ; ` - args: function (..., envir = parent.frame(), workers = "") [22:17:40.977] | : . ' , ; ` - tweaked: FALSE [22:17:40.977] | : . ' , ; ` - call: plan(sequential) [22:17:40.978] | : . ' , ; plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... done [22:17:40.978] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:40.979] | : . ' , assign_globals() ... [22:17:40.979] | : . ' , List of 1 [22:17:40.979] | : . ' , $ my_fcn:Formal class 'standardGeneric' [package "methods"] with 8 slots [22:17:40.979] | : . ' , .. ..@ .Data :function (x) [22:17:40.979] | : . ' , .. ..@ generic : chr "my_fcn" [22:17:40.979] | : . ' , .. .. ..- attr(*, "package")= chr ".GlobalEnv" [22:17:40.979] | : . ' , .. ..@ package : chr ".GlobalEnv" [22:17:40.979] | : . ' , .. ..@ group : list() [22:17:40.979] | : . ' , .. ..@ valueClass: chr(0) [22:17:40.979] | : . ' , .. ..@ signature : chr "x" [22:17:40.979] | : . ' , .. ..@ default : NULL [22:17:40.979] | : . ' , .. ..@ skeleton : language (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", ... [22:17:40.979] | : . ' , - attr(*, "where")=List of 1 [22:17:40.979] | : . ' , ..$ my_fcn: [22:17:40.979] | : . ' , - attr(*, "class")= chr [1:3] "FutureGlobals" "Globals" "list" [22:17:40.979] | : . ' , - attr(*, "resolved")= logi FALSE [22:17:40.979] | : . ' , - attr(*, "total_size")= num NA [22:17:40.979] | : . ' , - attr(*, "already-done")= logi TRUE [22:17:40.987] | : . ' , - copied 'my_fcn' to environment [22:17:40.988] | : . ' , assign_globals() ... done [22:17:40.992] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:40.993] | : . ' , ; plan(<'FutureStrategyList'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:40.993] | : . ' , ; ` plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... [22:17:40.994] | : . ' , ; ` | all.equal() for FutureStrategyList ... [22:17:40.995] | : . ' , ; ` | : New stack: [22:17:40.995] | : . ' , ; ` | : List of 1 [22:17:40.995] | : . ' , ; ` | : $ :function (..., envir = parent.frame()) [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.995] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:40.995] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:40.995] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:40.995] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:40.995] | : . ' , ; ` | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.000] | : . ' , ; ` | : Old stack: [22:17:41.001] | : . ' , ; ` | : List of 1 [22:17:41.001] | : . ' , ; ` | : $ :function (..., envir = parent.frame()) [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.001] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:41.001] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.001] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.001] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.001] | : . ' , ; ` | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.006] | : . ' , ; ` | : Identical [22:17:41.007] | : . ' , ; ` | all.equal() for FutureStrategyList ... done [22:17:41.007] | : . ' , ; ` | plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:41.008] | : . ' , ; ` | List of future strategies: [22:17:41.008] | : . ' , ; ` | 1. sequential: [22:17:41.008] | : . ' , ; ` | - args: function (..., envir = parent.frame(), workers = "") [22:17:41.008] | : . ' , ; ` | - tweaked: FALSE [22:17:41.008] | : . ' , ; ` | - call: plan(sequential) [22:17:41.009] | : . ' , ; ` plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... done [22:17:41.009] | : . ' , ; plan(<'FutureStrategyList'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:41.010] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:41.014] | : . ' , SequentialFuture started (and completed) [22:17:41.015] | : . ' , signalConditions() ... [22:17:41.016] | : . ' , ; include = 'immediateCondition' [22:17:41.016] | : . ' , ; exclude = [22:17:41.017] | : . ' , ; resignal = FALSE [22:17:41.017] | : . ' , ; Number of conditions: 6 [22:17:41.018] | : . ' , ; int 6 [22:17:41.019] | : . ' , ; Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.019] | : . ' , ; Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.020] | : . ' , ; Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.020] | : . ' , ; Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.021] | : . ' , ; Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.021] | : . ' , ; Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.022] | : . ' , signalConditions() ... done [22:17:41.023] | : . ' launchFuture() for 'SequentialFutureBackend', 'FutureBackend', 'environment' ... done [22:17:41.023] | : . Launching futures ... done [22:17:41.024] | : . Future launched: 'SequentialFuture', 'UniprocessFuture', 'Future' [22:17:41.024] | : Using SequentialFutureBackend ... done [22:17:41.025] | run() for 'Future' () ... done [22:17:41.026] | signalConditions() ... [22:17:41.026] | : include = 'immediateCondition' [22:17:41.027] | : exclude = [22:17:41.028] | : resignal = FALSE [22:17:41.028] | : Number of conditions: 6 [22:17:41.029] | : int 6 [22:17:41.030] | : Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.030] | : Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.031] | : Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.031] | : Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.032] | : Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.032] | : Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.033] | signalConditions() ... done [22:17:41.033] | relay stdout ... [22:17:41.034] | relay stdout ... done [22:17:41.034] | check for misuse ... [22:17:41.035] | check for misuse ... done [22:17:41.035] | relay conditions ... [22:17:41.036] | : Future state: 'finished' [22:17:41.036] | : signalConditions() ... [22:17:41.037] | : . include = 'condition' [22:17:41.037] | : . exclude = 'immediateCondition' [22:17:41.038] | : . resignal = TRUE [22:17:41.038] | : . Number of conditions: 6 [22:17:41.039] | : . int 6 [22:17:41.040] | : . Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.040] | : . Condition #1: 'simpleMessage', 'message', 'condition' [22:17:40.951] | : . ' , attachPackages() ... [22:17:41.041] | : . Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.042] | : . Condition #2: 'simpleMessage', 'message', 'condition' [22:17:40.952] | : . ' , ; packages: [n=1] 'future' [22:17:41.042] | : . Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.043] | : . Condition #3: 'simpleMessage', 'message', 'condition' [22:17:40.952] | : . ' , attachPackages() ... done [22:17:41.043] | : . Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.044] | : . Condition #4: 'simpleMessage', 'message', 'condition' [22:17:40.953] | : . ' , attachPackages() ... [22:17:41.044] | : . Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.045] | : . Condition #5: 'simpleMessage', 'message', 'condition' [22:17:40.953] | : . ' , ; packages: [n=0] [22:17:41.046] | : . Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.046] | : . Condition #6: 'simpleMessage', 'message', 'condition' [22:17:40.954] | : . ' , attachPackages() ... done [22:17:41.047] | : signalConditions() ... done [22:17:41.047] | relay conditions ... done [22:17:41.048] value() for Future () ... done new("standardGeneric", .Data = function (x) standardGeneric("my_fcn"), generic = "my_fcn", package = ".GlobalEnv", group = list(), valueClass = character(0), signature = "x", default = NULL, skeleton = (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", "my_fcn"), domain = NA))(x)) attr(,"generic") [1] "my_fcn" attr(,"generic")attr(,"package") [1] ".GlobalEnv" attr(,"package") [1] ".GlobalEnv" attr(,"group") list() attr(,"valueClass") character(0) attr(,"signature") [1] "x" attr(,"default") `\001NULL\001` attr(,"skeleton") (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", "my_fcn"), domain = NA))(x) attr(,"class") [1] "standardGeneric" attr(,"class")attr(,"package") [1] "methods" [22:17:41.049] future(..., label = NULL) ... [22:17:41.049] | lazy: TRUE [22:17:41.050] | stdout: TRUE [22:17:41.050] | conditions: [n=1] 'condition' [22:17:41.051] | getGlobalsAndPackages() ... [22:17:41.051] | : Searching for globals ... [22:17:41.073] | : . globals found: [7] '{', 'my_fcn', 'standardGeneric', 'NULL', 'stop', 'gettextf', 'x' [22:17:41.073] | : Searching for globals ... done [22:17:41.074] | : Resolving globals: FALSE [22:17:41.074] | : Search for packages associated with the globals ... [22:17:41.075] | : . Packages associated with globals: [1] 'base' [22:17:41.076] | : . Packages: [1] 'base' [22:17:41.076] | : Search for packages associated with the globals ... done [22:17:41.077] | : Packages after dropping 'base': [0] [22:17:41.078] | : globals: [1] 'my_fcn' [22:17:41.078] | : packages: [0] [22:17:41.079] | getGlobalsAndPackages() ... done [22:17:41.079] future(..., label = NULL) ... done [22:17:41.080] value() for Future () ... [22:17:41.080] | run() for 'Future' () ... [22:17:41.081] | : state: 'created' [22:17:41.081] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.082] | : plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:41.082] | : Using SequentialFutureBackend ... [22:17:41.083] | : . Number of futures since start: 1 (1 created, 1 launched, 1 finished) [22:17:41.083] | : . Launching futures ... [22:17:41.084] | : . ' launchFuture() for 'SequentialFutureBackend', 'FutureBackend', 'environment' ... [22:17:41.085] | : . ' , getFutureData() ... [22:17:41.085] | : . ' , ; getFutureCore() ... [22:17:41.086] | : . ' , ; ` Packages needed by the future expression (n = 0): [22:17:41.086] | : . ' , ; getFutureCore() ... done [22:17:41.087] | : . ' , ; getFutureCapture() ... [22:17:41.087] | : . ' , ; getFutureCapture() ... done [22:17:41.088] | : . ' , ; getFutureContext() ... [22:17:41.088] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.089] | : . ' , ; ` | Getting stack without first backend: [n=0] [22:17:41.089] | : . ' , ; ` plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:41.090] | : . ' , ; ` Packages needed by future backend (n = 0): [22:17:41.090] | : . ' , ; getFutureContext() ... done [22:17:41.091] | : . ' , getFutureData() ... done [22:17:41.095] | : . ' , plan("list", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.096] | : . ' , ; Getting full stack: [n=1] 'sequential', 'uniprocess', 'future', 'function' [22:17:41.096] | : . ' , plan("list", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:41.097] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:41.098] | : . ' , ; plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... [22:17:41.098] | : . ' , ; ` all.equal() for FutureStrategyList ... [22:17:41.099] | : . ' , ; ` | New stack: [22:17:41.099] | : . ' , ; ` | List of 1 [22:17:41.099] | : . ' , ; ` | $ :function (..., envir = parent.frame()) [22:17:41.099] | : . ' , ; ` | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.099] | : . ' , ; ` | ..- attr(*, "init")= logi TRUE [22:17:41.099] | : . ' , ; ` | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.099] | : . ' , ; ` | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.099] | : . ' , ; ` | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.099] | : . ' , ; ` | ..- attr(*, "call")= language plan(strategiesR, .cleanup = FALSE, .init = FALSE) [22:17:41.099] | : . ' , ; ` | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.103] | : . ' , ; ` | Old stack: [22:17:41.104] | : . ' , ; ` | List of 1 [22:17:41.104] | : . ' , ; ` | $ :function (..., envir = parent.frame()) [22:17:41.104] | : . ' , ; ` | ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.104] | : . ' , ; ` | ..- attr(*, "init")= chr "done" [22:17:41.104] | : . ' , ; ` | ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.104] | : . ' , ; ` | .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.104] | : . ' , ; ` | ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.104] | : . ' , ; ` | ..- attr(*, "call")= language plan(sequential) [22:17:41.104] | : . ' , ; ` | ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.104] | : . ' , ; ` | .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.104] | : . ' , ; ` | .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.104] | : . ' , ; ` | - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.109] | : . ' , ; ` | Not identical [22:17:41.109] | : . ' , ; ` | all.equal() for future ... [22:17:41.110] | : . ' , ; ` | : List of 2 [22:17:41.110] | : . ' , ; ` | : $ target :function (..., envir = parent.frame()) [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "init")= logi TRUE [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.110] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "call")= language plan(strategiesR, .cleanup = FALSE, .init = FALSE) [22:17:41.110] | : . ' , ; ` | : $ current:function (..., envir = parent.frame()) [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.110] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:41.110] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.110] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.110] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.119] | : . ' , ; ` | all.equal() for future ... done [22:17:41.120] | : . ' , ; ` all.equal() for FutureStrategyList ... done [22:17:41.120] | : . ' , ; ` plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:41.121] | : . ' , ; ` List of future strategies: [22:17:41.121] | : . ' , ; ` 1. sequential: [22:17:41.121] | : . ' , ; ` - args: function (..., envir = parent.frame(), workers = "") [22:17:41.121] | : . ' , ; ` - tweaked: FALSE [22:17:41.121] | : . ' , ; ` - call: plan(sequential) [22:17:41.122] | : . ' , ; plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... done [22:17:41.122] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:41.123] | : . ' , assign_globals() ... [22:17:41.123] | : . ' , List of 1 [22:17:41.123] | : . ' , $ my_fcn:Formal class 'standardGeneric' [package "methods"] with 8 slots [22:17:41.123] | : . ' , .. ..@ .Data :function (x) [22:17:41.123] | : . ' , .. ..@ generic : chr "my_fcn" [22:17:41.123] | : . ' , .. .. ..- attr(*, "package")= chr ".GlobalEnv" [22:17:41.123] | : . ' , .. ..@ package : chr ".GlobalEnv" [22:17:41.123] | : . ' , .. ..@ group : list() [22:17:41.123] | : . ' , .. ..@ valueClass: chr(0) [22:17:41.123] | : . ' , .. ..@ signature : chr "x" [22:17:41.123] | : . ' , .. ..@ default : NULL [22:17:41.123] | : . ' , .. ..@ skeleton : language (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", ... [22:17:41.123] | : . ' , - attr(*, "where")=List of 1 [22:17:41.123] | : . ' , ..$ my_fcn: [22:17:41.123] | : . ' , - attr(*, "class")= chr [1:3] "FutureGlobals" "Globals" "list" [22:17:41.123] | : . ' , - attr(*, "resolved")= logi FALSE [22:17:41.123] | : . ' , - attr(*, "total_size")= num NA [22:17:41.123] | : . ' , - attr(*, "already-done")= logi TRUE [22:17:41.130] | : . ' , - copied 'my_fcn' to environment [22:17:41.130] | : . ' , assign_globals() ... done [22:17:41.134] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:41.135] | : . ' , ; plan(<'FutureStrategyList'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... [22:17:41.135] | : . ' , ; ` plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... [22:17:41.136] | : . ' , ; ` | all.equal() for FutureStrategyList ... [22:17:41.136] | : . ' , ; ` | : New stack: [22:17:41.137] | : . ' , ; ` | : List of 1 [22:17:41.137] | : . ' , ; ` | : $ :function (..., envir = parent.frame()) [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.137] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:41.137] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.137] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.137] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.137] | : . ' , ; ` | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.141] | : . ' , ; ` | : Old stack: [22:17:41.142] | : . ' , ; ` | : List of 1 [22:17:41.142] | : . ' , ; ` | : $ :function (..., envir = parent.frame()) [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "init")= chr "done" [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.142] | : . ' , ; ` | : .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "call")= language plan(sequential) [22:17:41.142] | : . ' , ; ` | : ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.142] | : . ' , ; ` | : .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.142] | : . ' , ; ` | : .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.142] | : . ' , ; ` | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.146] | : . ' , ; ` | : Identical [22:17:41.146] | : . ' , ; ` | all.equal() for FutureStrategyList ... done [22:17:41.147] | : . ' , ; ` | plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:41.147] | : . ' , ; ` | List of future strategies: [22:17:41.147] | : . ' , ; ` | 1. sequential: [22:17:41.147] | : . ' , ; ` | - args: function (..., envir = parent.frame(), workers = "") [22:17:41.147] | : . ' , ; ` | - tweaked: FALSE [22:17:41.147] | : . ' , ; ` | - call: plan(sequential) [22:17:41.148] | : . ' , ; ` plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = FALSE, init = FALSE) ... done [22:17:41.149] | : . ' , ; plan(<'FutureStrategyList'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:41.149] | : . ' , plan(<'name'>, .skip = FALSE, .cleanup = FALSE, .init = FALSE) ... done [22:17:41.150] | : . ' , SequentialFuture started (and completed) [22:17:41.151] | : . ' , signalConditions() ... [22:17:41.151] | : . ' , ; include = 'immediateCondition' [22:17:41.152] | : . ' , ; exclude = [22:17:41.152] | : . ' , ; resignal = FALSE [22:17:41.152] | : . ' , ; Number of conditions: 6 [22:17:41.153] | : . ' , ; int 6 [22:17:41.154] | : . ' , ; Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.154] | : . ' , ; Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.155] | : . ' , ; Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.155] | : . ' , ; Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.156] | : . ' , ; Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.156] | : . ' , ; Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.157] | : . ' , signalConditions() ... done [22:17:41.157] | : . ' launchFuture() for 'SequentialFutureBackend', 'FutureBackend', 'environment' ... done [22:17:41.158] | : . Launching futures ... done [22:17:41.158] | : . Future launched: 'SequentialFuture', 'UniprocessFuture', 'Future' [22:17:41.159] | : Using SequentialFutureBackend ... done [22:17:41.159] | run() for 'Future' () ... done [22:17:41.160] | signalConditions() ... [22:17:41.160] | : include = 'immediateCondition' [22:17:41.161] | : exclude = [22:17:41.161] | : resignal = FALSE [22:17:41.162] | : Number of conditions: 6 [22:17:41.162] | : int 6 [22:17:41.163] | : Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.164] | : Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.164] | : Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.165] | : Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.165] | : Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.166] | : Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.166] | signalConditions() ... done [22:17:41.166] | relay stdout ... [22:17:41.167] | relay stdout ... done [22:17:41.167] | check for misuse ... [22:17:41.168] | check for misuse ... done [22:17:41.168] | relay conditions ... [22:17:41.169] | : Future state: 'finished' [22:17:41.169] | : signalConditions() ... [22:17:41.170] | : . include = 'condition' [22:17:41.170] | : . exclude = 'immediateCondition' [22:17:41.171] | : . resignal = TRUE [22:17:41.171] | : . Number of conditions: 6 [22:17:41.172] | : . int 6 [22:17:41.173] | : . Condition #1 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.173] | : . Condition #1: 'simpleMessage', 'message', 'condition' [22:17:41.092] | : . ' , attachPackages() ... [22:17:41.174] | : . Condition #2 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.174] | : . Condition #2: 'simpleMessage', 'message', 'condition' [22:17:41.092] | : . ' , ; packages: [n=1] 'future' [22:17:41.175] | : . Condition #3 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.175] | : . Condition #3: 'simpleMessage', 'message', 'condition' [22:17:41.093] | : . ' , attachPackages() ... done [22:17:41.176] | : . Condition #4 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.176] | : . Condition #4: 'simpleMessage', 'message', 'condition' [22:17:41.093] | : . ' , attachPackages() ... [22:17:41.177] | : . Condition #5 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.177] | : . Condition #5: 'simpleMessage', 'message', 'condition' [22:17:41.094] | : . ' , ; packages: [n=0] [22:17:41.178] | : . Condition #6 (class: 'simpleMessage', 'message', 'condition'): [22:17:41.178] | : . Condition #6: 'simpleMessage', 'message', 'condition' [22:17:41.094] | : . ' , attachPackages() ... done [22:17:41.179] | : signalConditions() ... done [22:17:41.179] | relay conditions ... done [22:17:41.180] value() for Future () ... done [1] -3 future.globals.keepWhere=TRUE [22:17:41.180] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.181] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:17:41.182] | : all.equal() for FutureStrategyList ... [22:17:41.182] | : . New stack: [22:17:41.183] | : . List of 1 [22:17:41.183] | : . $ :function (..., envir = parent.frame()) [22:17:41.183] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.183] | : . ..- attr(*, "init")= logi TRUE [22:17:41.183] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.183] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.183] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.183] | : . ..- attr(*, "call")= language plan(sequential) [22:17:41.183] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.186] | : . Old stack: [22:17:41.187] | : . List of 1 [22:17:41.187] | : . $ :function (..., envir = parent.frame()) [22:17:41.187] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.187] | : . ..- attr(*, "init")= chr "done" [22:17:41.187] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.187] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.187] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.187] | : . ..- attr(*, "call")= language plan(sequential) [22:17:41.187] | : . ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.187] | : . .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.187] | : . .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.187] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.191] | : . Not identical [22:17:41.191] | : . all.equal() for future ... [22:17:41.192] | : . ' List of 2 [22:17:41.192] | : . ' $ target :function (..., envir = parent.frame()) [22:17:41.192] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.192] | : . ' ..- attr(*, "init")= logi TRUE [22:17:41.192] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.192] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.192] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.192] | : . ' ..- attr(*, "call")= language plan(sequential) [22:17:41.192] | : . ' $ current:function (..., envir = parent.frame()) [22:17:41.192] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.192] | : . ' ..- attr(*, "init")= chr "done" [22:17:41.192] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.192] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.192] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.192] | : . ' ..- attr(*, "call")= language plan(sequential) [22:17:41.192] | : . ' ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.192] | : . ' .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.192] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.199] | : . all.equal() for future ... done [22:17:41.199] | : all.equal() for FutureStrategyList ... done [22:17:41.200] | : plan(): Skip setting new future backend stack because it is the same as the current one: [22:17:41.200] | : List of future strategies: [22:17:41.200] | : 1. sequential: [22:17:41.200] | : - args: function (..., envir = parent.frame(), workers = "") [22:17:41.200] | : - tweaked: FALSE [22:17:41.200] | : - call: plan(sequential) [22:17:41.201] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:17:41.202] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done Type of future: multisession [22:17:41.202] plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.203] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:17:41.203] | : all.equal() for FutureStrategyList ... [22:17:41.204] | : . New stack: [22:17:41.204] | : . List of 1 [22:17:41.204] | : . $ :function (..., workers = availableCores(constraints = "connections-16"), [22:17:41.204] | : . rscript_libs = .libPaths()) [22:17:41.204] | : . ..- attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:41.204] | : . ..- attr(*, "init")= logi TRUE [22:17:41.204] | : . ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:41.204] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:41.204] | : . .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.204] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.204] | : . ..- attr(*, "untweakable")= chr "persistent" [22:17:41.204] | : . ..- attr(*, "call")= language plan(strategy) [22:17:41.204] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.208] | : . Old stack: [22:17:41.209] | : . List of 1 [22:17:41.209] | : . $ :function (..., envir = parent.frame()) [22:17:41.209] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.209] | : . ..- attr(*, "init")= chr "done" [22:17:41.209] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.209] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.209] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.209] | : . ..- attr(*, "call")= language plan(sequential) [22:17:41.209] | : . ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.209] | : . .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.209] | : . .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.209] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.213] | : . Not identical [22:17:41.213] | : . all.equal() for future ... [22:17:41.214] | : . ' List of 2 [22:17:41.214] | : . ' $ target :function (..., workers = availableCores(constraints = "connections-16"), [22:17:41.214] | : . ' rscript_libs = .libPaths()) [22:17:41.214] | : . ' ..- attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:41.214] | : . ' ..- attr(*, "init")= logi TRUE [22:17:41.214] | : . ' ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:41.214] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:41.214] | : . ' .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.214] | : . ' ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.214] | : . ' ..- attr(*, "untweakable")= chr "persistent" [22:17:41.214] | : . ' ..- attr(*, "call")= language plan(strategy) [22:17:41.214] | : . ' $ current:function (..., envir = parent.frame()) [22:17:41.214] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:41.214] | : . ' ..- attr(*, "init")= chr "done" [22:17:41.214] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.214] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.214] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.214] | : . ' ..- attr(*, "call")= language plan(sequential) [22:17:41.214] | : . ' ..- attr(*, "backend")=Classes 'SequentialFutureBackend', 'FutureBackend', 'environment' [22:17:41.214] | : . ' .. ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:41.214] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:41.221] | : . ' Formals differ [22:17:41.221] | : . all.equal() for future ... done [22:17:41.222] | : . Future strategies differ at level 1 [22:17:41.222] | : all.equal() for FutureStrategyList ... done [22:17:41.223] | : plan(): Setting new future backend stack: [22:17:41.223] | : List of future strategies: [22:17:41.223] | : 1. multisession: [22:17:41.223] | : - args: function (..., workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths()) [22:17:41.223] | : - tweaked: FALSE [22:17:41.223] | : - call: plan(strategy) [22:17:41.224] | : List of 1 [22:17:41.224] | : $ :function (..., workers = availableCores(constraints = "connections-16"), [22:17:41.224] | : rscript_libs = .libPaths()) [22:17:41.224] | : ..- attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:41.224] | : ..- attr(*, "init")= logi TRUE [22:17:41.224] | : ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:41.224] | : interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:41.224] | : .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.224] | : ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.224] | : ..- attr(*, "untweakable")= chr "persistent" [22:17:41.224] | : ..- attr(*, "call")= language plan(strategy) [22:17:41.224] | : - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:41.228] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... [22:17:41.228] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.229] | : . ' Getting current ("next") strategy: 'FutureStrategy', 'sequential', 'uniprocess', 'future', 'function' [22:17:41.230] | : . plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:41.230] | : . Legacy shutdown of cluster workers ... [22:17:41.231] | : . ' Stopping existing cluster ... [22:17:41.231] | : . ' , No pre-existing cluster. Skipping [22:17:41.232] | : . ' Stopping existing cluster ... done [22:17:41.232] | : . Legacy shutdown of cluster workers ... done [22:17:41.233] | : plan(): plan_cleanup(<'sequential', 'uniprocess', 'future', 'function'>, cleanup = NA) ... done [22:17:41.233] | : plan(): plan_init() of 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... [22:17:41.234] | : . function (..., workers = availableCores(constraints = "connections-16"), [22:17:41.234] | : . rscript_libs = .libPaths()) [22:17:41.234] | : . - attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:41.234] | : . - attr(*, "init")= logi TRUE [22:17:41.234] | : . - attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:41.234] | : . interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:41.234] | : . ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.234] | : . - attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:41.234] | : . - attr(*, "untweakable")= chr "persistent" [22:17:41.234] | : . - attr(*, "call")= language plan(strategy) [22:17:41.237] | : . init: TRUE [22:17:41.237] | : . makeFutureBackend() ... [22:17:41.238] | : . ' Backend function: <'NULL'> [22:17:41.238] | : . ' Evaluator tweak arguments: [n=0] [22:17:41.239] | : . ' list() [22:17:41.240] | : . ' Evaluator formal arguments: [n=2] [22:17:41.240] | : . ' Dotted pair list of 2 [22:17:41.240] | : . ' $ workers : language availableCores(constraints = "connections-16") [22:17:41.240] | : . ' $ rscript_libs: language .libPaths() [22:17:41.242] | : . ' Arguments passed to the future-backend factory: [n=2] [22:17:41.242] | : . ' List of 2 [22:17:41.242] | : . ' $ workers : language availableCores(constraints = "connections-16") [22:17:41.242] | : . ' $ rscript_libs: language .libPaths() [22:17:41.244] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... [22:17:41.244] | : . ' , Arguments: [22:17:41.245] | : . ' , List of 5 [22:17:41.245] | : . ' , $ workers : Named int 2 [22:17:41.245] | : . ' , ..- attr(*, "names")= chr "mc.cores" [22:17:41.245] | : . ' , $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:17:41.245] | : . ' , $ interrupts : logi TRUE [22:17:41.245] | : . ' , $ gc : logi FALSE [22:17:41.245] | : . ' , $ earlySignal : logi FALSE [22:17:41.287] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... [22:17:41.288] | : . ' , ; workers: 2 [22:17:41.288] | : . ' , ; getCluster() ... [22:17:41.289] | : . ' , ; getCluster() ... done [22:17:41.289] | : . ' , ; Stopping existing cluster ... [22:17:41.290] | : . ' , ; ` No pre-existing cluster. Skipping [22:17:41.290] | : . ' , ; Stopping existing cluster ... done [22:17:41.291] | : . ' , ; Starting new cluster ... [22:17:41.291] | : . ' , ; ` makeCluster(workers, ...) ... [22:17:41.291] | : . ' , ; ` | Arguments: [22:17:41.292] | : . ' , ; ` | List of 2 [22:17:41.292] | : . ' , ; ` | $ : int 2 [22:17:41.292] | : . ' , ; ` | $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:17:41.294] | : . ' , ; ` | .makeCluster() ... [22:17:41.294] | : . ' , ; ` | : workers: '2' [22:17:41.295] | : . ' , ; ` | : R_FUTURE_PLAN: NA [22:17:41.295] | : . ' , ; ` | : parallelly::makeClusterPSOCK() arguments: [22:17:41.296] | : . ' , ; ` | : List of 2 [22:17:41.296] | : . ' , ; ` | : $ : int 2 [22:17:41.296] | : . ' , ; ` | : $ rscript_libs: chr [1:2] "D:/temp/2026_03_13_22_15_17_12234/Rtmpq4wOsv/RLIBS_b2b8d53625" "D:/RCompile/recent/R/library" [22:17:41.968] | : . ' , ; ` | : Generated cluster UUID [22:17:41.969] | : . ' , ; ` | : Cluster UUID: '13da89b89e8f27ce5711ca0cc6a7227d' [22:17:41.969] | : . ' , ; ` | : Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:17:41.972] | : . ' , ; ` | .makeCluster() ... done [22:17:41.973] | : . ' , ; ` | Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:17:41.975] | : . ' , ; ` makeCluster(workers, ...) ... done [22:17:41.976] | : . ' , ; ` Number of workers: 2 [22:17:41.976] | : . ' , ; ` Workers UUID: '13da89b89e8f27ce5711ca0cc6a7227d' [22:17:41.977] | : . ' , ; Starting new cluster ... done [22:17:41.977] | : . ' , ClusterFutureBackend(..., persistent = FALSE, gc = FALSE, earlySignal = FALSE) ... done [22:17:41.978] | : . ' , Backend: <'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment'> [22:17:41.978] | : . ' MultisessionFutureBackend(workers = , interrupts = TRUE, ...) ... done [22:17:41.979] | : . makeFutureBackend() ... done [22:17:41.979] | : . nbrOfWorkers(NULL) ... [22:17:41.980] | : . ' List of 4 [22:17:41.980] | : . ' $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:17:41.980] | : . ' $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:17:41.980] | : . ' $ : language nbrOfWorkers() [22:17:41.980] | : . ' $ : language nbrOfWorkers.NULL() [22:17:41.982] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:41.983] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:41.984] | : . ' Number of workers: 2 [22:17:41.984] | : . nbrOfWorkers(NULL) ... done [22:17:41.985] | : . plan(): nbrOfWorkers() = 2 [22:17:41.985] | : plan(): plan_init() of 'multisession', 'cluster', 'multiprocess', 'future', 'function' ... done [22:17:41.986] | plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:17:41.986] | future(..., label = NULL) ... [22:17:41.986] | : lazy: TRUE [22:17:41.987] | : stdout: TRUE [22:17:41.987] | : conditions: [n=1] 'condition' [22:17:41.988] | : getGlobalsAndPackages() ... [22:17:41.988] | : . Searching for globals ... [22:17:42.004] | : . ' globals found: [7] '{', 'my_fcn', 'standardGeneric', 'NULL', 'stop', 'gettextf', 'x' [22:17:42.005] | : . Searching for globals ... done [22:17:42.005] | : . Resolving globals: FALSE [22:17:42.006] | : . Search for packages associated with the globals ... [22:17:42.006] | : . ' Packages associated with globals: [1] 'base' [22:17:42.007] | : . ' Packages: [1] 'base' [22:17:42.008] | : . Search for packages associated with the globals ... done [22:17:42.008] | : . Packages after dropping 'base': [0] [22:17:42.009] | : . globals: [1] 'my_fcn' [22:17:42.009] | : . packages: [0] [22:17:42.010] | : getGlobalsAndPackages() ... done [22:17:42.010] | future(..., label = NULL) ... done [22:17:42.011] | value() for Future () ... [22:17:42.011] | : run() for 'Future' () ... [22:17:42.012] | : . state: 'created' [22:17:42.012] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.013] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.013] | : . Using MultisessionFutureBackend ... [22:17:42.014] | : . ' Number of futures since start: 0 (0 created, 0 launched, 0 finished) [22:17:42.014] | : . ' Launching futures ... [22:17:42.015] | : . ' , launchFuture() for ClusterFutureBackend ... [22:17:42.016] | : . ' , ; Workers: [n=2] [22:17:42.016] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:17:42.018] | : . ' , ; FutureRegistry: 'workers-13da89b89e8f27ce5711ca0cc6a7227d' [22:17:42.019] | : . ' , ; requestWorker() ... [22:17:42.019] | : . ' , ; ` requestNode() ... [22:17:42.020] | : . ' , ; ` | Number of workers: 2 [22:17:42.020] | : . ' , ; ` | Polling for a free worker ... [22:17:42.021] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.021] | : . ' , ; ` | : . Created empty registry 'workers-13da89b89e8f27ce5711ca0cc6a7227d' [22:17:42.022] | : . ' , ; ` | : . Listing all futures [22:17:42.022] | : . ' , ; ` | : . Number of registered futures: 0 [22:17:42.023] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.023] | : . ' , ; ` | : Total time: 0 [22:17:42.024] | : . ' , ; ` | Polling for a free worker ... done [22:17:42.024] | : . ' , ; ` | FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.025] | : . ' , ; ` | : Listing all futures [22:17:42.025] | : . ' , ; ` | : Number of registered futures: 0 [22:17:42.026] | : . ' , ; ` | FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.026] | : . ' , ; ` | avail: [n=2] '1', '2' [22:17:42.027] | : . ' , ; ` | Index of first available worker: 1 [22:17:42.027] | : . ' , ; ` | Validate that the worker is functional ... [22:17:42.028] | : . ' , ; ` | : Connection is valid [22:17:42.028] | : . ' , ; ` | : RichSOCKnode of a socket cluster on local host 'localhost' with pid 37628 (R Under development (unstable) (2026-03-12 r89611 ucrt), x86_64-w64-mingw32) using socket connection #4 ('<-CRANwin3.fb05.statistik.uni-dortmund.de:33637') [22:17:42.030] | : . ' , ; ` | : Worker is functional [22:17:42.030] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.031] | : . ' , ; ` | : . Listing all futures [22:17:42.031] | : . ' , ; ` | : . Number of registered futures: 0 [22:17:42.032] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.032] | : . ' , ; ` | Validate that the worker is functional ... done [22:17:42.033] | : . ' , ; ` requestNode() ... done [22:17:42.033] | : . ' , ; ` cluster node index: 1 [22:17:42.034] | : . ' , ; requestWorker() ... done [22:17:42.034] | : . ' , ; eraseGlobalEnvironment() ... [22:17:42.035] | : . ' , ; eraseGlobalEnvironment() ... done [22:17:42.036] | : . ' , ; launchFuture() ... [22:17:42.036] | : . ' , ; ` cluster node index: 1 [22:17:42.037] | : . ' , ; ` getFutureData() ... [22:17:42.037] | : . ' , ; ` | getFutureCore() ... [22:17:42.038] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [22:17:42.038] | : . ' , ; ` | getFutureCore() ... done [22:17:42.039] | : . ' , ; ` | getFutureCapture() ... [22:17:42.039] | : . ' , ; ` | getFutureCapture() ... done [22:17:42.040] | : . ' , ; ` | getFutureContext() ... [22:17:42.040] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.041] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [22:17:42.041] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.042] | : . ' , ; ` | : Packages needed by future backend (n = 0): [22:17:42.042] | : . ' , ; ` | getFutureContext() ... done [22:17:42.043] | : . ' , ; ` getFutureData() ... done [22:17:42.044] | : . ' , ; ` FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'add', earlySignal = 0) ... [22:17:42.045] | : . ' , ; ` | Appended future to position #1 [22:17:42.045] | : . ' , ; ` | Number of registered futures: 1 [22:17:42.046] | : . ' , ; ` FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'add', earlySignal = 0) ... done [22:17:42.046] | : . ' , ; launchFuture() ... done [22:17:42.047] | : . ' , ; MultisessionFuture started [22:17:42.047] | : . ' , launchFuture() for ClusterFutureBackend ... done [22:17:42.048] | : . ' Launching futures ... done [22:17:42.048] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:17:42.049] | : . Using MultisessionFutureBackend ... done [22:17:42.049] | : run() for 'Future' () ... done [22:17:42.050] | : result() for ClusterFuture ... [22:17:42.051] | : . assertValidConnection() ... [22:17:42.051] | : . ' cluster node index: 1 [22:17:42.052] | : . assertValidConnection() ... done [22:17:42.053] | : . receiveMessageFromWorker() for ClusterFuture ... [22:17:42.054] | : . ' cluster node index: 1 [22:17:42.104] | : . ' [1] TRUE [22:17:42.105] | : . ' Received data: [22:17:42.105] | : . ' List of 5 [22:17:42.105] | : . ' $ type : chr "VALUE" [22:17:42.105] | : . ' $ value :List of 16 [22:17:42.105] | : . ' ..$ value :Formal class 'standardGeneric' [package "methods"] with 8 slots [22:17:42.105] | : . ' .. .. ..@ .Data :function (x) [22:17:42.105] | : . ' .. .. ..@ generic : chr "my_fcn" [22:17:42.105] | : . ' .. .. .. ..- attr(*, "package")= chr ".GlobalEnv" [22:17:42.105] | : . ' .. .. ..@ package : chr ".GlobalEnv" [22:17:42.105] | : . ' .. .. ..@ group : list() [22:17:42.105] | : . ' .. .. ..@ valueClass: chr(0) [22:17:42.105] | : . ' .. .. ..@ signature : chr "x" [22:17:42.105] | : . ' .. .. ..@ default : NULL [22:17:42.105] | : . ' .. .. ..@ skeleton : language (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", ... [22:17:42.105] | : . ' ..$ visible : logi TRUE [22:17:42.105] | : . ' ..$ stdout : chr "" [22:17:42.105] | : . ' ..$ conditions : list() [22:17:42.105] | : . ' ..$ rng : logi FALSE [22:17:42.105] | : . ' ..$ seed : NULL [22:17:42.105] | : . ' ..$ misuseGlobalEnv : NULL [22:17:42.105] | : . ' ..$ misuseConnections :List of 3 [22:17:42.105] | : . ' .. ..$ added : NULL [22:17:42.105] | : . ' .. ..$ removed : NULL [22:17:42.105] | : . ' .. ..$ replaced: NULL [22:17:42.105] | : . ' ..$ misuseDevices : NULL [22:17:42.105] | : . ' ..$ misuseDefaultDevice: list() [22:17:42.105] | : . ' ..$ started : POSIXct[1:1], format: "2026-03-13 22:17:42" [22:17:42.105] | : . ' ..$ finished : POSIXct[1:1], format: "2026-03-13 22:17:42" [22:17:42.105] | : . ' ..$ uuid : chr [1:2] "c781ea6271e7fad647a923618f39d819" "3" [22:17:42.105] | : . ' ..$ session_uuid : chr "511225045ed279fcdc8b363420daca4d" [22:17:42.105] | : . ' .. ..- attr(*, "source")=List of 5 [22:17:42.105] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [22:17:42.105] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:17:42.105] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:17:42.105] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:17:42.105] | : . ' .. .. ..$ pid : int 37628 [22:17:42.105] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:17:41" [22:17:42.105] | : . ' .. .. ..$ random: int 2147483647 [22:17:42.105] | : . ' ..$ r_info :List of 4 [22:17:42.105] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:17:42.105] | : . ' .. .. ..$ : int [1:3] 4 6 0 [22:17:42.105] | : . ' .. ..$ os : chr "windows" [22:17:42.105] | : . ' .. ..$ os_name : chr "Windows" [22:17:42.105] | : . ' .. ..$ captures_utf8: logi TRUE [22:17:42.105] | : . ' ..$ version : chr "1.8" [22:17:42.105] | : . ' ..- attr(*, "class")= chr "FutureResult" [22:17:42.105] | : . ' $ success: logi TRUE [22:17:42.105] | : . ' $ time : 'proc_time' Named num [1:5] 0.05 0 0.04 NA NA [22:17:42.105] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:17:42.105] | : . ' $ tag : NULL [22:17:42.124] | : . ' Received FutureResult [22:17:42.124] | : . ' FutureResult: [22:17:42.124] | : . ' value: 'standardGeneric' [22:17:42.124] | : . ' visible: TRUE [22:17:42.124] | : . ' stdout: character [22:17:42.124] | : . ' conditions: [n = 0] [22:17:42.124] | : . ' RNG used: FALSE [22:17:42.124] | : . ' duration: 0.05767083 secs (started 2026-03-13 22:17:42.045679) [22:17:42.124] | : . ' version: 1.8 [22:17:42.126] | : . ' FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'remove', earlySignal = 0) ... [22:17:42.126] | : . ' , Removed future from position #1 [22:17:42.127] | : . ' , Number of registered futures: 0 [22:17:42.127] | : . ' FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'remove', earlySignal = 0) ... done [22:17:42.128] | : . ' Erased future from future backend [22:17:42.128] | : . ' result() for ClusterFuture ... [22:17:42.129] | : . ' , result already collected: FutureResult [22:17:42.129] | : . ' result() for ClusterFuture ... done [22:17:42.130] | : . ' signalConditions() ... [22:17:42.130] | : . ' , include = 'immediateCondition' [22:17:42.131] | : . ' , exclude = [22:17:42.131] | : . ' , resignal = FALSE [22:17:42.132] | : . ' , Number of conditions: 0 [22:17:42.132] | : . ' , int 0 [22:17:42.133] | : . ' signalConditions() ... done [22:17:42.134] | : . receiveMessageFromWorker() for ClusterFuture ... done [22:17:42.134] | : result() for ClusterFuture ... done [22:17:42.135] | : result() for ClusterFuture ... [22:17:42.135] | : . result already collected: FutureResult [22:17:42.136] | : result() for ClusterFuture ... done [22:17:42.136] | : signalConditions() ... [22:17:42.137] | : . include = 'immediateCondition' [22:17:42.137] | : . exclude = [22:17:42.138] | : . resignal = FALSE [22:17:42.138] | : . Number of conditions: 0 [22:17:42.139] | : . int 0 [22:17:42.140] | : signalConditions() ... done [22:17:42.140] | : relay stdout ... [22:17:42.141] | : relay stdout ... done [22:17:42.141] | : check for misuse ... [22:17:42.142] | : check for misuse ... done [22:17:42.142] | value() for Future () ... done new("standardGeneric", .Data = function (x) standardGeneric("my_fcn"), generic = "my_fcn", package = ".GlobalEnv", group = list(), valueClass = character(0), signature = "x", default = NULL, skeleton = (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", "my_fcn"), domain = NA))(x)) attr(,"generic") [1] "my_fcn" attr(,"generic")attr(,"package") [1] ".GlobalEnv" attr(,"package") [1] ".GlobalEnv" attr(,"group") list() attr(,"valueClass") character(0) attr(,"signature") [1] "x" attr(,"default") `\001NULL\001` attr(,"skeleton") (function (x) stop(gettextf("invalid call in method dispatch to '%s' (no default method)", "my_fcn"), domain = NA))(x) attr(,"class") [1] "standardGeneric" attr(,"class")attr(,"package") [1] "methods" [22:17:42.143] | future(..., label = NULL) ... [22:17:42.144] | : lazy: TRUE [22:17:42.144] | : stdout: TRUE [22:17:42.145] | : conditions: [n=1] 'condition' [22:17:42.145] | : getGlobalsAndPackages() ... [22:17:42.146] | : . Searching for globals ... [22:17:42.166] | : . ' globals found: [7] '{', 'my_fcn', 'standardGeneric', 'NULL', 'stop', 'gettextf', 'x' [22:17:42.166] | : . Searching for globals ... done [22:17:42.167] | : . Resolving globals: FALSE [22:17:42.167] | : . Search for packages associated with the globals ... [22:17:42.168] | : . ' Packages associated with globals: [1] 'base' [22:17:42.169] | : . ' Packages: [1] 'base' [22:17:42.169] | : . Search for packages associated with the globals ... done [22:17:42.170] | : . Packages after dropping 'base': [0] [22:17:42.170] | : . globals: [1] 'my_fcn' [22:17:42.171] | : . packages: [0] [22:17:42.171] | : getGlobalsAndPackages() ... done [22:17:42.172] | future(..., label = NULL) ... done [22:17:42.172] | value() for Future () ... [22:17:42.173] | : run() for 'Future' () ... [22:17:42.173] | : . state: 'created' [22:17:42.174] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.174] | : . plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.175] | : . Using MultisessionFutureBackend ... [22:17:42.175] | : . ' Number of futures since start: 1 (1 created, 1 launched, 1 finished) [22:17:42.176] | : . ' Launching futures ... [22:17:42.176] | : . ' , launchFuture() for ClusterFutureBackend ... [22:17:42.177] | : . ' , ; Workers: [n=2] [22:17:42.177] | : . ' , ; Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:17:42.179] | : . ' , ; FutureRegistry: 'workers-13da89b89e8f27ce5711ca0cc6a7227d' [22:17:42.180] | : . ' , ; requestWorker() ... [22:17:42.180] | : . ' , ; ` requestNode() ... [22:17:42.181] | : . ' , ; ` | Number of workers: 2 [22:17:42.181] | : . ' , ; ` | Polling for a free worker ... [22:17:42.182] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.182] | : . ' , ; ` | : . Listing all futures [22:17:42.183] | : . ' , ; ` | : . Number of registered futures: 0 [22:17:42.183] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.184] | : . ' , ; ` | : Total time: 0 [22:17:42.184] | : . ' , ; ` | Polling for a free worker ... done [22:17:42.185] | : . ' , ; ` | FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.185] | : . ' , ; ` | : Listing all futures [22:17:42.186] | : . ' , ; ` | : Number of registered futures: 0 [22:17:42.186] | : . ' , ; ` | FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.187] | : . ' , ; ` | avail: [n=2] '1', '2' [22:17:42.187] | : . ' , ; ` | Index of first available worker: 1 [22:17:42.188] | : . ' , ; ` | Validate that the worker is functional ... [22:17:42.188] | : . ' , ; ` | : Connection is valid [22:17:42.189] | : . ' , ; ` | : RichSOCKnode of a socket cluster on local host 'localhost' with pid 37628 (R Under development (unstable) (2026-03-12 r89611 ucrt), x86_64-w64-mingw32) using socket connection #4 ('<-CRANwin3.fb05.statistik.uni-dortmund.de:33637') [22:17:42.190] | : . ' , ; ` | : Worker is functional [22:17:42.191] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... [22:17:42.191] | : . ' , ; ` | : . Listing all futures [22:17:42.192] | : . ' , ; ` | : . Number of registered futures: 0 [22:17:42.192] | : . ' , ; ` | : FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'list', earlySignal = 0) ... done [22:17:42.193] | : . ' , ; ` | Validate that the worker is functional ... done [22:17:42.193] | : . ' , ; ` requestNode() ... done [22:17:42.194] | : . ' , ; ` cluster node index: 1 [22:17:42.194] | : . ' , ; requestWorker() ... done [22:17:42.195] | : . ' , ; eraseGlobalEnvironment() ... [22:17:42.196] | : . ' , ; eraseGlobalEnvironment() ... done [22:17:42.196] | : . ' , ; launchFuture() ... [22:17:42.197] | : . ' , ; ` cluster node index: 1 [22:17:42.197] | : . ' , ; ` getFutureData() ... [22:17:42.198] | : . ' , ; ` | getFutureCore() ... [22:17:42.198] | : . ' , ; ` | : Packages needed by the future expression (n = 0): [22:17:42.199] | : . ' , ; ` | getFutureCore() ... done [22:17:42.199] | : . ' , ; ` | getFutureCapture() ... [22:17:42.200] | : . ' , ; ` | getFutureCapture() ... done [22:17:42.200] | : . ' , ; ` | getFutureContext() ... [22:17:42.201] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.201] | : . ' , ; ` | : . Getting stack without first backend: [n=0] [22:17:42.202] | : . ' , ; ` | : plan("tail", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.202] | : . ' , ; ` | : Packages needed by future backend (n = 0): [22:17:42.203] | : . ' , ; ` | getFutureContext() ... done [22:17:42.203] | : . ' , ; ` getFutureData() ... done [22:17:42.204] | : . ' , ; ` FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'add', earlySignal = 0) ... [22:17:42.204] | : . ' , ; ` | Appended future to position #1 [22:17:42.205] | : . ' , ; ` | Number of registered futures: 1 [22:17:42.205] | : . ' , ; ` FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'add', earlySignal = 0) ... done [22:17:42.206] | : . ' , ; launchFuture() ... done [22:17:42.206] | : . ' , ; MultisessionFuture started [22:17:42.207] | : . ' , launchFuture() for ClusterFutureBackend ... done [22:17:42.207] | : . ' Launching futures ... done [22:17:42.208] | : . ' Future launched: 'MultisessionFuture', 'ClusterFuture', 'MultiprocessFuture', 'Future' [22:17:42.208] | : . Using MultisessionFutureBackend ... done [22:17:42.209] | : run() for 'Future' () ... done [22:17:42.209] | : result() for ClusterFuture ... [22:17:42.210] | : . assertValidConnection() ... [22:17:42.210] | : . ' cluster node index: 1 [22:17:42.211] | : . assertValidConnection() ... done [22:17:42.212] | : . receiveMessageFromWorker() for ClusterFuture ... [22:17:42.212] | : . ' cluster node index: 1 [22:17:42.213] | : . ' [1] TRUE [22:17:42.213] | : . ' Received data: [22:17:42.214] | : . ' List of 5 [22:17:42.214] | : . ' $ type : chr "VALUE" [22:17:42.214] | : . ' $ value :List of 16 [22:17:42.214] | : . ' ..$ value : num -3 [22:17:42.214] | : . ' ..$ visible : logi TRUE [22:17:42.214] | : . ' ..$ stdout : chr "" [22:17:42.214] | : . ' ..$ conditions : list() [22:17:42.214] | : . ' ..$ rng : logi FALSE [22:17:42.214] | : . ' ..$ seed : NULL [22:17:42.214] | : . ' ..$ misuseGlobalEnv : NULL [22:17:42.214] | : . ' ..$ misuseConnections :List of 3 [22:17:42.214] | : . ' .. ..$ added : NULL [22:17:42.214] | : . ' .. ..$ removed : NULL [22:17:42.214] | : . ' .. ..$ replaced: NULL [22:17:42.214] | : . ' ..$ misuseDevices : NULL [22:17:42.214] | : . ' ..$ misuseDefaultDevice: list() [22:17:42.214] | : . ' ..$ started : POSIXct[1:1], format: "2026-03-13 22:17:42" [22:17:42.214] | : . ' ..$ finished : POSIXct[1:1], format: "2026-03-13 22:17:42" [22:17:42.214] | : . ' ..$ uuid : chr [1:2] "c781ea6271e7fad647a923618f39d819" "4" [22:17:42.214] | : . ' ..$ session_uuid : chr "511225045ed279fcdc8b363420daca4d" [22:17:42.214] | : . ' .. ..- attr(*, "source")=List of 5 [22:17:42.214] | : . ' .. .. ..$ host : Named chr "CRANWIN3" [22:17:42.214] | : . ' .. .. .. ..- attr(*, "names")= chr "COMPUTERNAME" [22:17:42.214] | : . ' .. .. ..$ info : Named chr [1:9] "Windows" "Server x64" "build 20348" "CRANWIN3" ... [22:17:42.214] | : . ' .. .. .. ..- attr(*, "names")= chr [1:9] "sysname" "release" "version" "nodename" ... [22:17:42.214] | : . ' .. .. ..$ pid : int 37628 [22:17:42.214] | : . ' .. .. ..$ time : POSIXct[1:1], format: "2026-03-13 22:17:41" [22:17:42.214] | : . ' .. .. ..$ random: int 2147483647 [22:17:42.214] | : . ' ..$ r_info :List of 4 [22:17:42.214] | : . ' .. ..$ version :Classes 'R_system_version', 'package_version', 'numeric_version' hidden list of 1 [22:17:42.214] | : . ' .. .. ..$ : int [1:3] 4 6 0 [22:17:42.214] | : . ' .. ..$ os : chr "windows" [22:17:42.214] | : . ' .. ..$ os_name : chr "Windows" [22:17:42.214] | : . ' .. ..$ captures_utf8: logi TRUE [22:17:42.214] | : . ' ..$ version : chr "1.8" [22:17:42.214] | : . ' ..- attr(*, "class")= chr "FutureResult" [22:17:42.214] | : . ' $ success: logi TRUE [22:17:42.214] | : . ' $ time : 'proc_time' Named num [1:5] 0 0 0 NA NA [22:17:42.214] | : . ' ..- attr(*, "names")= chr [1:5] "user.self" "sys.self" "elapsed" "user.child" ... [22:17:42.214] | : . ' $ tag : NULL [22:17:42.228] | : . ' Received FutureResult [22:17:42.229] | : . ' FutureResult: [22:17:42.229] | : . ' value: 'numeric' [22:17:42.229] | : . ' visible: TRUE [22:17:42.229] | : . ' stdout: character [22:17:42.229] | : . ' conditions: [n = 0] [22:17:42.229] | : . ' RNG used: FALSE [22:17:42.229] | : . ' duration: 0.003027916 secs (started 2026-03-13 22:17:42.204493) [22:17:42.229] | : . ' version: 1.8 [22:17:42.230] | : . ' FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'remove', earlySignal = 0) ... [22:17:42.231] | : . ' , Removed future from position #1 [22:17:42.231] | : . ' , Number of registered futures: 0 [22:17:42.232] | : . ' FutureRegistry('workers-13da89b89e8f27ce5711ca0cc6a7227d', action = 'remove', earlySignal = 0) ... done [22:17:42.232] | : . ' Erased future from future backend [22:17:42.233] | : . ' result() for ClusterFuture ... [22:17:42.233] | : . ' , result already collected: FutureResult [22:17:42.234] | : . ' result() for ClusterFuture ... done [22:17:42.234] | : . ' signalConditions() ... [22:17:42.235] | : . ' , include = 'immediateCondition' [22:17:42.235] | : . ' , exclude = [22:17:42.235] | : . ' , resignal = FALSE [22:17:42.236] | : . ' , Number of conditions: 0 [22:17:42.236] | : . ' , int 0 [22:17:42.237] | : . ' signalConditions() ... done [22:17:42.238] | : . receiveMessageFromWorker() for ClusterFuture ... done [22:17:42.238] | : result() for ClusterFuture ... done [22:17:42.239] | : result() for ClusterFuture ... [22:17:42.239] | : . result already collected: FutureResult [22:17:42.240] | : result() for ClusterFuture ... done [22:17:42.240] | : signalConditions() ... [22:17:42.241] | : . include = 'immediateCondition' [22:17:42.241] | : . exclude = [22:17:42.242] | : . resignal = FALSE [22:17:42.242] | : . Number of conditions: 0 [22:17:42.243] | : . int 0 [22:17:42.243] | : signalConditions() ... done [22:17:42.244] | : relay stdout ... [22:17:42.244] | : relay stdout ... done [22:17:42.245] | : check for misuse ... [22:17:42.245] | : check for misuse ... done [22:17:42.246] | value() for Future () ... done [1] -3 future.globals.keepWhere=TRUE [22:17:42.247] | plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.247] | : plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... [22:17:42.248] | : . all.equal() for FutureStrategyList ... [22:17:42.248] | : . ' New stack: [22:17:42.249] | : . ' List of 1 [22:17:42.249] | : . ' $ :function (..., envir = parent.frame()) [22:17:42.249] | : . ' ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:42.249] | : . ' ..- attr(*, "init")= logi TRUE [22:17:42.249] | : . ' ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:42.249] | : . ' .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.249] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.249] | : . ' ..- attr(*, "call")= language plan(sequential) [22:17:42.249] | : . ' - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:42.252] | : . ' Old stack: [22:17:42.253] | : . ' List of 1 [22:17:42.253] | : . ' $ :function (..., workers = availableCores(constraints = "connections-16"), [22:17:42.253] | : . ' rscript_libs = .libPaths()) [22:17:42.253] | : . ' ..- attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:42.253] | : . ' ..- attr(*, "init")= chr "done" [22:17:42.253] | : . ' ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:42.253] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:42.253] | : . ' .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.253] | : . ' ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.253] | : . ' ..- attr(*, "untweakable")= chr "persistent" [22:17:42.253] | : . ' ..- attr(*, "call")= language plan(strategy) [22:17:42.253] | : . ' ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:17:42.253] | : . ' .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:42.253] | : . ' interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:42.253] | : . ' .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.253] | : . ' - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:42.257] | : . ' Not identical [22:17:42.258] | : . ' all.equal() for future ... [22:17:42.258] | : . ' , List of 2 [22:17:42.258] | : . ' , $ target :function (..., envir = parent.frame()) [22:17:42.258] | : . ' , ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:42.258] | : . ' , ..- attr(*, "init")= logi TRUE [22:17:42.258] | : . ' , ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:42.258] | : . ' , .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.258] | : . ' , ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.258] | : . ' , ..- attr(*, "call")= language plan(sequential) [22:17:42.258] | : . ' , $ current:function (..., workers = availableCores(constraints = "connections-16"), [22:17:42.258] | : . ' , rscript_libs = .libPaths()) [22:17:42.258] | : . ' , ..- attr(*, "class")= chr [1:5] "multisession" "cluster" "multiprocess" "future" ... [22:17:42.258] | : . ' , ..- attr(*, "init")= chr "done" [22:17:42.258] | : . ' , ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:42.258] | : . ' , interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:42.258] | : . ' , .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.258] | : . ' , ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.258] | : . ' , ..- attr(*, "untweakable")= chr "persistent" [22:17:42.258] | : . ' , ..- attr(*, "call")= language plan(strategy) [22:17:42.258] | : . ' , ..- attr(*, "backend")=Classes 'MultisessionFutureBackend', 'ClusterFutureBackend', 'MultiprocessFutureBackend', 'FutureBackend', 'environment' [22:17:42.258] | : . ' , .. ..- attr(*, "factory")=function (workers = availableCores(constraints = "connections-16"), rscript_libs = .libPaths(), [22:17:42.258] | : . ' , interrupts = TRUE, gc = FALSE, earlySignal = FALSE, ...) [22:17:42.258] | : . ' , .. .. ..- attr(*, "tweakable")= chr [1:11] "workers" "rscript_libs" "interrupts" "gc" ... [22:17:42.265] | : . ' , Formals differ [22:17:42.266] | : . ' all.equal() for future ... done [22:17:42.266] | : . ' Future strategies differ at level 1 [22:17:42.267] | : . all.equal() for FutureStrategyList ... done [22:17:42.267] | : . plan(): Setting new future backend stack: [22:17:42.268] | : . List of future strategies: [22:17:42.268] | : . 1. sequential: [22:17:42.268] | : . - args: function (..., envir = parent.frame(), workers = "") [22:17:42.268] | : . - tweaked: FALSE [22:17:42.268] | : . - call: plan(sequential) [22:17:42.269] | : . List of 1 [22:17:42.269] | : . $ :function (..., envir = parent.frame()) [22:17:42.269] | : . ..- attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:42.269] | : . ..- attr(*, "init")= logi TRUE [22:17:42.269] | : . ..- attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:42.269] | : . .. ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.269] | : . ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.269] | : . ..- attr(*, "call")= language plan(sequential) [22:17:42.269] | : . - attr(*, "class")= chr [1:2] "FutureStrategyList" "list" [22:17:42.272] | : . plan(): plan_cleanup(<'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... [22:17:42.273] | : . ' plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.273] | : . ' , Getting current ("next") strategy: 'FutureStrategy', 'multisession', 'cluster', 'multiprocess', 'future', 'function' [22:17:42.274] | : . ' plan("next", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.274] | : . ' stopWorkers() for MultisessionFutureBackend ... [22:17:42.275] | : . ' , Interrupt active futures ... [22:17:42.276] | : . ' , ; Number of futures: 0 [22:17:42.277] | : . ' , Interrupt active futures ... done [22:17:42.277] | : . ' , Clear future registry ... [22:17:42.278] | : . ' , Clear future registry ... done [22:17:42.279] | : . ' , Stop cluster workers ... [22:17:42.279] | : . ' , ; Stopping existing cluster ... [22:17:42.279] | : . ' , ; ` Cluster to shut down: [22:17:42.280] | : . ' , ; ` Socket cluster with 2 nodes on host 'localhost' (R Under development (unstable) (2026-03-12 r89611 ucrt), platform x86_64-w64-mingw32) [22:17:42.290] | : . ' , ; ` Stopped cluster: 'c(TRUE, TRUE)' [22:17:42.328] | : . ' , ; Stopping existing cluster ... done [22:17:42.329] | : . ' , Stop cluster workers ... done [22:17:42.330] | : . ' stopWorkers() for MultisessionFutureBackend ... done [22:17:42.330] | : . ' Legacy shutdown of cluster workers ... [22:17:42.331] | : . ' , Stopping existing cluster ... [22:17:42.331] | : . ' , ; No pre-existing cluster. Skipping [22:17:42.331] | : . ' , Stopping existing cluster ... done [22:17:42.332] | : . ' Legacy shutdown of cluster workers ... done [22:17:42.332] | : . plan(): plan_cleanup(<'multisession', 'cluster', 'multiprocess', 'future', 'function'>, cleanup = NA) ... done [22:17:42.333] | : . plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... [22:17:42.333] | : . ' function (..., envir = parent.frame()) [22:17:42.333] | : . ' - attr(*, "class")= chr [1:4] "sequential" "uniprocess" "future" "function" [22:17:42.333] | : . ' - attr(*, "init")= logi TRUE [22:17:42.333] | : . ' - attr(*, "factory")=function (..., maxSizeOfObjects = +Inf) [22:17:42.333] | : . ' ..- attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.333] | : . ' - attr(*, "tweakable")= chr [1:5] "maxSizeOfObjects" "earlySignal" "gc" "interrupts" ... [22:17:42.333] | : . ' - attr(*, "call")= language plan(sequential) [22:17:42.336] | : . ' init: TRUE [22:17:42.337] | : . ' makeFutureBackend() ... [22:17:42.338] | : . ' , Backend function: <'NULL'> [22:17:42.338] | : . ' , Evaluator tweak arguments: [n=0] [22:17:42.338] | : . ' , list() [22:17:42.339] | : . ' , Evaluator formal arguments: [n=1] [22:17:42.340] | : . ' , Dotted pair list of 1 [22:17:42.340] | : . ' , $ envir: language parent.frame() [22:17:42.341] | : . ' , Arguments passed to the future-backend factory: [n=1] [22:17:42.341] | : . ' , List of 1 [22:17:42.341] | : . ' , $ envir: language parent.frame() [22:17:42.343] | : . ' , Backend: <'SequentialFutureBackend', 'FutureBackend', 'environment'> [22:17:42.343] | : . ' makeFutureBackend() ... done [22:17:42.344] | : . plan(): plan_init() of 'sequential', 'uniprocess', 'future', 'function' ... done [22:17:42.344] | : . nbrOfWorkers(NULL) ... [22:17:42.345] | : . ' List of 4 [22:17:42.345] | : . ' $ : language plan_set(newStack, skip = .skip, cleanup = .cleanup, init = .init, debug = debug) [22:17:42.345] | : . ' $ : language with_assert({ nbrOfWorkers <- nbrOfWorkers() ... [22:17:42.345] | : . ' $ : language nbrOfWorkers() [22:17:42.345] | : . ' $ : language nbrOfWorkers.NULL() [22:17:42.347] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... [22:17:42.347] | : . ' plan("backend", .skip = FALSE, .cleanup = NA, .init = TRUE) ... done [22:17:42.348] | : . ' Number of workers: 1 [22:17:42.348] | : . nbrOfWorkers(NULL) ... done [22:17:42.349] | : . plan(): nbrOfWorkers() = 1 [22:17:42.349] | : plan(): plan_set(<1 strategies>, skip = FALSE, cleanup = NA, init = TRUE) ... done [22:17:42.350] | plan(<'name'>, .skip = FALSE, .cleanup = NA, .init = TRUE) ... done > message("*** Globals - S4 methods ... DONE") *** Globals - S4 methods ... DONE Failed to undo environment variables: - Expected environment variables: [n=218] '!ExitCode', 'ALLUSERSPROFILE', 'APPDATA', 'BIBINPUTS', 'BINDIR', 'BSTINPUTS', 'COMMONPROGRAMFILES', 'COMPUTERNAME', 'COMSPEC', 'CURL_CA_BUNDLE', 'CV_Instance001', 'CYGWIN', 'CommonProgramFiles(x86)', 'CommonProgramW6432', 'DriverData', 'HOME', 'HOMEDRIVE', 'HOMEPATH', 'JAGS_ROOT', 'JAVA_HOME', 'LANGUAGE', 'LC_COLLATE', 'LC_MONETARY', 'LC_TIME', 'LOCALAPPDATA', 'LOGONSERVER', 'LS_HOME', 'LS_LICENSE_PATH', 'MAKE', 'MAKEFLAGS', 'MAKELEVEL', 'MFLAGS', 'MSMPI_BENCHMARKS', 'MSMPI_BIN', 'MSYS2_ENV_CONV_EXCL', 'NUMBER_OF_PROCESSORS', 'OCL', 'OMP_THREAD_LIMIT', 'OS', 'PATH', 'PATHEXT', 'PROCESSOR_ARCHITECTURE', 'PROCESSOR_IDENTIFIER', 'PROCESSOR_LEVEL', 'PROCESSOR_REVISION', 'PROGRAMFILES', 'PROMPT', 'PSModulePath', 'PUBLIC', 'PWD', 'ProgramData', 'ProgramFiles(x86)', 'ProgramW6432', 'RTOOLS44_HOME', 'RTOOLS45_HOME', 'R_ARCH', 'R_BROWSER', 'R_BZIPCMD', 'R_CMD', 'R_COMPILED_BY', 'R_CRAN_WEB', 'R_CUSTOM_TOOLS_PATH', 'R_CUSTOM_TOOLS_SOFT', 'R_DOC_DIR', 'R_ENVIRON_USER', 'R_GSCMD', 'R_GZIPCMD', 'R_HOME', 'R_INCLUDE_DIR', 'R_INSTALL_TAR', 'R_LIBS', 'R_LIBS_SITE', 'R_LIBS_USER', 'R_MAX_NUM_DLLS', 'R_OSTYPE', 'R_PAPERSIZE', 'R_PAPERSIZE_USER', 'R_PARALLELLY_MAKENODEPSOCK_AUTOKILL', 'R_PARALLELLY_MAKENODEPSOCK_CONNECTTIMEOUT', 'R_PARALLELLY_MAKENODEPSOCK_RSCRIPT_LABEL', 'R_PARALLELLY_MAKENODEPSOCK_SESSIONINFO_PKGS', 'R_PARALLELLY_MAKENODEPSOCK_TIMEOUT', 'R_PARALLELLY_RANDOM_PORTS', 'R_PARALLEL_PORT', 'R_RD4PDF', 'R_RTOOLS45_PATH', 'R_SCRIPT_LEGACY', 'R_SHARE_DIR', 'R_TESTME_NAME', 'R_TESTME_PACKAGE', 'R_TESTME_PATH', 'R_TESTS', 'R_UNZIPCMD', 'R_USER', 'R_VERSION', 'R_ZIPCMD', 'SED', 'SHLVL', 'SYSTEMDRIVE', 'SYSTEMROOT', 'TAR', 'TAR_OPTIONS', 'TEMP', 'TERM', 'TETRAD_DIR', 'TEXINPUTS', 'TMP', 'TMPDIR', 'USERDOMAIN', 'USERDOMAIN_ROAMINGPROFILE', 'USERNAME', 'USERPROFILE', 'WINDIR', '_', '_R_CHECK_AUTOCONF_', '_R_CHECK_BOGUS_RETURN_', '_R_CHECK_BROWSER_NONINTERACTIVE_', '_R_CHECK_BUILD_VIGNETTES_SEPARATELY_', '_R_CHECK_CODETOOLS_PROFILE_', '_R_CHECK_CODE_ASSIGN_TO_GLOBALENV_', '_R_CHECK_CODE_ATTACH_', '_R_CHECK_CODE_CLASS_IS_STRING_', '_R_CHECK_CODE_DATA_INTO_GLOBALENV_', '_R_CHECK_CODE_USAGE_VIA_NAMESPACES_', '_R_CHECK_CODE_USAGE_WITHOUT_LOADING_', '_R_CHECK_CODE_USAGE_WITH_ONLY_BASE_ATTACHED_', '_R_CHECK_CODOC_VARIABLES_IN_USAGES_', '_R_CHECK_COMPACT_DATA2_', '_R_CHECK_COMPILATION_FLAGS_', '_R_CHECK_CONNECTIONS_LEFT_OPEN_', '_R_CHECK_CRAN_INCOMING_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_MAYBE_', '_R_CHECK_CRAN_INCOMING_ASPELL_RECHECK_START_', '_R_CHECK_CRAN_INCOMING_CHECK_FILE_URIS_', '_R_CHECK_CRAN_INCOMING_CHECK_URLS_IN_PARALLEL_', '_R_CHECK_CRAN_INCOMING_NOTE_GNU_MAKE_', '_R_CHECK_CRAN_INCOMING_REMOTE_', '_R_CHECK_CRAN_INCOMING_USE_ASPELL_', '_R_CHECK_DATALIST_', '_R_CHECK_DEPRECATED_DEFUNCT_', '_R_CHECK_DOC_SIZES2_', '_R_CHECK_DOT_FIRSTLIB_', '_R_CHECK_DOT_INTERNAL_', '_R_CHECK_EXAMPLE_TIMING_THRESHOLD_', '_R_CHECK_EXECUTABLES_', '_R_CHECK_EXECUTABLES_EXCLUSIONS_', '_R_CHECK_FF_CALLS_', '_R_CHECK_FF_DUP_', '_R_CHECK_FORCE_SUGGESTS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_', '_R_CHECK_FUTURE_FILE_TIMESTAMPS_LEEWAY_', '_R_CHECK_HAVE_MYSQL_', '_R_CHECK_HAVE_ODBC_', '_R_CHECK_HAVE_PERL_', '_R_CHECK_HAVE_POSTGRES_', '_R_CHECK_INSTALL_DEPENDS_', '_R_CHECK_INTERNALS2_', '_R_CHECK_LENGTH_1_CONDITION_', '_R_CHECK_LICENSE_', '_R_CHECK_LIMIT_CORES_', '_R_CHECK_LOG_USE_INFO_', '_R_CHECK_MATRIX_DATA_', '_R_CHECK_MBCS_CONVERSION_FAILURE_', '_R_CHECK_NATIVE_ROUTINE_REGISTRATION_', '_R_CHECK_NEWS_IN_PLAIN_TEXT_', '_R_CHECK_NO_RECOMMENDED_', '_R_CHECK_NO_STOP_ON_TEST_ERROR_', '_R_CHECK_ORPHANED_', '_R_CHECK_OVERWRITE_REGISTERED_S3_METHODS_', '_R_CHECK_PACKAGES_USED_IGNORE_UNUSED_IMPORTS_', '_R_CHECK_PACKAGES_USED_IN_TESTS_USE_SUBDIRS_', '_R_CHECK_PACKAGE_DATASETS_SUPPRESS_NOTES_', '_R_CHECK_PACKAGE_NAME_', '_R_CHECK_PKG_SIZES_', '_R_CHECK_PKG_SIZES_THRESHOLD_', '_R_CHECK_PRAGMAS_', '_R_CHECK_RCPP_NOT_NEEDED_', '_R_CHECK_RD_BIBENTRIES_CITED_NOT_SHOWN_', '_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_RELATIVE_PATHS_', '_R_CHECK_URLS_SHOW_301_STATUS_', '_R_CHECK_VC_DIRS_', '_R_CHECK_VIGNETTES_NLINES_', '_R_CHECK_VIGNETTES_SKIP_RUN_MAYBE_', '_R_CHECK_VIGNETTE_TIMING_', '_R_CHECK_VIGNETTE_TITLES_', '_R_CHECK_WINDOWS_DEVICE_', '_R_CHECK_XREFS_NOTE_MISSING_PACKAGE_ANCHORS_', '_R_CHECK_XREFS_USE_ALIASES_FROM_CRAN_', '_R_CLASS_MATRIX_ARRAY_', '_R_DEPRECATED_IS_R_', '_R_S3_METHOD_LOOKUP_BASEENV_AFTER_GLOBALENV_', '_R_SHLIB_BUILD_OBJECTS_SYMBOL_TABLES_', '_R_USE_STRICT_R_HEADERS_', '__R_CHECK_DOC_FILES_NOTE_IF_ALL_INTERNAL__', 'maj.version', 'nextArg--timingsnextArg--install', 'tempdirname' - Environment variables still there: [n=0] - Environment variables missing: [n=1] 'MAKEFLAGS' Differences environment variable by environment variable: Skipping, because path appears not to be an 'R CMD check' folder: 'D:/temp/2026_03_13_22_15_17_12234' Test time: user.self=0.9s, sys.self=0.04s, elapsed=2s, user.child=NAs, sys.child=NAs Test 'globals,S4methods' ... success > > proc.time() user system elapsed 1.20 0.10 1.98