Running ‘000.sessionDetails.R’
  Running ‘ClusterRegistry.R’
  Running ‘Future-class.R’
  Running ‘FutureError.R’
  Running ‘FutureGlobals.R’
  Running ‘FutureRegistry.R’
  Running ‘adhoc_native_to_utf8.R’
  Running ‘backtrace.R’
  Running ‘bquote.R’
  Running ‘capture_journals.R’
  Running ‘cluster,worker-termination.R’
  Running ‘cluster-missing-future-pkg.R’
  Running ‘cluster.R’ [4s/16s]
  Running ‘constant.R’
  Running ‘demo.R’ [10s/17s]
  Running ‘dotdotdot.R’
  Running ‘early-signaling.R’ [2s/14s]
  Running ‘future,labels.R’
  Running ‘future,optsenvvars.R’
  Running ‘future.R’
  Running ‘futureAssign.R’
  Running ‘futureAssign_OP.R’
  Running ‘futureAssign_OP_with_environment.R’
  Running ‘futureAssign_OP_with_listenv.R’
  Running ‘futureCall.R’ [5s/13s]
  Running ‘futureOf.R’
  Running ‘futureOf_with_environment.R’
  Running ‘futureOf_with_listenv.R’
  Running ‘futureSessionInfo.R’
  Running ‘futures.R’ [16s/29s]
  Running ‘globals,NSE.R’ [4s/11s]
  Running ‘globals,S4methods.R’
  Running ‘globals,formulas.R’ [6s/11s]
  Running ‘globals,locals.R’
  Running ‘globals,manual.R’ [7s/16s]
  Running ‘globals,resolve.R’
  Running ‘globals,subassignment.R’
  Running ‘globals,toolarge.R’
  Running ‘globals,tricky.R’ [13s/25s]
  Running ‘globals,tricky_recursive.R’
  Running ‘globalsOf,tweaks.R’
  Running ‘immediateCondition.R’
  Running ‘invalid-owner.R’
  Running ‘mandelbrot.R’
  Running ‘mpi.R’
  Running ‘multicore,multithreading.R’
  Running ‘multicore,worker-termination.R’
  Running ‘multicore.R’
  Running ‘multisession.R’ [4s/12s]
  Running ‘nbrOfWorkers.R’ [3s/10s]
  Running ‘nested_futures,mc.cores.R’ [5s/22s]
  Running ‘nested_futures.R’ [8s/18s]
  Running ‘non-exportable,connections.R’
  Running ‘objectSize.R’
  Running ‘plan.R’
  Running ‘relaying,muffle.R’
  Running ‘relaying,split.R’
  Running ‘relaying.R’ [4s/11s]
  Running ‘requestCore.R’
  Running ‘requestNode.R’
  Running ‘reserved-keyword-functions.R’
  Running ‘resolve.R’ [15s/43s]
  Running ‘resolved-non-blocking-test.R’ [2s/19s]
  Running ‘rng.R’ [21s/29s]
  Running ‘rng_utils.R’
  Running ‘sequential.R’
  Running ‘sessionDetails.R’
  Running ‘startup.R’
  Running ‘stdout.R’
  Running ‘tweak.R’
  Running ‘utils.R’ [13s/15s]
  Running ‘uuid.R’
  Running ‘whichIndex.R’
Running the tests in ‘tests/capture_journals.R’ failed.
Complete output:
  > source("incl/start.R")
  [06:23:22.615] plan(): Setting new future strategy stack:
  [06:23:22.617] List of future strategies:
  [06:23:22.617] 1. sequential:
  [06:23:22.617]    - args: function (..., envir = parent.frame(), workers = "<NULL>")
  [06:23:22.617]    - tweaked: FALSE
  [06:23:22.617]    - call: future::plan("sequential")
  [06:23:22.667] plan(): nbrOfWorkers() = 1
  > 
  > capture_journals <- future:::capture_journals
  > 
  > message("*** capture_journals() ...")
  *** capture_journals() ...
  > 
  > slow_fcn <- function(x) {
  +   Sys.sleep(0.5 + 1/x)
  + }
  > 
  > plan(multisession, workers = 2)
  [06:23:22.733] plan(): Setting new future strategy stack:
  [06:23:22.733] List of future strategies:
  [06:23:22.733] 1. multisession:
  [06:23:22.733]    - args: function (..., workers = 2, envir = parent.frame())
  [06:23:22.733]    - tweaked: TRUE
  [06:23:22.733]    - call: plan(multisession, workers = 2)
  [06:23:22.783] plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ...
  [06:23:22.783] multisession:
  [06:23:22.783] - args: function (..., workers = 2, envir = parent.frame())
  [06:23:22.783] - tweaked: TRUE
  [06:23:22.783] - call: plan(multisession, workers = 2)
  [06:23:22.787] getGlobalsAndPackages() ...
  [06:23:22.788] Not searching for globals
  [06:23:22.788] - globals: [0] <none>
  [06:23:22.789] getGlobalsAndPackages() ... DONE
  [06:23:22.790] [local output] makeClusterPSOCK() ...
  [06:23:22.960] [local output] Workers: [n = 2] 'localhost', 'localhost'
  [06:23:22.978] [local output] Base port: 26961
  [06:23:22.979] [local output] Getting setup options for 2 cluster nodes ...
  [06:23:22.979] [local output]  - Node #1 of 2 ...
  [06:23:22.980] [local output] localMachine=TRUE => revtunnel=FALSE
  
  [06:23:22.983] Testing if worker's PID can be inferred: ''/data/gannet/ripley/R/R-devel/bin/Rscript' -e 'try(suppressWarnings(cat(Sys.getpid(),file="/tmp/RtmptUeVwp/working_dir/RtmpHYEsXh/worker.rank=1.parallelly.parent=3597189.36e3858b7e12c.pid")), silent = TRUE)' -e 'file.exists("/tmp/RtmptUeVwp/working_dir/RtmpHYEsXh/worker.rank=1.parallelly.parent=3597189.36e3858b7e12c.pid")''
  [06:23:23.708] - Possible to infer worker's PID: TRUE
  [06:23:23.709] [local output] Rscript port: 26961
  
  [06:23:23.710] [local output]  - Node #2 of 2 ...
  [06:23:23.712] [local output] localMachine=TRUE => revtunnel=FALSE
  
  [06:23:23.714] [local output] Rscript port: 26961
  
  [06:23:23.715] [local output] Getting setup options for 2 cluster nodes ... done
  [06:23:23.715] [local output]  - Parallel setup requested for some PSOCK nodes
  [06:23:23.717] [local output] Setting up PSOCK nodes in parallel
  [06:23:23.717] List of 36
  [06:23:23.717]  $ worker          : chr "localhost"
  [06:23:23.717]   ..- attr(*, "localhost")= logi TRUE
  [06:23:23.717]  $ master          : chr "localhost"
  [06:23:23.717]  $ port            : int 26961
  [06:23:23.717]  $ connectTimeout  : num 120
  [06:23:23.717]  $ timeout         : num 120
  [06:23:23.717]  $ rscript         : chr "'/data/gannet/ripley/R/R-devel/bin/Rscript'"
  [06:23:23.717]  $ homogeneous     : logi TRUE
  [06:23:23.717]  $ rscript_args    : chr "--default-packages=datasets,utils,grDevices,graphics,stats,methods -e '#label=capture_journals.R:3597189:gannet"| __truncated__
  [06:23:23.717]  $ rscript_envs    : NULL
  [06:23:23.717]  $ rscript_libs    : chr [1:4] "/data/gannet/ripley/R/packages/tests-devel/future.Rcheck" "/data/gannet/ripley/R/test-dev" "/data/gannet/ripley/R/test-BioCdata" "/data/gannet/ripley/R/R-devel/library"
  [06:23:23.717]  $ rscript_startup : NULL
  [06:23:23.717]  $ rscript_sh      : chr [1:2] "sh" "sh"
  [06:23:23.717]  $ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ...
  [06:23:23.717]  $ methods         : logi TRUE
  [06:23:23.717]  $ socketOptions   : chr "no-delay"
  [06:23:23.717]  $ useXDR          : logi FALSE
  [06:23:23.717]  $ outfile         : chr "/dev/null"
  [06:23:23.717]  $ renice          : int NA
  [06:23:23.717]  $ rshcmd          : NULL
  [06:23:23.717]  $ user            : chr(0) 
  [06:23:23.717]  $ revtunnel       : logi FALSE
  [06:23:23.717]  $ rshlogfile      : NULL
  [06:23:23.717]  $ rshopts         : chr(0) 
  [06:23:23.717]  $ rank            : int 1
  [06:23:23.717]  $ manual          : logi FALSE
  [06:23:23.717]  $ dryrun          : logi FALSE
  [06:23:23.717]  $ quiet           : logi FALSE
  [06:23:23.717]  $ setup_strategy  : chr "parallel"
  [06:23:23.717]  $ local_cmd       : chr "'/data/gannet/ripley/R/R-devel/bin/Rscript' --default-packages=datasets,utils,grDevices,graphics,stats,methods "| __truncated__
  [06:23:23.717]  $ pidfile         : chr "/tmp/RtmptUeVwp/working_dir/RtmpHYEsXh/worker.rank=1.parallelly.parent=3597189.36e3858b7e12c.pid"
  [06:23:23.717]  $ rshcmd_label    : NULL
  [06:23:23.717]  $ rsh_call        : NULL
  [06:23:23.717]  $ cmd             : chr "'/data/gannet/ripley/R/R-devel/bin/Rscript' --default-packages=datasets,utils,grDevices,graphics,stats,methods "| __truncated__
  [06:23:23.717]  $ localMachine    : logi TRUE
  [06:23:23.717]  $ make_fcn        :function (worker = getOption2("parallelly.localhost.hostname", "localhost"), 
  [06:23:23.717]     master = NULL, port, connectTimeout = getOption2("parallelly.makeNodePSOCK.connectTimeout", 
  [06:23:23.717]         2 * 60), timeout = getOption2("parallelly.makeNodePSOCK.timeout", 
  [06:23:23.717]         30 * 24 * 60 * 60), rscript = NULL, homogeneous = NULL, rscript_args = NULL, 
  [06:23:23.717]     rscript_envs = NULL, rscript_libs = NULL, rscript_startup = NULL, rscript_sh = c("auto", 
  [06:23:23.717]         "cmd", "sh", "none"), default_packages = c("datasets", "utils", 
  [06:23:23.717]         "grDevices", "graphics", "stats", if (methods) "methods"), methods = TRUE, 
  [06:23:23.717]     socketOptions = getOption2("parallelly.makeNodePSOCK.socketOptions", 
  [06:23:23.717]         "no-delay"), useXDR = getOption2("parallelly.makeNodePSOCK.useXDR", 
  [06:23:23.717]         FALSE), outfile = "/dev/null", renice = NA_integer_, rshcmd = getOption2("parallelly.makeNodePSOCK.rshcmd", 
  [06:23:23.717]         NULL), user = NULL, revtunnel = NA, rshlogfile = NULL, rshopts = getOption2("parallelly.makeNodePSOCK.rshopts", 
  [06:23:23.717]         NULL), rank = 1L, manual = FALSE, dryrun = FALSE, quiet = FALSE, 
  [06:23:23.717]     setup_strategy = getOption2("parallelly.makeNodePSOCK.setup_strategy", 
  [06:23:23.717]         "parallel"), action = c("launch", "options"), verbose = FALSE)  
  [06:23:23.717]  $ arguments       :List of 28
  [06:23:23.717]   ..$ worker          : chr "localhost"
  [06:23:23.717]   ..$ master          : NULL
  [06:23:23.717]   ..$ port            : int 26961
  [06:23:23.717]   ..$ connectTimeout  : num 120
  [06:23:23.717]   ..$ timeout         : num 120
  [06:23:23.717]   ..$ rscript         : NULL
  [06:23:23.717]   ..$ homogeneous     : NULL
  [06:23:23.717]   ..$ rscript_args    : NULL
  [06:23:23.717]   ..$ rscript_envs    : NULL
  [06:23:23.717]   ..$ rscript_libs    : chr [1:4] "/data/gannet/ripley/R/packages/tests-devel/future.Rcheck" "/data/gannet/ripley/R/test-dev" "/data/gannet/ripley/R/test-BioCdata" "/data/gannet/ripley/R/R-devel/library"
  [06:23:23.717]   ..$ rscript_startup : NULL
  [06:23:23.717]   ..$ rscript_sh      : chr "auto"
  [06:23:23.717]   ..$ default_packages: chr [1:6] "datasets" "utils" "grDevices" "graphics" ...
  [06:23:23.717]   ..$ methods         : logi TRUE
  [06:23:23.717]   ..$ socketOptions   : chr "no-delay"
  [06:23:23.717]   ..$ useXDR          : logi FALSE
  [06:23:23.717]   ..$ outfile         : chr "/dev/null"
  [06:23:23.717]   ..$ renice          : int NA
  [06:23:23.717]   ..$ rshcmd          : NULL
  [06:23:23.717]   ..$ user            : NULL
  [06:23:23.717]   ..$ revtunnel       : logi NA
  [06:23:23.717]   ..$ rshlogfile      : NULL
  [06:23:23.717]   ..$ rshopts         : NULL
  [06:23:23.717]   ..$ rank            : int 1
  [06:23:23.717]   ..$ manual          : logi FALSE
  [06:23:23.717]   ..$ dryrun          : logi FALSE
  [06:23:23.717]   ..$ quiet           : logi FALSE
  [06:23:23.717]   ..$ setup_strategy  : chr "parallel"
  [06:23:23.717]  - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions"
  [06:23:23.812] [local output] System call to launch all workers:
  [06:23:23.812] [local output] '/data/gannet/ripley/R/R-devel/bin/Rscript' --default-packages=datasets,utils,grDevices,graphics,stats,methods -e '#label=capture_journals.R:3597189:gannet.stats.ox.ac.uk:ripley' -e 'try(suppressWarnings(cat(Sys.getpid(),file="/tmp/RtmptUeVwp/working_dir/RtmpHYEsXh/worker.rank=1.parallelly.parent=3597189.36e3858b7e12c.pid")), silent = TRUE)' -e 'options(socketOptions = "no-delay")' -e '.libPaths(c("/data/gannet/ripley/R/packages/tests-devel/future.Rcheck","/data/gannet/ripley/R/test-dev","/data/gannet/ripley/R/test-BioCdata","/data/gannet/ripley/R/R-devel/library"))' -e 'workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()' MASTER=localhost PORT=26961 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel
  [06:23:23.813] [local output] Starting PSOCK main server
  Error in serverSocket(port = port) : 
    creation of server socket failed: port 26961 cannot be opened
  Calls: plan ... ClusterRegistry -> makeCluster -> makeClusterPSOCK -> serverSocket
  Execution halted