Running ‘000.sessionDetails.R’ [1s/1s]
  Running ‘ClusterRegistry.R’ [2s/5s]
  Running ‘Future-class.R’ [1s/3s]
  Running ‘FutureError.R’ [1s/1s]
  Running ‘FutureGlobals.R’ [1s/1s]
  Running ‘FutureRegistry.R’ [1s/1s]
  Running ‘adhoc_native_to_utf8.R’ [1s/1s]
  Running ‘backtrace.R’ [2s/6s]
  Running ‘bquote.R’ [1s/1s]
  Running ‘cluster,worker-termination.R’ [1s/3s]
  Running ‘cluster-missing-future-pkg.R’ [1s/3s]
  Running ‘cluster.R’ [3s/13s]
  Running ‘constant.R’ [1s/1s]
  Running ‘demo.R’ [12s/16s]
  Running ‘dotdotdot.R’ [3s/6s]
  Running ‘early-signaling.R’ [2s/13s]
  Running ‘future,labels.R’ [2s/5s]
  Running ‘future,optsenvvars.R’ [1s/3s]
  Running ‘future.R’ [1s/1s]
  Running ‘futureAssign.R’ [2s/6s]
  Running ‘futureAssign_OP.R’ [4s/9s]
  Running ‘futureAssign_OP_with_environment.R’ [1s/1s]
  Running ‘futureAssign_OP_with_listenv.R’ [1s/1s]
  Running ‘futureCall.R’ [3s/8s]
  Running ‘futureOf.R’ [1s/1s]
  Running ‘futureOf_with_environment.R’ [1s/1s]
  Running ‘futureOf_with_listenv.R’ [1s/1s]
  Running ‘futureSessionInfo.R’ [1s/1s]
  Running ‘futures.R’ [10s/19s]
  Running ‘globals,NSE.R’ [2s/6s]
  Running ‘globals,S4methods.R’ [2s/4s]
  Running ‘globals,formulas.R’ [4s/9s]
  Running ‘globals,locals.R’ [2s/5s]
  Running ‘globals,manual.R’ [4s/10s]
  Running ‘globals,resolve.R’ [1s/3s]
  Running ‘globals,subassignment.R’ [4s/7s]
  Running ‘globals,toolarge.R’ [1s/3s]
  Running ‘globals,tricky.R’ [8s/16s]
  Running ‘globals,tricky_recursive.R’ [2s/4s]
  Running ‘globalsOf,tweaks.R’ [1s/1s]
  Running ‘immediateCondition.R’ [1s/5s]
  Running ‘invalid-owner.R’ [1s/2s]
  Running ‘mandelbrot.R’ [1s/1s]
  Running ‘mpi.R’ [1s/1s]
  Running ‘multicore,multithreading.R’ [1s/2s]
  Running ‘multicore,worker-termination.R’ [1s/1s]
  Running ‘multicore.R’ [3s/5s]
  Running ‘multiprocess.R’ [2s/2s]
  Running ‘multisession.R’ [2s/8s]
  Running ‘nbrOfWorkers.R’ [2s/7s]
  Running ‘nested_futures,mc.cores.R’ [3s/17s]
  Running ‘nested_futures.R’ [5s/15s]
  Running ‘non-exportable,connections.R’ [1s/3s]
  Running ‘objectSize.R’ [1s/1s]
  Running ‘plan.R’ [1s/5s]
  Running ‘relaying,muffle.R’ [1s/1s]
  Running ‘relaying,split.R’ [1s/1s]
  Running ‘relaying.R’ [3s/9s]
  Running ‘requestCore.R’ [1s/3s]
  Running ‘requestNode.R’ [1s/9s]
  Running ‘reserved-keyword-functions.R’ [1s/1s]
  Running ‘resolve.R’ [10s/35s]
  Running ‘resolved-non-blocking-test.R’ [2s/17s]
  Running ‘rng.R’ [15s/24s]
  Running ‘rng_utils.R’ [1s/1s]
  Running ‘sequential.R’ [1s/1s]
  Running ‘sessionDetails.R’ [1s/1s]
  Running ‘startup.R’ [2s/6s]
  Running ‘stdout.R’ [3s/7s]
  Running ‘tweak.R’ [1s/3s]
  Running ‘utils.R’ [1s/1s]
  Running ‘uuid.R’ [1s/2s]
  Running ‘whichIndex.R’ [1s/1s]
Running the tests in ‘tests/invalid-owner.R’ failed.
Complete output:
  > source("incl/start.R")
  [04:55:29.066] plan(): Setting new future strategy stack:
  [04:55:29.067] List of future strategies:
  [04:55:29.067] 1. sequential:
  [04:55:29.067]    - args: function (..., envir = parent.frame())
  [04:55:29.067]    - tweaked: FALSE
  [04:55:29.067]    - call: future::plan("sequential")
  [04:55:29.097] plan(): nbrOfWorkers() = 1
  > 
  > ## Local functions
  > usedNodes <- function(future) {
  +   ## Number of unresolved cluster futures
  +   workers <- future$workers
  +   reg <- sprintf("workers-%s", attr(workers, "name"))
  +   c(used = length(future:::FutureRegistry(reg, action = "list")), total = length(workers))
  + }
  > 
  > ## This test requires at least two background processes
  > plan(multisession, workers = 2L)
  [04:55:29.198] plan(): Setting new future strategy stack:
  [04:55:29.199] List of future strategies:
  [04:55:29.199] 1. multisession:
  [04:55:29.199]    - args: function (..., workers = 2L, envir = parent.frame())
  [04:55:29.199]    - tweaked: TRUE
  [04:55:29.199]    - call: plan(multisession, workers = 2L)
  [04:55:29.243] plan(): plan_init() of 'tweaked', 'multisession', 'cluster', 'multiprocess', 'future', 'function' ...
  [04:55:29.244] multisession:
  [04:55:29.244] - args: function (..., workers = 2L, envir = parent.frame())
  [04:55:29.244] - tweaked: TRUE
  [04:55:29.244] - call: plan(multisession, workers = 2L)
  [04:55:29.247] getGlobalsAndPackages() ...
  [04:55:29.248] Not searching for globals
  [04:55:29.249] - globals: [0] <none>
  [04:55:29.249] getGlobalsAndPackages() ... DONE
  [04:55:29.250] [local output] makeClusterPSOCK() ...
  [04:55:29.314] [local output] Workers: [n = 2] 'localhost', 'localhost'
  [04:55:29.330] [local output] Base port: 35384
  [04:55:29.331] [local output] Getting setup options for 2 cluster nodes ...
  [04:55:29.331] [local output]  - Node 1 of 2 ...
  [04:55:29.332] [local output] localMachine=TRUE => revtunnel=FALSE
  
  [04:55:29.334] Testing if worker's PID can be inferred: ''/home/hornik/tmp/R.check/r-patched-gcc/Work/build/bin/Rscript' -e 'try(suppressWarnings(cat(Sys.getpid(),file="/tmp/RtmpQOOtNl/worker.rank=1.parallelly.parent=3699777.38744156e13c3d.pid")), silent = TRUE)' -e 'file.exists("/tmp/RtmpQOOtNl/worker.rank=1.parallelly.parent=3699777.38744156e13c3d.pid")''
  [04:55:29.991] - Possible to infer worker's PID: TRUE
  [04:55:29.992] [local output] Rscript port: 35384
  
  [04:55:29.992] [local output]  - Node 2 of 2 ...
  [04:55:29.993] [local output] localMachine=TRUE => revtunnel=FALSE
  
  [04:55:29.996] [local output] Rscript port: 35384
  
  [04:55:29.997] [local output] Getting setup options for 2 cluster nodes ... done
  [04:55:29.997] [local output]  - Parallel setup requested for some PSOCK nodes
  [04:55:29.999] [local output] Setting up PSOCK nodes in parallel
  [04:55:29.999] List of 20
  [04:55:29.999]  $ local_cmd     : chr "'/home/hornik/tmp/R.check/r-patched-gcc/Work/build/bin/Rscript' --default-packages=datasets,utils,grDevices,gra"| __truncated__
  [04:55:29.999]  $ worker        : chr "localhost"
  [04:55:29.999]   ..- attr(*, "localhost")= logi TRUE
  [04:55:29.999]  $ rank          : int 1
  [04:55:29.999]  $ rshlogfile    : NULL
  [04:55:29.999]  $ port          : int 35384
  [04:55:29.999]  $ connectTimeout: num 120
  [04:55:29.999]  $ timeout       : num 120
  [04:55:29.999]  $ useXDR        : logi FALSE
  [04:55:29.999]  $ pidfile       : chr "/tmp/RtmpQOOtNl/worker.rank=1.parallelly.parent=3699777.38744156e13c3d.pid"
  [04:55:29.999]  $ setup_strategy: chr "parallel"
  [04:55:29.999]  $ outfile       : chr "/dev/null"
  [04:55:29.999]  $ rshcmd_label  : NULL
  [04:55:29.999]  $ rsh_call      : NULL
  [04:55:29.999]  $ cmd           : chr "'/home/hornik/tmp/R.check/r-patched-gcc/Work/build/bin/Rscript' --default-packages=datasets,utils,grDevices,gra"| __truncated__
  [04:55:29.999]  $ localMachine  : logi TRUE
  [04:55:29.999]  $ manual        : logi FALSE
  [04:55:29.999]  $ dryrun        : logi FALSE
  [04:55:29.999]  $ quiet         : logi FALSE
  [04:55:29.999]  $ rshcmd        : NULL
  [04:55:29.999]  $ revtunnel     : logi FALSE
  [04:55:29.999]  - attr(*, "class")= chr [1:2] "makeNodePSOCKOptions" "makeNodeOptions"
  [04:55:30.035] [local output] System call to launch all workers:
  [04:55:30.036] [local output] '/home/hornik/tmp/R.check/r-patched-gcc/Work/build/bin/Rscript' --default-packages=datasets,utils,grDevices,graphics,stats,methods -e '#label=invalid-owner.R:3699777:gimli:hornik' -e 'try(suppressWarnings(cat(Sys.getpid(),file="/tmp/RtmpQOOtNl/worker.rank=1.parallelly.parent=3699777.38744156e13c3d.pid")), silent = TRUE)' -e 'options(socketOptions = "no-delay")' -e '.libPaths(c("/tmp/RtmpZaSwBZ/RLIBS_3702945662905c","/home/hornik/tmp/R.check/r-patched-gcc/Work/build/library"))' -e 'workRSOCK <- tryCatch(parallel:::.workRSOCK, error=function(e) parallel:::.slaveRSOCK); workRSOCK()' MASTER=localhost PORT=35384 OUT=/dev/null TIMEOUT=120 XDR=FALSE SETUPTIMEOUT=120 SETUPSTRATEGY=parallel
  [04:55:30.036] [local output] Starting PSOCK main server
  Error in serverSocket(port = port) : 
    creation of server socket failed: port 35384 cannot be opened
  Calls: plan ... ClusterRegistry -> makeCluster -> makeClusterPSOCK -> serverSocket
  Execution halted