Skip to content

Instantly share code, notes, and snippets.

@Mark-Booth
Last active September 25, 2017 13:59
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save Mark-Booth/842b916c17702c18ee7eae9567911425 to your computer and use it in GitHub Desktop.
Save Mark-Booth/842b916c17702c18ee7eae9567911425 to your computer and use it in GitHub Desktop.
Effects of different build groups on eclipse scanning
travis_fold:start:worker_info
Worker information
hostname: f5a04d8c-fdfb-4325-b347-bbd8c94395bb@1.production-1-worker-org-c-6-gce
version: v3.0.2 https://github.com/travis-ci/worker/tree/f1c05caed79c66a9103f12a22e8a45ec66dbca64
instance: testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac:travis-ci-garnet-trusty-1499451966 (via amqp)
startup: 21.098226806s
travis_fold:end:worker_info
travis_fold:start:system_info
Build system information
Build language: java
Build group: deprecated-2017Q3
Build dist: trusty
Build id: 275898070
Job id: 275898073
Runtime kernel version: 4.4.0-83-generic
travis-build version: ec7da3751
Build image provisioning date and time
Fri Jul 7 18:51:38 UTC 2017
Operating System Details
Distributor ID: Ubuntu
Description: Ubuntu 14.04.5 LTS
Release: 14.04
Codename: trusty
Linux Version
4.4.0-81-generic
Cookbooks Version
15a6f94 https://github.com/travis-ci/travis-cookbooks/tree/15a6f94
git version
git version 2.13.0
bash version
GNU bash, version 4.3.11(1)-release (x86_64-pc-linux-gnu)
gcc version
gcc (Ubuntu 4.8.4-2ubuntu1~14.04.3) 4.8.4
Copyright (C) 2013 Free Software Foundation, Inc.
This is free software; see the source for copying conditions. There is NO
warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.
docker version
Client:
Version: 17.03.1-ce
API version: 1.27
Go version: go1.7.5
Git commit: c6d412e
Built: Mon Mar 27 17:10:36 2017
OS/Arch: linux/amd64
Server:
Version: 17.03.1-ce
API version: 1.27 (minimum version 1.12)
Go version: go1.7.5
Git commit: c6d412e
Built: Mon Mar 27 17:10:36 2017
OS/Arch: linux/amd64
Experimental: false
clang version
clang version 3.5.0 (tags/RELEASE_350/final)
Target: x86_64-unknown-linux-gnu
Thread model: posix
jq version
jq-1.5
bats version
Bats 0.4.0
shellcheck version
0.4.5
shfmt version
v1.0.0
ccache version
ccache version 3.1.9
Copyright (C) 2002-2007 Andrew Tridgell
Copyright (C) 2009-2011 Joel Rosdahl
This program is free software; you can redistribute it and/or modify it under
the terms of the GNU General Public License as published by the Free Software
Foundation; either version 3 of the License, or (at your option) any later
version.
cmake version
cmake version 3.2.2
CMake suite maintained and supported by Kitware (kitware.com/cmake).
heroku version
heroku-cli/6.12.5-17216bc (linux-x64) node-v8.1.3
imagemagick version
Version: ImageMagick 6.7.7-10 2017-05-26 Q16 http://www.imagemagick.org
md5deep version
4.2
mercurial version
Mercurial Distributed SCM (version 4.2.2)
(see https://mercurial-scm.org for more information)
Copyright (C) 2005-2017 Matt Mackall and others
This is free software; see the source for copying conditions. There is NO
warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.
mysql version
mysql Ver 14.14 Distrib 5.6.33, for debian-linux-gnu (x86_64) using EditLine wrapper
openssl version
OpenSSL 1.0.2j 26 Sep 2016
packer version
Packer v0.10.1
Your version of Packer is out of date! The latest version
is 1.0.2. You can update by downloading from www.packer.io
postgresql client version
psql (PostgreSQL) 9.6.3
ragel version
Ragel State Machine Compiler version 6.8 Feb 2013
Copyright (c) 2001-2009 by Adrian Thurston
subversion version
svn, version 1.8.8 (r1568071)
compiled Aug 20 2015, 12:51:30 on x86_64-pc-linux-gnu
Copyright (C) 2013 The Apache Software Foundation.
This software consists of contributions made by many people;
see the NOTICE file for more information.
Subversion is open source software, see http://subversion.apache.org/
The following repository access (RA) modules are available:
* ra_svn : Module for accessing a repository using the svn network protocol.
- with Cyrus SASL authentication
- handles 'svn' scheme
* ra_local : Module for accessing a repository on local disk.
- handles 'file' scheme
* ra_serf : Module for accessing a repository via WebDAV protocol using serf.
- using serf 1.3.3
- handles 'http' scheme
- handles 'https' scheme
sudo version
Sudo version 1.8.9p5
Configure options: --prefix=/usr -v --with-all-insults --with-pam --with-fqdn --with-logging=syslog --with-logfac=authpriv --with-env-editor --with-editor=/usr/bin/editor --with-timeout=15 --with-password-timeout=0 --with-passprompt=[sudo] password for %p: --without-lecture --with-tty-tickets --disable-root-mailer --enable-admin-flag --with-sendmail=/usr/sbin/sendmail --with-timedir=/var/lib/sudo --mandir=/usr/share/man --libexecdir=/usr/lib/sudo --with-sssd --with-sssd-lib=/usr/lib/x86_64-linux-gnu --with-selinux
Sudoers policy plugin version 1.8.9p5
Sudoers file grammar version 43
Sudoers path: /etc/sudoers
Authentication methods: 'pam'
Syslog facility if syslog is being used for logging: authpriv
Syslog priority to use when user authenticates successfully: notice
Syslog priority to use when user authenticates unsuccessfully: alert
Send mail if the user is not in sudoers
Use a separate timestamp for each user/tty combo
Lecture user the first time they run sudo
Root may run sudo
Allow some information gathering to give useful error messages
Require fully-qualified hostnames in the sudoers file
Visudo will honor the EDITOR environment variable
Set the LOGNAME and USER environment variables
Length at which to wrap log file lines (0 for no wrap): 80
Authentication timestamp timeout: 15.0 minutes
Password prompt timeout: 0.0 minutes
Number of tries to enter a password: 3
Umask to use or 0777 to use user's: 022
Path to mail program: /usr/sbin/sendmail
Flags for mail program: -t
Address to send mail to: root
Subject line for mail messages: *** SECURITY information for %h ***
Incorrect password message: Sorry, try again.
Path to authentication timestamp dir: /var/lib/sudo
Default password prompt: [sudo] password for %p:
Default user to run commands as: root
Value to override user's $PATH with: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin
Path to the editor for use by visudo: /usr/bin/editor
When to require a password for 'list' pseudocommand: any
When to require a password for 'verify' pseudocommand: all
File descriptors >= 3 will be closed before executing a command
Environment variables to check for sanity:
TZ
TERM
LINGUAS
LC_*
LANGUAGE
LANG
COLORTERM
Environment variables to remove:
RUBYOPT
RUBYLIB
PYTHONUSERBASE
PYTHONINSPECT
PYTHONPATH
PYTHONHOME
TMPPREFIX
ZDOTDIR
READNULLCMD
NULLCMD
FPATH
PERL5DB
PERL5OPT
PERL5LIB
PERLLIB
PERLIO_DEBUG
JAVA_TOOL_OPTIONS
SHELLOPTS
GLOBIGNORE
PS4
BASH_ENV
ENV
TERMCAP
TERMPATH
TERMINFO_DIRS
TERMINFO
_RLD*
LD_*
PATH_LOCALE
NLSPATH
HOSTALIASES
RES_OPTIONS
LOCALDOMAIN
CDPATH
IFS
Environment variables to preserve:
JAVA_HOME
TRAVIS
CI
DEBIAN_FRONTEND
XAUTHORIZATION
XAUTHORITY
PS2
PS1
PATH
LS_COLORS
KRB5CCNAME
HOSTNAME
HOME
DISPLAY
COLORS
Locale to use while parsing sudoers: C
Directory in which to store input/output logs: /var/log/sudo-io
File in which to store the input/output log: %{seq}
Add an entry to the utmp/utmpx file when allocating a pty
PAM service name to use
PAM service name to use for login shells
Create a new PAM session for the command to run in
Maximum I/O log sequence number: 0
Local IP address and netmask pairs:
10.240.0.5/255.255.255.255
172.17.0.1/255.255.0.0
Sudoers I/O plugin version 1.8.9p5
gzip version
gzip 1.6
Copyright (C) 2007, 2010, 2011 Free Software Foundation, Inc.
Copyright (C) 1993 Jean-loup Gailly.
This is free software. You may redistribute copies of it under the terms of
the GNU General Public License <http://www.gnu.org/licenses/gpl.html>.
There is NO WARRANTY, to the extent permitted by law.
Written by Jean-loup Gailly.
zip version
Copyright (c) 1990-2008 Info-ZIP - Type 'zip "-L"' for software license.
This is Zip 3.0 (July 5th 2008), by Info-ZIP.
Currently maintained by E. Gordon. Please send bug reports to
the authors using the web page at www.info-zip.org; see README for details.
Latest sources and executables are at ftp://ftp.info-zip.org/pub/infozip,
as of above date; see http://www.info-zip.org/ for other sites.
Compiled with gcc 4.8.2 for Unix (Linux ELF) on Oct 21 2013.
Zip special compilation options:
USE_EF_UT_TIME (store Universal Time)
BZIP2_SUPPORT (bzip2 library version 1.0.6, 6-Sept-2010)
bzip2 code and library copyright (c) Julian R Seward
(See the bzip2 license for terms of use)
SYMLINK_SUPPORT (symbolic links supported)
LARGE_FILE_SUPPORT (can read and write large files on file system)
ZIP64_SUPPORT (use Zip64 to store large files in archives)
UNICODE_SUPPORT (store and read UTF-8 Unicode paths)
STORE_UNIX_UIDs_GIDs (store UID/GID sizes/values using new extra field)
UIDGID_NOT_16BIT (old Unix 16-bit UID/GID extra field not used)
[encryption, version 2.91 of 05 Jan 2007] (modified for Zip 3)
Encryption notice:
The encryption code of this program is not copyrighted and is
put in the public domain. It was originally written in Europe
and, to the best of our knowledge, can be freely distributed
in both source and object forms from any country, including
the USA under License Exception TSU of the U.S. Export
Administration Regulations (section 740.13(e)) of 6 June 2002.
Zip environment options:
ZIP: [none]
ZIPOPT: [none]
vim version
VIM - Vi IMproved 7.4 (2013 Aug 10, compiled Nov 24 2016 16:43:18)
Included patches: 1-52
Extra patches: 8.0.0056
Modified by pkg-vim-maintainers@lists.alioth.debian.org
Compiled by buildd@
Huge version without GUI. Features included (+) or not (-):
+acl +farsi +mouse_netterm +syntax
+arabic +file_in_path +mouse_sgr +tag_binary
+autocmd +find_in_path -mouse_sysmouse +tag_old_static
-balloon_eval +float +mouse_urxvt -tag_any_white
-browse +folding +mouse_xterm -tcl
++builtin_terms -footer +multi_byte +terminfo
+byte_offset +fork() +multi_lang +termresponse
+cindent +gettext -mzscheme +textobjects
-clientserver -hangul_input +netbeans_intg +title
-clipboard +iconv +path_extra -toolbar
+cmdline_compl +insert_expand -perl +user_commands
+cmdline_hist +jumplist +persistent_undo +vertsplit
+cmdline_info +keymap +postscript +virtualedit
+comments +langmap +printer +visual
+conceal +libcall +profile +visualextra
+cryptv +linebreak +python +viminfo
+cscope +lispindent -python3 +vreplace
+cursorbind +listcmds +quickfix +wildignore
+cursorshape +localmap +reltime +wildmenu
+dialog_con -lua +rightleft +windows
+diff +menu -ruby +writebackup
+digraphs +mksession +scrollbind -X11
-dnd +modify_fname +signs -xfontset
-ebcdic +mouse +smartindent -xim
+emacs_tags -mouseshape -sniff -xsmp
+eval +mouse_dec +startuptime -xterm_clipboard
+ex_extra +mouse_gpm +statusline -xterm_save
+extra_search -mouse_jsbterm -sun_workshop -xpm
system vimrc file: "$VIM/vimrc"
user vimrc file: "$HOME/.vimrc"
2nd user vimrc file: "~/.vim/vimrc"
user exrc file: "$HOME/.exrc"
fall-back for $VIM: "/usr/share/vim"
Compilation: gcc -c -I. -Iproto -DHAVE_CONFIG_H -g -O2 -fstack-protector --param=ssp-buffer-size=4 -Wformat -Werror=format-security -U_FORTIFY_SOURCE -D_FORTIFY_SOURCE=1
Linking: gcc -Wl,-Bsymbolic-functions -Wl,-z,relro -Wl,--as-needed -o vim -lm -ltinfo -lnsl -lselinux -lacl -lattr -lgpm -ldl -L/usr/lib/python2.7/config-x86_64-linux-gnu -lpython2.7 -lpthread -ldl -lutil -lm -Xlinker -export-dynamic -Wl,-O1 -Wl,-Bsymbolic-functions
iptables version
iptables v1.4.21
curl version
curl 7.35.0 (x86_64-pc-linux-gnu) libcurl/7.35.0 OpenSSL/1.0.1f zlib/1.2.8 libidn/1.28 librtmp/2.3
wget version
GNU Wget 1.15 built on linux-gnu.
rsync version
rsync version 3.1.0 protocol version 31
gimme version
v1.0.0
nvm version
0.33.0
perlbrew version
/home/travis/perl5/perlbrew/bin/perlbrew - App::perlbrew/0.80
phpenv version
rbenv 1.1.1-2-g615f844
rvm version
rvm 1.29.2 (latest) by Michal Papis, Piotr Kuczynski, Wayne E. Seguin [https://rvm.io/]
default ruby version
ruby 2.4.1p111 (2017-03-22 revision 58053) [x86_64-linux]
CouchDB version
couchdb 1.6.1
ElasticSearch version
5.4.3
Installed Firefox version
firefox 50.0.2
MongoDB version
MongoDB 3.2.15
Pre-installed PostgreSQL versions
9.2.21
9.3.17
9.4.12
9.5.7
9.6.3
RabbitMQ Version
3.6.10
Redis version
redis-server 3.2.9
riak version
2.2.3
Pre-installed Go versions
1.7.4
ant version
Apache Ant(TM) version 1.9.3 compiled on April 8 2014
mvn version
Apache Maven 3.3.9 (bb52d8502b132ec0a5a3f4c09453c07478323dc5; 2015-11-10T16:41:47+00:00)
Maven home: /usr/local/maven-3.3.9
Java version: 1.8.0_131, vendor: Oracle Corporation
Java home: /usr/lib/jvm/java-8-oracle/jre
Default locale: en_US, platform encoding: UTF-8
OS name: "linux", version: "4.4.0-81-generic", arch: "amd64", family: "unix"
gradle version
------------------------------------------------------------
Gradle 3.2.1
------------------------------------------------------------
Build time: 2016-11-22 15:19:54 UTC
Revision: 83b485b914fd4f335ad0e66af9d14aad458d2cc5
Groovy: 2.4.7
Ant: Apache Ant(TM) version 1.9.6 compiled on June 29 2015
JVM: 1.8.0_131 (Oracle Corporation 25.131-b11)
OS: Linux 4.4.0-81-generic amd64
lein version
WARNING: You're currently running as root; probably by accident.
Press control-C to abort or Enter to continue as root.
Set LEIN_ROOT to disable this warning.
Leiningen 2.7.1 on Java 1.8.0_131 Java HotSpot(TM) 64-Bit Server VM
Pre-installed Node.js versions
v4.8.3
v6.11.0
v6.9.4
v7.4
v7.4.0
phpenv versions
system
5.6
* 5.6.24 (set by /home/travis/.phpenv/version)
7.0
7.0.7
hhvm
hhvm-stable
composer --version
Composer version 1.2.0 2016-07-19 01:28:52
Pre-installed Ruby versions
ruby-2.2.7
ruby-2.3.4
ruby-2.4.1
travis_fold:end:system_info

W: http://dl.hhvm.com/ubuntu/dists/trusty/InRelease: Signature by key 36AEF64D0207E7EEE352D4875A16E7281BE7A449 uses weak digest algorithm (SHA1)
W: http://ppa.launchpad.net/couchdb/stable/ubuntu/dists/trusty/Release.gpg: Signature by key 15866BAFD9BCC4F3C1E0DFC7D69548E1C17EAB57 uses weak digest algorithm (SHA1)
$ jdk_switcher use oraclejdk8
Switching to Oracle JDK8 (java-8-oracle), JAVA_HOME will be set to /usr/lib/jvm/java-8-oracle
travis_fold:start:git.checkout
travis_time:start:05778b1b
$ git clone --depth=50 https://github.com/eclipse/scanning.git eclipse/scanning
Cloning into 'eclipse/scanning'...
travis_time:end:05778b1b:start=1505488455175929191,finish=1505488456683223239,duration=1507294048
$ cd eclipse/scanning
travis_time:start:2c1dcf59
$ git fetch origin +refs/pull/225/merge:
From https://github.com/eclipse/scanning
* branch refs/pull/225/merge -> FETCH_HEAD
travis_time:end:2c1dcf59:start=1505488456690574705,finish=1505488457120005633,duration=429430928
$ git checkout -qf FETCH_HEAD
travis_fold:end:git.checkout
travis_fold:start:services
travis_time:start:0987d4c0
$ sudo service docker start
start: Job is already running: docker
travis_time:end:0987d4c0:start=1505488457216173629,finish=1505488457231832033,duration=15658404
travis_fold:end:services

Setting environment variables from repository settings
$ export SONAR_TOKEN=[secure]
$ export SONAR_GITHUB_TOKEN=[secure]
$ export GITHUB_READ_REPO=[secure]
Setting environment variables from .travis.yml
$ export CLEAR_TMP=true
$ export LD_LIBRARY_PATH=/home/travis/build/dawn-hdf/hdf.hdf5lib/lib/linux-x86_64
Disabling Gradle daemon
travis_time:start:1c0db24b
$ mkdir -p ~/.gradle && echo "org.gradle.daemon=false" >> ~/.gradle/gradle.properties
travis_time:end:1c0db24b:start=1505488460246893510,finish=1505488460254405047,duration=7511537
$ export PATH=$JAVA_HOME/bin:$PATH
travis_fold:start:cache.1
Setting up build cache
$ export CASHER_DIR=$HOME/.casher
travis_time:start:139c480e
$ Installing caching utilities
travis_time:end:139c480e:start=1505488463777817901,finish=1505488463916123075,duration=138305174
travis_time:start:01d8027e

travis_time:end:01d8027e:start=1505488463921476004,finish=1505488463925005269,duration=3529265
travis_time:start:208d03d6
attempting to download cache archive
fetching PR.225/cache-linux-trusty-c8278a81e364d1134ac0e76f8ca54631baa064cceb8b737445e3b3db2ca48e5d--jdk-oraclejdk8.tgz
fetching PR.225/cache--jdk-oraclejdk8.tgz
fetching master/cache-linux-trusty-c8278a81e364d1134ac0e76f8ca54631baa064cceb8b737445e3b3db2ca48e5d--jdk-oraclejdk8.tgz
found cache
travis_time:end:208d03d6:start=1505488463929523968,finish=1505488469415428783,duration=5485904815
travis_time:start:0fb70d03

travis_time:end:0fb70d03:start=1505488469420572326,finish=1505488469424470976,duration=3898650
travis_time:start:1358a1fb
adding /home/travis/build/eclipse/scanning/.autoconf to cache
creating directory /home/travis/build/eclipse/scanning/.autoconf
adding /home/travis/.m2 to cache
travis_time:end:1358a1fb:start=1505488469429216163,finish=1505488472744522405,duration=3315306242
travis_fold:end:cache.1
$ java -Xmx32m -version
java version "1.8.0_131"
Java(TM) SE Runtime Environment (build 1.8.0_131-b11)
Java HotSpot(TM) 64-Bit Server VM (build 25.131-b11, mixed mode)
$ javac -J-Xmx32m -version
javac 1.8.0_131
travis_fold:start:before_install.1
travis_time:start:0e406944
$ /sbin/start-stop-daemon --start --quiet --pidfile /tmp/custom_xvfb_99.pid --make-pidfile --background --exec /usr/bin/Xvfb -- :99 -ac -screen 0 1280x1024x16
travis_time:end:0e406944:start=1505488473452707581,finish=1505488473457999942,duration=5292361
travis_fold:end:before_install.1
travis_fold:start:before_install.2
travis_time:start:1260ed7c
$ chmod +x ./org.eclipse.scanning.releng/build/commit-msg.sh
travis_time:end:1260ed7c:start=1505488473464017696,finish=1505488473469927496,duration=5909800
travis_fold:end:before_install.2
travis_fold:start:before_install.3
travis_time:start:05a8ac2e
$ bash ./org.eclipse.scanning.releng/build/commit-msg.sh
The last commit was: Merge d7de7b64c552be18078be224004d546aa35c325f into bc7ce4cd725307f4b6f23b654a2989c6a497392d
Jira ticket or merge found, build may proceed
travis_time:end:05a8ac2e:start=1505488473475508230,finish=1505488473484998398,duration=9490168
travis_fold:end:before_install.3
travis_fold:start:before_install.4
travis_time:start:2ba1d398
$ git clone --depth=50 --branch=master https://github.com/eclipse/richbeans.git ../../eclipse/org.eclipse.richbeans
Cloning into '../../eclipse/org.eclipse.richbeans'...
travis_time:end:2ba1d398:start=1505488473490763187,finish=1505488474365894776,duration=875131589
travis_fold:end:before_install.4
travis_fold:start:before_install.5
travis_time:start:07341ede
$ git clone --depth=50 --branch=master https://github.com/eclipse/dawnsci.git ../../eclipse/org.eclipse.dawnsci
Cloning into '../../eclipse/org.eclipse.dawnsci'...
travis_time:end:07341ede:start=1505488474373283630,finish=1505488481615299597,duration=7242015967
travis_fold:end:before_install.5
travis_fold:start:before_install.6
travis_time:start:0fd2a6ee
$ git clone --depth=50 --branch=master https://github.com/DawnScience/dawn-hdf.git ../../dawn-hdf
Cloning into '../../dawn-hdf'...
travis_time:end:0fd2a6ee:start=1505488481621702291,finish=1505488485628364077,duration=4006661786
travis_fold:end:before_install.6
travis_fold:start:before_install.7
travis_time:start:03063480
$ git clone --depth=50 --branch=gda-9.5 https://github.com/openGDA/diamond-jython.git ../../diamond-jython
Cloning into '../../diamond-jython'...
travis_time:end:03063480:start=1505488485635136693,finish=1505488490891515149,duration=5256378456
travis_fold:end:before_install.7
travis_fold:start:install.1
travis_time:start:3a93779c
$ cd ../../eclipse
travis_time:end:3a93779c:start=1505488490898658744,finish=1505488490903321032,duration=4662288
travis_fold:end:install.1
travis_fold:start:install.2
travis_time:start:0b616165
$ mv ../eclipse/scanning org.eclipse.scanning
travis_time:end:0b616165:start=1505488490909294207,finish=1505488490914651505,duration=5357298
travis_fold:end:install.2
travis_fold:start:install.3
travis_time:start:08911521
$ cd org.eclipse.scanning
travis_time:end:08911521:start=1505488490920491804,finish=1505488490925169669,duration=4677865
travis_fold:end:install.3
travis_fold:start:sonarcloud.addon
SonarCloud addon
addon hash: 23baed938124b62a45592fb9dfa3cc87
Preparing SonarQube Scanner CLI
Archive: /home/travis/.sonarscanner/sonar-scanner.zip
creating: /home/travis/.sonarscanner/sonar-scanner-2.8/
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-scanner.bat
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-scanner-debug
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/lib/sonar-scanner-cli-2.8.jar
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-scanner
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/conf/sonar-scanner.properties
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-runner.bat
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-runner
inflating: /home/travis/.sonarscanner/sonar-scanner-2.8/bin/sonar-scanner-debug.bat
$ export SONAR_SCANNER_HOME=$HOME/.sonarscanner/sonar-scanner-2.8
Not installing SonarSource build-wrapper because it's a Java or Javascript project
Preparing SonarQube Scanner parameters
travis_fold:end:sonarcloud.addon
travis_fold:start:before_script.1
travis_time:start:011729fa
$ export DISPLAY=:99.0
travis_time:end:011729fa:start=1505488491058773648,finish=1505488491064555493,duration=5781845
travis_fold:end:before_script.1
travis_fold:start:before_script.2
travis_time:start:1efedcb5
$ sh -e /etc/init.d/xvfb start
Starting virtual X frame buffer: Xvfb.
travis_time:end:1efedcb5:start=1505488491072045216,finish=1505488491087766200,duration=15720984
travis_fold:end:before_script.2
travis_fold:start:before_script.3
travis_time:start:07e12998
$ sleep 3
travis_time:end:07e12998:start=1505488491093692885,finish=1505488494099218796,duration=3005525911
travis_fold:end:before_script.3
travis_time:start:04e90f7d
$ mvn -T 4 -q clean install
travis_time:end:04e90f7d:start=1505488494105274066,finish=1505488547584420047,duration=53479145981

The command "mvn -T 4 -q clean install" exited with 0.
travis_time:start:1df24cc6
$ mvn -q surefire:test -Dtest.includes=org/eclipse/scanning/**/Suite.java
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.eclipse.scanning.test.points.Suite
Starting generator service
Tests run: 183, Failures: 0, Errors: 0, Skipped: 10, Time elapsed: 27.689 sec - in org.eclipse.scanning.test.points.Suite
Running org.eclipse.scanning.test.filter.Suite
Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.726 sec - in org.eclipse.scanning.test.filter.Suite
Running org.eclipse.scanning.test.remote.Suite
Clearing /tmp of old nexus files.
Create Services
Started IMarshallerService
Started ActivemqConnectorService
Started IEventService
Starting device service
Set connectors
Made Servlets
Clearing /tmp of old nexus files.
Create Services
Set connectors
Made Servlets
Moving to 300.0 from 295.0
Moving to 295.0 from 300.0
Tests run: 12, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 8.726 sec - in org.eclipse.scanning.test.remote.Suite
Running org.eclipse.scanning.test.stashing.Suite
Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.102 sec - in org.eclipse.scanning.test.stashing.Suite
Running org.eclipse.scanning.test.event.Suite
{"@type":"CompoundModel","models":[{"@type":"SpiralModel","name":"Fermat Spiral","fastAxisName":"x","slowAxisName":"y","boundingBox":{"@type":"BoundingBox","fastAxisName":"stage_x","fastAxisStart":0.0,"fastAxisLength":10.0,"slowAxisName":"stage_y","slowAxisStart":-5.0,"slowAxisLength":5.0},"scale":1.0}],"regions":[{"@type":"ScanRegion","roi":{"@type":"roi.linear","length":2.8284271247461903,"angle":0.7853981633974483,"point":[1.0,2.0]},"scannables":["x","y"]}],"duration":-1.0}
Clearing /tmp of old nexus files.
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166)
at java.lang.Thread.run(Thread.java:748)
15:17:03.728 ERROR o.e.scanning.event.SubscriberImpl - RuntimeException occured despatching event [Submitter despatch thread org.eclipse.scanning.submission.queue]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296) ~[bin/:na]
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166)
at java.lang.Thread.run(Thread.java:748)
15:17:03.742 ERROR o.e.scanning.event.SubscriberImpl - RuntimeException occured despatching event [Submitter despatch thread org.eclipse.scanning.submission.queue]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296) ~[bin/:na]
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166)
at java.lang.Thread.run(Thread.java:748)
15:17:03.743 ERROR o.e.scanning.event.SubscriberImpl - RuntimeException occured despatching event [Submitter despatch thread org.eclipse.scanning.submission.queue]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296) ~[bin/:na]
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166)
at java.lang.Thread.run(Thread.java:748)
15:17:03.744 ERROR o.e.scanning.event.SubscriberImpl - RuntimeException occured despatching event [Submitter despatch thread org.eclipse.scanning.submission.queue]
java.lang.RuntimeException: Test exception
at org.eclipse.scanning.test.event.AbstractScanEventTest$7.scanStateChanged(AbstractScanEventTest.java:296) ~[bin/:na]
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:415) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$4.diseminate(SubscriberImpl.java:297) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:230) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.SubscriberImpl$3.run(SubscriberImpl.java:166) ~[org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131]
Clearing /tmp of old nexus files.
Test Consumer is paused
Removed ActiveMQTextMessage {commandId = 5, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-237:1:1:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-237:1:1:1, destination = queue://org.eclipse.scanning.submission.queue, transactionId = null, expiration = 0, timestamp = 1505488627726, arrival = 0, brokerInTime = 1505488627726, brokerOutTime = 1505488627833, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@2d3c501b, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"236be1aa-01...05488627724}}
Removed ActiveMQTextMessage {commandId = 5, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-239:1:1:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-239:1:1:1, destination = queue://org.eclipse.scanning.submission.queue, transactionId = null, expiration = 0, timestamp = 1505488627746, arrival = 0, brokerInTime = 1505488627746, brokerOutTime = 1505488627852, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@38b54694, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"2794dca2-8b...05488627746}}
Test Consumer running
Creating process for name = Submission1 id = 2794dca2-8b20-4f32-9ea7-832ebd0f0703
Removed ActiveMQTextMessage {commandId = 113, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-257:1:2:11:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-257:1:2:11, destination = queue://org.eclipse.scanning.status.set, transactionId = null, expiration = 0, timestamp = 1505488629423, arrival = 0, brokerInTime = 1505488629423, brokerOutTime = 1505488629446, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@2ed7978c, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"2794dca2-8b...05488627746}}
Removed ActiveMQTextMessage {commandId = 19, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-235:1:2:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-235:1:2:1, destination = queue://org.eclipse.scanning.command.set, transactionId = null, expiration = 0, timestamp = 1505488628413, arrival = 0, brokerInTime = 1505488628413, brokerOutTime = 1505488629467, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@15ad5acb, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"PauseBean","uniqueId":"658b98e6-26f...ause":false}}
Creating process for name = Submission0 id = 236be1aa-018a-48aa-a547-8ac66d3990af
Removed ActiveMQTextMessage {commandId = 259, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-257:1:2:25:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-257:1:2:25, destination = queue://org.eclipse.scanning.status.set, transactionId = null, expiration = 0, timestamp = 1505488631588, arrival = 0, brokerInTime = 1505488631588, brokerOutTime = 1505488631618, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@2d2a8819, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"236be1aa-01...05488627724}}
Creating process for name = Test id = b9bbf695-e4af-41c1-ba29-c37f7bcc1754
Test Consumer is paused
Test Consumer running
Removed ActiveMQTextMessage {commandId = 63, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-285:1:2:6:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-285:1:2:6, destination = queue://org.eclipse.scanning.status.set, transactionId = null, expiration = 0, timestamp = 1505488632106, arrival = 0, brokerInTime = 1505488632106, brokerOutTime = 1505488632180, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@51fb5fe6, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"b9bbf695-e4...05488631630}}
Removed ActiveMQTextMessage {commandId = 19, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-287:1:2:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-287:1:2:1, destination = queue://org.eclipse.scanning.command.set, transactionId = null, expiration = 0, timestamp = 1505488632064, arrival = 0, brokerInTime = 1505488632065, brokerOutTime = 1505488632201, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@3cb49121, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"PauseBean","uniqueId":"9c499333-b15...ause":false}}
Creating process for name = Test id = 6111057c-924d-4759-ac89-13f8b586cd22
Test Consumer is paused
Test Consumer running
Removed ActiveMQTextMessage {commandId = 63, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-315:1:2:6:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-315:1:2:6, destination = queue://org.eclipse.scanning.status.set, transactionId = null, expiration = 0, timestamp = 1505488634817, arrival = 0, brokerInTime = 1505488634818, brokerOutTime = 1505488634895, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@c6c84fa, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"StatusBean","uniqueId":"6111057c-92...05488634366}}
Removed ActiveMQTextMessage {commandId = 19, responseRequired = true, messageId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-317:1:2:1:1, originalDestination = null, originalTransactionId = null, producerId = ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-317:1:2:1, destination = queue://org.eclipse.scanning.command.set, transactionId = null, expiration = 0, timestamp = 1505488634785, arrival = 0, brokerInTime = 1505488634785, brokerOutTime = 1505488634942, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@4c02899, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false, jmsXGroupFirstForConsumer = false, text = {"@type":"PauseBean","uniqueId":"26cb22f3-b82...ause":false}}
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Encountered 19 beats
15:17:25.095 WARN o.e.scanning.event.PublisherImpl - Event publisher heartbeat connection to tcp://localhost:8713 lost. [Alive Notification org.eclipse.scanning.alive.topic ]
15:17:25.096 WARN o.e.scanning.event.PublisherImpl - We will check every 2 seconds for 24 hours, until it comes back. [Alive Notification org.eclipse.scanning.alive.topic ]
15:17:26.112 WARN o.e.scanning.event.PublisherImpl - Event publisher heartbeat connection to tcp://localhost:8713 lost. [Alive Notification org.eclipse.scanning.alive.topic ]
15:17:26.114 WARN o.e.scanning.event.PublisherImpl - We will check every 2 seconds for 24 hours, until it comes back. [Alive Notification org.eclipse.scanning.alive.topic ]
Clearing /tmp of old nexus files.
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
org.eclipse.scanning.api.event.EventException: There is no created device called 'fred'
at org.eclipse.scanning.api.device.DeviceRequestHandler.processRunnables(DeviceRequestHandler.java:174)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:84)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:1)
at org.eclipse.scanning.event.ResponderImpl$1.beanChangePerformed(ResponderImpl.java:59)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:409)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$6.diseminate(SubscriberImpl.java:325)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:227)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$2.run(SubscriberImpl.java:143)
org.eclipse.scanning.api.event.EventException: There is no created device called 'fred'
at org.eclipse.scanning.api.device.DeviceRequestHandler.processRunnables(DeviceRequestHandler.java:174)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:84)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:1)
at org.eclipse.scanning.event.ResponderImpl$1.beanChangePerformed(ResponderImpl.java:59)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:409)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$6.diseminate(SubscriberImpl.java:325)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:227)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$2.run(SubscriberImpl.java:143)
Setting malcolm device state to: Ready
org.eclipse.scanning.api.malcolm.MalcolmDeviceException: No such attribute: unknown
at org.eclipse.scanning.example.malcolm.DummyMalcolmDevice.getAttribute(DummyMalcolmDevice.java:810)
at org.eclipse.scanning.api.device.DeviceRequestHandler.addDeviceInformationAndAttributes(DeviceRequestHandler.java:245)
at org.eclipse.scanning.api.device.DeviceRequestHandler.processRunnables(DeviceRequestHandler.java:205)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:84)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:1)
at org.eclipse.scanning.event.ResponderImpl$1.beanChangePerformed(ResponderImpl.java:59)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:409)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$6.diseminate(SubscriberImpl.java:325)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:227)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$2.run(SubscriberImpl.java:143)
org.eclipse.scanning.api.malcolm.MalcolmDeviceException: No such attribute: unknown
at org.eclipse.scanning.example.malcolm.DummyMalcolmDevice.getAttribute(DummyMalcolmDevice.java:810)
at org.eclipse.scanning.api.device.DeviceRequestHandler.addDeviceInformationAndAttributes(DeviceRequestHandler.java:245)
at org.eclipse.scanning.api.device.DeviceRequestHandler.processRunnables(DeviceRequestHandler.java:205)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:84)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:1)
at org.eclipse.scanning.event.ResponderImpl$1.beanChangePerformed(ResponderImpl.java:59)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:409)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$6.diseminate(SubscriberImpl.java:325)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:227)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$2.run(SubscriberImpl.java:143)
org.eclipse.scanning.api.malcolm.MalcolmDeviceException: No such attribute: unknown
at org.eclipse.scanning.example.malcolm.DummyMalcolmDevice.getAttribute(DummyMalcolmDevice.java:810)
at org.eclipse.scanning.api.device.DeviceRequestHandler.addDeviceInformationAndAttributes(DeviceRequestHandler.java:245)
at org.eclipse.scanning.api.device.DeviceRequestHandler.processRunnables(DeviceRequestHandler.java:205)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:84)
at org.eclipse.scanning.api.device.DeviceRequestHandler.process(DeviceRequestHandler.java:1)
at org.eclipse.scanning.event.ResponderImpl$1.beanChangePerformed(ResponderImpl.java:59)
at org.eclipse.scanning.event.SubscriberImpl.execute(SubscriberImpl.java:409)
at org.eclipse.scanning.event.SubscriberImpl.access$9(SubscriberImpl.java:406)
at org.eclipse.scanning.event.SubscriberImpl$6.diseminate(SubscriberImpl.java:325)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:252)
at org.eclipse.scanning.event.SubscriberImpl.diseminate(SubscriberImpl.java:227)
at org.eclipse.scanning.event.SubscriberImpl.access$7(SubscriberImpl.java:225)
at org.eclipse.scanning.event.SubscriberImpl$2.run(SubscriberImpl.java:143)
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Clearing /tmp of old nexus files.
Starting NeXus File Factory
Writing to file /tmp/acquire_servlet_test4069602572376808049.nxs
HDF5 plugin directory found in library path
15:17:29.111 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [Submitter despatch thread org.eclipse.scanning.submission.queue]
15:17:29.112 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [Submitter despatch thread org.eclipse.scanning.submission.queue]
Tests run: 48, Failures: 0, Errors: 0, Skipped: 4, Time elapsed: 29.492 sec - in org.eclipse.scanning.test.event.Suite
Running org.eclipse.scanning.test.event.queues.Suite
15:17:31.230 ERROR o.e.s.e.r._QueueControllerService - Failed remove DummyAtom 'Carlos'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.231 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.231 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyBean) not supported by queue mock-active-queue [MainThread]
^---- Expected exception
15:17:31.231 ERROR o.e.s.e.r._QueueControllerService - Failed remove DummyAtom 'Xavier'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.232 ERROR o.e.s.e.r._QueueControllerService - DummyBean 'Albert' is already terminated [MainThread]
^---- Expected exception
15:17:31.232 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.233 ERROR o.e.s.e.r._QueueControllerService - Could not get state of DummyAtom 'Xavier' in queue. Is it in queue 'mock-active-queue'? Bean not found [MainThread]
^---- Expected exception
15:17:31.233 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.236 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.236 ERROR o.e.s.e.r._QueueControllerService - Failed reordering DummyAtom 'Xavier'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.237 ERROR o.e.s.e.r._QueueControllerService - DummyBean 'Albert' is already paused [MainThread]
^---- Expected exception
15:17:31.238 ERROR o.e.s.e.r._QueueControllerService - DummyBean 'Albert' is already resumed [MainThread]
^---- Expected exception
15:17:31.238 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.238 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyBean) not supported by queue mock-active-queue [MainThread]
^---- Expected exception
15:17:31.238 ERROR o.e.s.e.r._QueueControllerService - Could not get state of DummyAtom 'Xavier' in queue. Is it in queue 'mock-active-queue'? Bean not found [MainThread]
^---- Expected exception
15:17:31.238 ERROR o.e.s.e.r._QueueControllerService - Bean type (DummyAtom) not supported by queue mock-queue-root.job-queue [MainThread]
^---- Expected exception
Created IQueueControllerService
Created IQueueService
15:17:31.249 ERROR o.e.s.e.q.QueueControllerService - Failed remove DummyAtom 'Carlos'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.250 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.251 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyBean) not supported by queue test-queue-root.aq-0-921.active-queue [MainThread]
^---- Expected exception
15:17:31.252 ERROR o.e.s.e.q.QueueControllerService - Failed remove DummyAtom 'Xavier'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.253 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-921.active-queue is not active. [MainThread]
15:17:31.263 ERROR o.e.s.e.q.QueueControllerService - DummyBean 'Albert' is already terminated [MainThread]
^---- Expected exception
15:17:31.264 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.272 ERROR o.e.s.e.q.QueueControllerService - Could not get state of DummyAtom 'Xavier' in queue. Is it in queue 'test-queue-root.aq-0-802.active-queue'? Bean not found [MainThread]
^---- Expected exception
15:17:31.272 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.272 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-802.active-queue is not active. [MainThread]
15:17:31.273 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-716.active-queue is not active. [MainThread]
15:17:31.274 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.274 ERROR o.e.s.e.q.QueueControllerService - Failed reordering DummyAtom 'Xavier'. Is it in the status set already? [MainThread]
^---- Expected exception
15:17:31.274 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-850.active-queue is not active. [MainThread]
15:17:31.275 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-189.active-queue is not active. [MainThread]
15:17:31.289 ERROR o.e.s.e.q.QueueControllerService - DummyBean 'Albert' is already paused [MainThread]
^---- Expected exception
15:17:31.290 ERROR o.e.s.e.q.QueueControllerService - DummyBean 'Albert' is already resumed [MainThread]
^---- Expected exception
15:17:31.290 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.290 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyBean) not supported by queue test-queue-root.aq-0-294.active-queue [MainThread]
^---- Expected exception
15:17:31.291 ERROR o.e.s.e.q.QueueControllerService - Could not get state of DummyAtom 'Xavier' in queue. Is it in queue 'test-queue-root.aq-0-294.active-queue'? Bean not found [MainThread]
^---- Expected exception
15:17:31.291 ERROR o.e.s.e.q.QueueControllerService - Bean type (DummyAtom) not supported by queue test-queue-root.job-queue [MainThread]
^---- Expected exception
15:17:31.291 WARN o.e.s.event.queues.QueueService - Active-queue test-queue-root.aq-0-294.active-queue is not active. [MainThread]
15:17:31.336 ERROR o.e.s.e.queues.QueueProcessFactory - No processor registered for bean type 'org.eclipse.scanning.test.event.queues.dummy.DummyHasQueue' [MainThread]
^---- Expected exception
15:17:31.373 WARN o.e.s.event.queues.QueueService - Active-queue org.eclipse.scanning.event.queues.service.aq-0-731.active-queue already stopped. [MainThread]
^---- Expected exception
15:17:31.375 ERROR o.e.s.event.queues.QueueService - Could not start the QueueService - service has not been initialised. [MainThread]
^---- Expected exception
15:17:31.375 WARN o.e.s.event.queues.QueueService - Queue service has already been disposed or was never initialised. [MainThread]
15:17:31.375 WARN o.e.s.event.queues.QueueService - Active-queue org.eclipse.scanning.event.queues.service.aq-0-344.active-queue is not active. [MainThread]
Tests run: 31, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.162 sec - in org.eclipse.scanning.test.event.queues.Suite
Running org.eclipse.scanning.test.event.queues.spooler.Suite
15:17:31.431 ERROR o.e.s.e.q.spooler.QueueBeanFactory - Cannot register QueueValue. The reference 'QueueValue [name=null, value=homePosition, reference=true]' is already registered. [MainThread]
15:17:31.431 ERROR o.e.s.e.q.spooler.QueueBeanFactory - No global value with the short name QueueValue [name=null, value=locVal, reference=true] found in registry. [MainThread]
15:17:31.434 ERROR o.e.s.e.q.spooler.QueueBeanFactory - No QueueAtom with the short name QueueValue [name=null, value=setDetX, reference=true] found in registry. [MainThread]
15:17:31.434 ERROR o.e.s.e.q.spooler.QueueBeanFactory - No QueueAtom with the short name QueueValue [name=null, value=mvDum, reference=true] found in registry. [MainThread]
15:17:31.436 ERROR o.e.s.e.q.spooler.QueueBeanFactory - Cannot register PositionerAtom. The reference 'QueueValue [name=null, value=testAtom, reference=true]' is already registered. [MainThread]
15:17:31.449 ERROR o.e.s.e.q.s.b.ScanAtomAssembler - Both stored and experiment models configure 'stage_x'. Cannot specify multiple configurations for same device [MainThread]
Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.072 sec - in org.eclipse.scanning.test.event.queues.spooler.Suite
Running org.eclipse.scanning.test.event.queues.beans.Suite
Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.059 sec - in org.eclipse.scanning.test.event.queues.beans.Suite
Running org.eclipse.scanning.test.event.queues.api.Suite
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 sec - in org.eclipse.scanning.test.event.queues.api.Suite
Running org.eclipse.scanning.test.event.queues.processes.Suite
INFO: Sleeping for 750ms to give the processor time to run...
15:17:31.549 ERROR o.e.s.e.q.p.MonitorAtomProcess - Failed to get monitor with the name 'null': Invalid scannable null [MonitorAtom 'Error Causer' processing thread]
15:17:31.551 ERROR o.e.s.e.q.processes.QueueProcess - Processing MonitorAtom 'Error Causer' failed with: 'Failed to get monitor with the name 'null'' [MonitorAtom 'Error Causer' processing thread]
15:17:31.552 ERROR o.e.s.e.q.processes.QueueProcess - 'Error Causer' failed. Last message was: 'Processing MonitorAtom 'Error Causer' failed with: 'Failed to get monitor with the name 'null''' [Thread-53]
INFO: Sleeping for 750ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
15:17:38.119 ERROR o.e.s.e.q.processes.QueueProcess - 'Test queue sub task bean' failed. Last message was: 'Waiting for active queue to complete' [Thread-61]
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
INFO: Sleeping for 50ms to give the processor time to run...
Tests run: 22, Failures: 0, Errors: 0, Skipped: 2, Time elapsed: 6.957 sec - in org.eclipse.scanning.test.event.queues.processes.Suite
Running org.eclipse.scanning.test.annot.Suite
Duplicated types are not allowed in injected methods!
Your annotation of @PointEnd sits over a method 'repeatedTypes2' on class 'RepeatedTypeDevice' with duplicated types!
More than one of any given type is not allowed. Have you seen 'ScanInformation' class, which can be used to provide various metrics about the scan?
Each cycle took 1ms. We ran '1000' devices with '1000' cycles.
Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.162 sec - in org.eclipse.scanning.test.annot.Suite
Running org.eclipse.scanning.test.scan.Suite
Clearing /tmp of old nexus files.
Positioning 100,000 test motor with 100 levels took 10486 ms
Next position is [x=0, y=0, step=-1]
Next position is [x=0, y=0, step=-1]
Next position is [x=1, y=1, step=-1]
Sleeping for 200 while we get to it.
Next position is [x=2, y=2, step=-1]
Next position is [x=3, y=3, step=-1]
Sleeping for 400 while we get to it.
Next position is [x=4, y=4, step=-1]
Topup stopped
Before = 74
After = 74
Tolerance = 3
Starting Parser Service
Started Parser Service
Clearing /tmp of old nexus files.
>> testLinearScanNexusMediumWithEvents
File writing to /tmp/test_mandel_nexus2460501043157718781.nxs
Scanning to test_mandel_nexus2460501043157718781.nxs
Wrote dataset of shape: [257, 1, 1, 256, 256]
Checking that 1 points take 200ms or less to run. Using 1 tries.
File writing to /tmp/test_mandel_nexus2460501043157718781.nxs
Scanning to test_mandel_nexus2460501043157718781.nxs
1 point(s) took 63ms with detector exposure set to 0.0s
That's 63ms per point
Wrote dataset of shape: [2, 1, 1, 256, 256]
Checking that 10 points take 1830ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus2460501043157718781.nxs
Scanning to test_mandel_nexus2460501043157718781.nxs
10 point(s) took 145ms with detector exposure set to 0.0s
That's 14ms per point
Wrote dataset of shape: [11, 1, 1, 256, 256]
Checking that 100 points take 2650ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus2460501043157718781.nxs
Scanning to test_mandel_nexus2460501043157718781.nxs
100 point(s) took 1342ms with detector exposure set to 0.0s
That's 13ms per point
Wrote dataset of shape: [101, 1, 1, 256, 256]
>> done
Checking that 1 points take 200ms or less to run. Using 1 tries.
1 point(s) took 16ms with detector exposure set to 0.001s
That's 16ms per point
Checking that 10 points take 1360ms or less to run. Using 10 tries.
10 point(s) took 42ms with detector exposure set to 0.001s
That's 4ms per point
Checking that 100 points take 1620ms or less to run. Using 10 tries.
100 point(s) took 181ms with detector exposure set to 0.001s
That's 1ms per point
>> testLinearScanNexusSmallWithEvents
File writing to /tmp/test_mandel_nexus964510320996194980.nxs
Scanning to test_mandel_nexus964510320996194980.nxs
Wrote dataset of shape: [257, 1, 1, 64, 64]
Checking that 1 points take 200ms or less to run. Using 1 tries.
File writing to /tmp/test_mandel_nexus964510320996194980.nxs
Scanning to test_mandel_nexus964510320996194980.nxs
1 point(s) took 45ms with detector exposure set to 0.0s
That's 45ms per point
Wrote dataset of shape: [2, 1, 1, 64, 64]
Checking that 10 points take 1650ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus964510320996194980.nxs
Scanning to test_mandel_nexus964510320996194980.nxs
10 point(s) took 80ms with detector exposure set to 0.0s
That's 8ms per point
Wrote dataset of shape: [11, 1, 1, 64, 64]
Checking that 100 points take 2000ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus964510320996194980.nxs
Scanning to test_mandel_nexus964510320996194980.nxs
100 point(s) took 354ms with detector exposure set to 0.0s
That's 3ms per point
Wrote dataset of shape: [101, 1, 1, 64, 64]
>> done
>> testLinearScanNexusSmall
File writing to /tmp/test_mandel_nexus8627839603748166421.nxs
Scanning to test_mandel_nexus8627839603748166421.nxs
Wrote dataset of shape: [257, 1, 1, 64, 64]
Checking that 1 points take 100ms or less to run. Using 1 tries.
File writing to /tmp/test_mandel_nexus8627839603748166421.nxs
Scanning to test_mandel_nexus8627839603748166421.nxs
1 point(s) took 21ms with detector exposure set to 0.0s
That's 21ms per point
Wrote dataset of shape: [2, 1, 1, 64, 64]
Checking that 10 points take 1410ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus8627839603748166421.nxs
Scanning to test_mandel_nexus8627839603748166421.nxs
10 point(s) took 54ms with detector exposure set to 0.0s
That's 5ms per point
Wrote dataset of shape: [11, 1, 1, 64, 64]
Checking that 100 points take 1740ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus8627839603748166421.nxs
Scanning to test_mandel_nexus8627839603748166421.nxs
100 point(s) took 283ms with detector exposure set to 0.0s
That's 2ms per point
Wrote dataset of shape: [101, 1, 1, 64, 64]
>> done
>> testLinearScanNexusMedium
File writing to /tmp/test_mandel_nexus8653723971368977093.nxs
Scanning to test_mandel_nexus8653723971368977093.nxs
Wrote dataset of shape: [257, 1, 1, 256, 256]
Checking that 1 points take 100ms or less to run. Using 1 tries.
File writing to /tmp/test_mandel_nexus8653723971368977093.nxs
Scanning to test_mandel_nexus8653723971368977093.nxs
1 point(s) took 33ms with detector exposure set to 0.0s
That's 33ms per point
Wrote dataset of shape: [2, 1, 1, 256, 256]
Checking that 10 points take 1530ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus8653723971368977093.nxs
Scanning to test_mandel_nexus8653723971368977093.nxs
10 point(s) took 195ms with detector exposure set to 0.0s
That's 19ms per point
Wrote dataset of shape: [11, 1, 1, 256, 256]
Checking that 100 points take 3150ms or less to run. Using 10 tries.
File writing to /tmp/test_mandel_nexus8653723971368977093.nxs
Scanning to test_mandel_nexus8653723971368977093.nxs
100 point(s) took 965ms with detector exposure set to 0.0s
That's 9ms per point
Wrote dataset of shape: [101, 1, 1, 256, 256]
>> done
File writing to /tmp/test_abort_nexus2125443962216164600.nxs
Running acquisition scan of size 40
File writing to /tmp/test_abort_nexus7452393642123065086.nxs
File writing to /tmp/test_abort_nexus2940844282677158053.nxs
Running acquisition scan of size 40
Clearing /tmp of old nexus files.
Time for one point was (no annotations): 0ms
Time for one point was (all annotations): 14ms
Time for one point was (no annotations): 0ms
Time for one point was (no annotations): 10ms
Moving to 300.0 from 295.0
Moving to 295.0 from 300.0
Topup stopped
Topup stopped
Topup stopped
1000
Beam is deamed to be off
Topup stopped
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Waiting for imaginary topup for 10ms
Bean current is now stable again...
Topup stopped
Topup stopped
Topup stopped
Topup started @ 10Hz
Topup stopped
Setting malcolm device state to: Ready
Topup started @ 10Hz
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest8041061917106356795/panda.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest8041061917106356795/detector2.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest8041061917106356795/detector.h5
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Topup stopped
Topup started @ 10Hz
Topup stopped
Setting malcolm device state to: Ready
Topup started @ 10Hz
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest6282996157483456970/panda.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest6282996157483456970/detector2.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest6282996157483456970/detector.h5
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Armed
Topup stopped
Topup started @ 10Hz
Topup stopped
Setting malcolm device state to: Ready
Topup started @ 10Hz
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/panda.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector2.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector.h5
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/panda.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector2.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector.h5
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/panda.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector2.h5
Dummy malcolm device creating nexus file /tmp/DummyMalcolmDeviceTest5871786199080980807/detector.h5
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Paused
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Topup stopped
Topup stopped
Topup stopped
log4j:ERROR Could not find value for key log4j.appender.file
log4j:ERROR Could not instantiate appender named "file".
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Topup started @ 10Hz
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Topup stopped
Tests run: 91, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 157.604 sec - in org.eclipse.scanning.test.scan.Suite
Running org.eclipse.scanning.test.scan.preprocess.Suite
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.101 sec - in org.eclipse.scanning.test.scan.preprocess.Suite
Running org.eclipse.scanning.test.scan.nexus.Suite
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus1828963766410911403.nxs
Running acquisition scan of size 4
File writing to /tmp/test_nexus4932690612127777179.nxs
Running acquisition scan of size 4
File writing to /tmp/test_nexus2485709908360706184.nxs
Running acquisition scan of size 4
File writing to /tmp/test_nexus8936837357075252028.nxs
Running acquisition scan of size 4
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus7563185805753735197.nxs
Running acquisition scan of size 200
Running 200 points took 459 ms
File writing to /tmp/test_nexus5633737169431762004.nxs
Running acquisition scan of size 5
Running 5 points took 49 ms
File writing to /tmp/test_nexus1764338738809586651.nxs
Running acquisition scan of size 40
Running 40 points took 109 ms
File writing to /tmp/test_nexus8816284349236234911.nxs
Running acquisition scan of size 200
Running 200 points took 368 ms
File writing to /tmp/test_nexus4115864505294156907.nxs
Running acquisition scan of size 40
Running 40 points took 121 ms
File writing to /tmp/test_nexus4983463048472078931.nxs
Running acquisition scan of size 5
Running 5 points took 168 ms
File writing to /tmp/test_nexus5838504280147962153.nxs
Running acquisition scan of size 5
Running 5 points took 56 ms
File writing to /tmp/test_nexus5838504280147962153.nxs
Running acquisition scan of size 5
Running 5 points took 66 ms
File writing to /tmp/test_nexus5838504280147962153.nxs
Running acquisition scan of size 5
Running 5 points took 65 ms
File writing to /tmp/test_nexus5838504280147962153.nxs
Running acquisition scan of size 5
Running 5 points took 68 ms
File writing to /tmp/test_nexus5838504280147962153.nxs
Running acquisition scan of size 5
Running 5 points took 71 ms
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus321199835899716898.nxs
Running acquisition scan of size 40
Running 40 points took 162 ms
File writing to /tmp/test_nexus6846491997391842339.nxs
Running acquisition scan of size 40
Running 40 points took 180 ms
File writing to /tmp/test_nexus5014403887249269454.nxs
Running acquisition scan of size 40
Running 40 points took 187 ms
File writing to /tmp/test_nexus2355328338756890882.nxs
Running acquisition scan of size 40
Running 40 points took 151 ms
File writing to /tmp/test_nexus2745437182802725663.nxs
Running acquisition scan of size 40
Running 40 points took 162 ms
File writing to /tmp/test_nexus4308321416748917251.nxs
Running acquisition scan of size 40
Running 40 points took 144 ms
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus3914206554408428028.nxs
Running acquisition scan of size 8
File writing to /tmp/test_nexus6026550241893356997.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus9030194967308949654.nxs
Running acquisition scan of size 8
File writing to /tmp/test_nexus313912209635564204.nxs
Running acquisition scan of size 40
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus1617764904436069482.nxs
Running acquisition scan of size 40
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus7770793035683939944.nxs
Running acquisition size of scan 30
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus7770793035683939944/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus7770793035683939944/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus7770793035683939944/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, step=0]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=15.0, step=20]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=20.0, step=30]
Expected size = 30, shape = [3, 2, 5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus106737508289721799.nxs
Running acquisition size of scan 40
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus106737508289721799/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus106737508289721799/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus106737508289721799/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [, step=0]
Expected size = 40, shape = [8, 5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus2098767102462606593.nxs
Running acquisition size of scan 30
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus2098767102462606593/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus2098767102462606593/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus2098767102462606593/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, step=0]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=15.0, step=20]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=20.0, step=30]
Expected size = 30, shape = [3, 2, 5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus8892951016598208802.nxs
Running acquisition size of scan 40
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus8892951016598208802/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8892951016598208802/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8892951016598208802/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [, step=0]
Expected size = 40, shape = [8, 5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus1687941255519220907.nxs
Running acquisition size of scan 4
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus1687941255519220907/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus1687941255519220907/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus1687941255519220907/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, neXusScannable4(0)=10.0, neXusScannable5(0)=10.0, neXusScannable6(0)=10.0, step=0]
Expected size = 4, shape = [1, 1, 1, 1, 1, 1, 2, 2]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus5661869043066724968.nxs
Running acquisition size of scan 35
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus5661869043066724968/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus5661869043066724968/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus5661869043066724968/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [, step=0]
Expected size = 35, shape = [7, 5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus8266626271595855891.nxs
Running acquisition size of scan 4
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus8266626271595855891/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8266626271595855891/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8266626271595855891/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=0]
Expected size = 4, shape = [1, 1, 1, 2, 2]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus8555728412306534290.nxs
Running acquisition size of scan 36
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus8555728412306534290/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8555728412306534290/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus8555728412306534290/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(0)=10.0, step=0]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(1)=15.0, step=8]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(2)=20.0, step=12]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=15.0, neXusScannable2(0)=10.0, step=16]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=15.0, neXusScannable2(1)=15.0, step=20]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=15.0, neXusScannable2(2)=20.0, step=24]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=20.0, neXusScannable2(0)=10.0, step=28]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=20.0, neXusScannable2(1)=15.0, step=32]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=20.0, neXusScannable2(2)=20.0, step=36]
Expected size = 36, shape = [3, 3, 2, 2]
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus4917818514300618346.nxs
15:20:27.570 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [MainThread]
15:20:27.585 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [MainThread]
Running acquisition size of scan 1
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus4917818514300618346/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus4917818514300618346/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus4917818514300618346/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [, step=0]
Expected size = 1, shape = []
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus7921164574494395434.nxs
Running acquisition size of scan 5
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus7921164574494395434/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus7921164574494395434/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus7921164574494395434/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, step=0]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, step=2]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, step=3]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, step=4]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, step=5]
Expected size = 5, shape = [5]
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
File writing to /tmp/test_nexus989508887584250190.nxs
Running acquisition size of scan 30
Setting malcolm device state to: Running
Dummy malcolm device creating nexus file /tmp/test_nexus989508887584250190/panda.h5
Dummy malcolm device creating nexus file /tmp/test_nexus989508887584250190/detector2.h5
Dummy malcolm device creating nexus file /tmp/test_nexus989508887584250190/detector.h5
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=0]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(0)=10.0, neXusScannable3(1)=20.0, step=2]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(1)=15.0, neXusScannable3(0)=10.0, step=3]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(1)=15.0, neXusScannable3(1)=20.0, step=4]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(2)=20.0, neXusScannable3(0)=10.0, step=5]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(0)=10.0, neXusScannable2(2)=20.0, neXusScannable3(1)=20.0, step=6]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=7]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(0)=10.0, neXusScannable3(1)=20.0, step=8]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(1)=15.0, neXusScannable3(0)=10.0, step=9]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(1)=15.0, neXusScannable3(1)=20.0, step=10]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(2)=20.0, neXusScannable3(0)=10.0, step=11]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(1)=12.5, neXusScannable2(2)=20.0, neXusScannable3(1)=20.0, step=12]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=13]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(0)=10.0, neXusScannable3(1)=20.0, step=14]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(1)=15.0, neXusScannable3(0)=10.0, step=15]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(1)=15.0, neXusScannable3(1)=20.0, step=16]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(2)=20.0, neXusScannable3(0)=10.0, step=17]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(2)=15.0, neXusScannable2(2)=20.0, neXusScannable3(1)=20.0, step=18]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=19]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(0)=10.0, neXusScannable3(1)=20.0, step=20]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(1)=15.0, neXusScannable3(0)=10.0, step=21]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(1)=15.0, neXusScannable3(1)=20.0, step=22]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(2)=20.0, neXusScannable3(0)=10.0, step=23]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(3)=17.5, neXusScannable2(2)=20.0, neXusScannable3(1)=20.0, step=24]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(0)=10.0, neXusScannable3(0)=10.0, step=25]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(0)=10.0, neXusScannable3(1)=20.0, step=26]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(1)=15.0, neXusScannable3(0)=10.0, step=27]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(1)=15.0, neXusScannable3(1)=20.0, step=28]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(2)=20.0, neXusScannable3(0)=10.0, step=29]
Setting malcolm device state to: Running
Setting malcolm device state to: Armed
Ran test malcolm device @ [neXusScannable1(4)=20.0, neXusScannable2(2)=20.0, neXusScannable3(1)=20.0, step=30]
Expected size = 30, shape = [5, 3, 2]
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus2403116129015860771.nxs
15:20:28.602 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [MainThread]
15:20:28.603 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [MainThread]
Running acquire scan for detector mandelbrot
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus1123487495363838226.nxs
File writing to /tmp/test_nexus6968520605350600090.nxs
File writing to /tmp/test_nexus612239367815134036.nxs
File writing to /tmp/test_nexus559264620184877390.nxs
Writing 1 image in 3D stack took: 1 ms
File writing to /tmp/test_mandel_nexus6872498187821397325.nxs
Writing 1 image in 4D stack took: 1 ms
File writing to /tmp/test_nexus5026115738584540785.nxs
File writing to /tmp/test_nexus5217057515777008237.nxs
File writing to /tmp/test_nexus5217057515777008237.nxs
File writing to /tmp/test_nexus1390216315928238389.nxs
File writing to /tmp/test_nexus8148581444653258142.nxs
File writing to /tmp/test_nexus4852324463730872595.nxs
File writing to /tmp/test_nexus5339129616688421341.nxs
File writing to /tmp/test_nexus3815764158670573918.nxs
File writing to /tmp/test_nexus7765898133390816892.nxs
File writing to /tmp/test_nexus3861443137983331433.nxs
File writing to /tmp/test_nexus8564011706537536124.nxs
File writing to /tmp/test_nexus9013308521241335398.nxs
File writing to /tmp/test_nexus2245635817447012461.nxs
File writing to /tmp/test_nexus2638744851980530881.nxs
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus2295120708178646950.nxs
Running acquisition scan of size 8
File writing to /tmp/test_nexus683327416922210823.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus8084516546516176764.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus3407286867948375378.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus8247248906820907660.nxs
Running acquisition scan of size 8
File writing to /tmp/test_nexus2273319792517869470.nxs
Running acquisition scan of size 5
File writing to /tmp/test_nexus7934109917300819546.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus2772705916566975020.nxs
Running acquisition scan of size 2
File writing to /tmp/test_nexus1488222063688793511.nxs
Running acquisition scan of size 40
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus8090331156183781155.nxs
Running acquisition scan of size 4
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus8072234491201928723.nxs
File writing to /tmp/test_nexus952247721696589541.nxs
File writing to /tmp/test_nexus2423443867163935146.nxs
File writing to /tmp/test_nexus363192392981552836.nxs
File writing to /tmp/test_nexus4375729817266057705.nxs
File writing to /tmp/test_nexus3227541631814864149.nxs
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus8913260979477651269.nxs
File writing to /tmp/test_nexus8022460388954988083.nxs
File writing to /tmp/test_nexus5200469090193556820.nxs
File writing to /tmp/test_nexus7385780142483966052.nxs
File writing to /tmp/test_nexus6518653994857136892.nxs
File writing to /tmp/test_nexus2199526751955155622.nxs
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
File writing to /tmp/test_nexus2152693544328962484.nxs
File writing to /tmp/test_nexus389989345849739930.nxs
File writing to /tmp/test_nexus1795449161898386061.nxs
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
------------------------------
Normal NeXus Scan
------------------------------
Configure time was 38 ms
Ran in 743ms not including tree write time
Ran 1001 points at 0ms/pnt
File size is 167kB
------------------------------
NeXus with Publish
------------------------------
Configure time was 39 ms
Ran in 847ms not including tree write time
Ran 1001 points at 0ms/pnt
File size is 167kB
------------------------------
No NeXus scan
------------------------------
Configure time was 7 ms
Ran in 117ms not including tree write time
Ran 1001 points at 0ms/pnt
File size is 0kB
------------------------------
Scan no 'setSlice'
------------------------------
Configure time was 43 ms
Ran in 428ms not including tree write time
Ran 1001 points at 0ms/pnt
File size is 147kB
Tests run: 94, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 39.565 sec - in org.eclipse.scanning.test.scan.nexus.Suite
Running org.eclipse.scanning.test.scan.servlet.Suite
Starting ValidatorService
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Scan Consumer is paused
15:21:24.857 WARN o.e.s.event.AbstractConnection - Failed job Hello Scanning World messageid(ID:testing-gce-9fa8c465-9267-45e7-8a18-5ccc791323ac-35782-1505488613148-661:1:1:1:1) [MainThread]
15:21:26.778 WARN o.e.scanning.event.PublisherImpl - Event publisher heartbeat connection to tcp://localhost:8645 lost. [Alive Notification org.eclipse.scanning.alive.topic ]
15:21:26.778 WARN o.e.scanning.event.PublisherImpl - We will check every 2 seconds for 24 hours, until it comes back. [Alive Notification org.eclipse.scanning.alive.topic ]
Tests run: 13, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 29.869 sec - in org.eclipse.scanning.test.scan.servlet.Suite
Running org.eclipse.scanning.test.messaging.Suite
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Writing to file /tmp/art_test4996478508283197587.nxs
15:21:29.078 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [Submitter despatch thread org.eclipse.scanning.submission.queue]
15:21:29.078 WARN o.e.january.dataset.LazyDatasetBase - Shape is null!!! [Submitter despatch thread org.eclipse.scanning.submission.queue]
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Dry run : 0.0 : Test Xcen
{"@type":"XcenBean","uniqueId":"1441796619081_780ede90-6f30-4aaa-bd1b-c7a09fa12319","previousStatus":"QUEUED","status":"RUNNING","name":"Test Xcen","message":"A test xcen execution","percentComplete":0.0,"userName":"lkz95212","estimatedTime":0,"startTime":0,"runDirectory":"/tmp/xcenrun/Xcen_1","submissionTime":1441796619734,"beamline":"i04-1","visit":"nt5073-40","collection":"sapA-x56_A","x":0.0,"y":0.0,"z":0.0}
Dry run : 0.0 : Test Xcen
Dry run : 1.0 : Test Xcen
Dry run : 2.0 : Test Xcen
Dry run : 3.0 : Test Xcen
Dry run : 4.0 : Test Xcen
Dry run : 5.0 : Test Xcen
Dry run : 6.0 : Test Xcen
Dry run : 7.0 : Test Xcen
Dry run : 8.0 : Test Xcen
Dry run : 9.0 : Test Xcen
Dry run : 10.0 : Test Xcen
Dry run : 11.0 : Test Xcen
Dry run : 12.0 : Test Xcen
Dry run : 13.0 : Test Xcen
Dry run : 14.0 : Test Xcen
Dry run : 15.0 : Test Xcen
Dry run : 16.0 : Test Xcen
Dry run : 17.0 : Test Xcen
Dry run : 18.0 : Test Xcen
Dry run : 19.0 : Test Xcen
Clearing /tmp of old nexus files.
Writing to file /tmp/scan_api_test4695154527358461775.nxs
15:21:39.829 ERROR o.e.s.server.servlet.ScanProcess - Cannot execute run null c8f12aee-d56a-49f6-bc03-9c7de9415674 [Consumer Thread Scan Consumer]
org.eclipse.scanning.api.scan.ScanningException: sleep interrupted
at org.eclipse.scanning.sequencer.LevelRunner.abort(LevelRunner.java:267) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.sequencer.LevelRunner.abort(LevelRunner.java:260) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.sequencer.DeviceRunner$RunTask.call(DeviceRunner.java:116) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.sequencer.DeviceRunner$RunTask.call(DeviceRunner.java:1) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at java.util.concurrent.ForkJoinTask$AdaptedCallable.exec(ForkJoinTask.java:1424) ~[na:1.8.0_131]
at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:289) ~[na:1.8.0_131]
at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056) ~[na:1.8.0_131]
at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692) ~[na:1.8.0_131]
at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157) ~[na:1.8.0_131]
Caused by: java.lang.InterruptedException: sleep interrupted
at java.lang.Thread.sleep(Native Method) ~[na:1.8.0_131]
at org.eclipse.scanning.example.detector.MandelbrotDetector.run(MandelbrotDetector.java:197) ~[org.eclipse.scanning.example-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.sequencer.DeviceRunner$RunTask.call(DeviceRunner.java:114) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
... 6 common frames omitted
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.056 sec - in org.eclipse.scanning.test.messaging.Suite
Running org.eclipse.scanning.test.malcolm.Suite
Tests run: 4, Failures: 0, Errors: 0, Skipped: 4, Time elapsed: 0 sec - in org.eclipse.scanning.test.malcolm.Suite
Running org.eclipse.scanning.test.malcolm.real.Suite
pvAccess Server v5.0.0
CLASS : org.epics.pvaccess.server.impl.remote.ServerContextImpl
VERSION : pvAccess Server v5.0.0
PROVIDER_NAMES : local
BEACON_ADDR_LIST :
AUTO_BEACON_ADDR_LIST : true
BEACON_PERIOD : 15.0
BROADCAST_PORT : 5076
SERVER_PORT : 5075
RCV_BUFFER_SIZE : 16384
IGNORE_ADDR_LIST:
STATE : INITIALIZED
Got Async Request:
structure
Got Async Request:
structure
scanpointgenerator:generator/CompoundGenerator:1.0 generator
any[] mutators
double duration 23.1
any[] generators
any
scanpointgenerator:generator/SpiralGenerator:1.0
string[] axes [stage_x,stage_y]
double[] centre [1.5,-2.0]
double scale 1.0
boolean alternate false
string[] units [mm,mm]
double radius 7.632168761236874
any[] excluders
any
scanpointgenerator:excluder/ROIExcluder:1.0
string[] axes [stage_x,stage_y]
any[] rois
any
scanpointgenerator:roi/CircularROI:1.0
double[] centre [0.0,0.0]
double radius 2.0
any
scanpointgenerator:roi/CircularROI:1.0
double[] centre [-1.0,-2.0]
double radius 4.0
string fileDir /path/to/ixx-1234
string fileTemplate ixx-1234-%s.h5
Got Async Request:
structure
Got Async Request:
structure
int completedSteps 4
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.518 sec - in org.eclipse.scanning.test.malcolm.real.Suite
Running org.eclipse.scanning.test.epics.Suite
15:21:45.661 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'INVALID_DEVICE' (ERROR: channel not connected) [pool-32-thread-1]
pvAccess Server v5.0.0
CLASS : org.epics.pvaccess.server.impl.remote.ServerContextImpl
VERSION : pvAccess Server v5.0.0
PROVIDER_NAMES : local
BEACON_ADDR_LIST :
AUTO_BEACON_ADDR_LIST : true
BEACON_PERIOD : 15.0
BROADCAST_PORT : 5076
SERVER_PORT : 5075
RCV_BUFFER_SIZE : 16384
IGNORE_ADDR_LIST:
STATE : INITIALIZED
15:21:45.679 ERROR o.e.s.c.e.EpicsV4ConnectorService - CreateGet failed for 'NON_EXISTANT' (ERROR: illegal pvRequest) [pool-33-thread-1]
15:21:46.686 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'fred' (ERROR: channel not connected) [pool-34-thread-1]
Sep 15, 2017 3:21:46 PM org.epics.pvaccess.server.impl.remote.ServerContextImpl initialize
WARNING: Channel provider with name 'evil' not available.
15:21:47.691 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected) [pool-35-thread-1]
pvAccess Server v5.0.0
CLASS : org.epics.pvaccess.server.impl.remote.ServerContextImpl
VERSION : pvAccess Server v5.0.0
PROVIDER_NAMES : local
BEACON_ADDR_LIST :
AUTO_BEACON_ADDR_LIST : true
BEACON_PERIOD : 15.0
BROADCAST_PORT : 5076
SERVER_PORT : 5075
RCV_BUFFER_SIZE : 16384
IGNORE_ADDR_LIST:
STATE : INITIALIZED
Got Async Request:
structure
Got Async Request:
structure
scanpointgenerator:generator/CompoundGenerator:1.0 generator
any[] mutators
double duration 23.1
any[] generators
any
scanpointgenerator:generator/SpiralGenerator:1.0
string[] axes [stage_x,stage_y]
double[] centre [4.0,-1.0]
double scale 1.0
boolean alternate false
string[] units [mm,mm]
double radius 5.656854249492381
any[] excluders
any
scanpointgenerator:excluder/ROIExcluder:1.0
string[] axes [stage_x,stage_y]
any[] rois
any
scanpointgenerator:roi/CircularROI:1.0
double[] centre [6.0,1.0]
double radius 2.0
string fileDir /TestFile/Dir
string fileTemplate Dir-%s.h5
15:21:48.773 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected) [pool-39-thread-1]
java.lang.Exception: Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.sendCallMessage(EpicsV4ConnectorService.java:336)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:105)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:1)
at org.eclipse.scanning.connector.epics.EpicsV4MalcolmMessageGenerator.call(EpicsV4MalcolmMessageGenerator.java:90)
at org.eclipse.scanning.connector.epics.EpicsV4MalcolmMessageGenerator.call(EpicsV4MalcolmMessageGenerator.java:1)
at org.eclipse.scanning.malcolm.core.AbstractMalcolmDevice.lambda$1(AbstractMalcolmDevice.java:222)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
pvAccess Server v5.0.0
CLASS : org.epics.pvaccess.server.impl.remote.ServerContextImpl
VERSION : pvAccess Server v5.0.0
PROVIDER_NAMES : local
BEACON_ADDR_LIST :
AUTO_BEACON_ADDR_LIST : true
BEACON_PERIOD : 15.0
BROADCAST_PORT : 5076
SERVER_PORT : 5075
RCV_BUFFER_SIZE : 16384
IGNORE_ADDR_LIST:
STATE : INITIALIZED
15:21:49.822 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected) [pool-41-thread-1]
java.lang.Exception: Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.sendCallMessage(EpicsV4ConnectorService.java:336)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:105)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:1)
at org.eclipse.scanning.connector.epics.EpicsV4MalcolmMessageGenerator.call(EpicsV4MalcolmMessageGenerator.java:90)
at org.eclipse.scanning.connector.epics.EpicsV4MalcolmMessageGenerator.call(EpicsV4MalcolmMessageGenerator.java:1)
at org.eclipse.scanning.malcolm.core.AbstractMalcolmDevice.lambda$1(AbstractMalcolmDevice.java:222)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
15:21:50.832 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected) [pool-42-thread-1]
java.lang.Exception: Failed to connect to device 'DummyMalcolmDevice:malcolmTest' (ERROR: channel not connected)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.sendCallMessage(EpicsV4ConnectorService.java:336)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:105)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:1)
at org.eclipse.scanning.malcolm.core.AbstractMalcolmDevice.lambda$0(AbstractMalcolmDevice.java:218)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
15:21:50.885 ERROR o.e.s.c.e.EpicsV4ConnectorService - Failed to connect to device 'INVALID_DEVICE' (ERROR: null) [pool-43-thread-1]
Sep 15, 2017 3:21:50 PM org.epics.pvaccess.server.impl.remote.ServerContextImpl initialize
WARNING: Channel provider with name 'evil' not available.
java.lang.InterruptedException
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:998)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1304)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:231)
at org.eclipse.scanning.test.epics.HangingGetConnectorService.sendGetMessage(HangingGetConnectorService.java:21)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:108)
at org.eclipse.scanning.connector.epics.EpicsV4ConnectorService.send(EpicsV4ConnectorService.java:1)
at org.eclipse.scanning.malcolm.core.AbstractMalcolmDevice.lambda$0(AbstractMalcolmDevice.java:218)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
15:21:50.987 ERROR o.e.s.c.e.EpicsV4ConnectorService - null [pool-44-thread-1]
pvAccess Server v5.0.0
CLASS : org.epics.pvaccess.server.impl.remote.ServerContextImpl
VERSION : pvAccess Server v5.0.0
PROVIDER_NAMES : local
BEACON_ADDR_LIST :
AUTO_BEACON_ADDR_LIST : true
BEACON_PERIOD : 15.0
BROADCAST_PORT : 5076
SERVER_PORT : 5075
RCV_BUFFER_SIZE : 16384
IGNORE_ADDR_LIST:
STATE : INITIALIZED
Tests run: 23, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 6.631 sec - in org.eclipse.scanning.test.epics.Suite
Running org.eclipse.scanning.test.command.Suite
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Clearing /tmp of old nexus files.
Creating process for name = processing id = 1d61327e-4e5f-4321-b0aa-b84d265bd114
Creating process for name = p id = ce331685-1b43-4b73-8ae1-eea360a4311e
Creating process for name = processing id = 770468e2-0b15-4cca-b91d-e2d2303e2213
15:22:04.181 ERROR o.e.s.server.servlet.ScanProcess - Cannot execute run Scan [Grid(yNex, xNex)] with Detectors [processing, mandelbrot] 0833b76e-d441-4ab7-a8fe-b36c7890b283 [Consumer Thread Scan Consumer]
org.eclipse.scanning.api.ModelValidationException: The value of 'detectorName' references a device (fred) not a valid device!
at org.eclipse.scanning.points.validation.ScanRequestValidator.validateAnnotations(ScanRequestValidator.java:97) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ScanRequestValidator.validate(ScanRequestValidator.java:61) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ScanRequestValidator.validate(ScanRequestValidator.java:1) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ValidatorService.validate(ValidatorService.java:113) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.server.servlet.ScanProcess.validateRequest(ScanProcess.java:213) ~[org.eclipse.scanning.server-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.server.servlet.ScanProcess.execute(ScanProcess.java:143) ~[org.eclipse.scanning.server-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.api.event.core.IConsumerProcess.start(IConsumerProcess.java:52) [org.eclipse.scanning.api-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.executeBean(ConsumerImpl.java:708) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.consume(ConsumerImpl.java:517) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.run(ConsumerImpl.java:457) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl$1.run(ConsumerImpl.java:326) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
15:22:04.436 ERROR o.e.s.server.servlet.ScanProcess - Cannot execute run Scan [Grid(yNex, xNex)] with Detectors [processing, mandelbrot] d1390aa7-97d7-4d2d-b867-0c92eeb71d72 [Consumer Thread Scan Consumer]
org.eclipse.scanning.api.ModelValidationException: The detector must be set
at org.eclipse.scanning.sequencer.analysis.ClusterProcessingRunnableDevice.validate(ClusterProcessingRunnableDevice.java:71) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.sequencer.analysis.ClusterProcessingRunnableDevice.validate(ClusterProcessingRunnableDevice.java:1) ~[org.eclipse.scanning.sequencer-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ScanRequestValidator.validateDetectors(ScanRequestValidator.java:154) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ScanRequestValidator.validate(ScanRequestValidator.java:60) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ScanRequestValidator.validate(ScanRequestValidator.java:1) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.points.validation.ValidatorService.validate(ValidatorService.java:113) ~[org.eclipse.scanning.points-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.server.servlet.ScanProcess.validateRequest(ScanProcess.java:213) ~[org.eclipse.scanning.server-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.server.servlet.ScanProcess.execute(ScanProcess.java:143) ~[org.eclipse.scanning.server-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.api.event.core.IConsumerProcess.start(IConsumerProcess.java:52) [org.eclipse.scanning.api-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.executeBean(ConsumerImpl.java:708) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.consume(ConsumerImpl.java:517) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl.run(ConsumerImpl.java:457) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
at org.eclipse.scanning.event.ConsumerImpl$1.run(ConsumerImpl.java:326) [org.eclipse.scanning.event-1.0.0-SNAPSHOT.jar:na]
Creating process for name = processing id = d84ca42d-e724-4af8-9775-e006f5fa7fdd
Clearing /tmp of old nexus files.
Tests run: 55, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 34.815 sec - in org.eclipse.scanning.test.command.Suite
Running org.eclipse.scanning.test.fakespring.Suite
detector_beans.xml parsed and size was: 6
file1.xml parsed and size was: 2
file2.xml parsed and size was: 2
detectors_with_ref.xml parsed and size was: 9
nothing.xml parsed and size was: 0
links.xml parsed and size was: 14
control_tree.xml parsed and size was: 7
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.106 sec - in org.eclipse.scanning.test.fakespring.Suite
Running org.eclipse.scanning.test.validation.Suite
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Ready
Setting malcolm device state to: Configuring
Setting malcolm device state to: Armed
Setting malcolm device state to: Ready
Tests run: 26, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.504 sec - in org.eclipse.scanning.test.validation.Suite
Results :
Tests run: 726, Failures: 0, Errors: 0, Skipped: 28
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.eclipse.scanning.connector.activemq.test.Suite
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Started IMarshallerService
Started ActivemqConnectorService
ActivemqConnectorService needs an IJsonMarshaller to function correctly
ActivemqConnectorService needs an IJsonMarshaller to function correctly
Tests run: 16, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 1.014 sec - in org.eclipse.scanning.connector.activemq.test.Suite
Results :
Tests run: 16, Failures: 0, Errors: 0, Skipped: 1
travis_time:end:1df24cc6:start=1505488547591337167,finish=1505488948593663515,duration=401002326348

The command "mvn -q surefire:test -Dtest.includes=org/eclipse/scanning/**/Suite.java" exited with 0.
travis_time:start:0e5c88b6
$ mvn -q surefire:test -Dtest.includes=org/eclipse/scanning/**/UISuite.java -Dlog4j.configuration=log4j.properties
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.eclipse.scanning.test.ui.UISuite
Starting SWTBot UI Thread
Starting InterfaceService
Started IMarshallerService
Started ActivemqConnectorService
Started IEventService
15:23:02.676 ERROR o.a.activemq.broker.BrokerService - Temporary Store limit is 51200 mb, whilst the temporary data directory: /home/travis/build/eclipse/org.eclipse.scanning/org.eclipse.scanning.test/activemq-data/localhost/tmp_storage only has 18721 mb of usable space - resetting to maximum available 18721 mb. [main]
Stopped SWTBot UI Thread
Exception in thread "SWTBot UI Thread" java.lang.UnsatisfiedLinkError: Cannot load 32-bit SWT libraries on 64-bit JVM
at org.eclipse.swt.internal.Library.loadLibrary(Library.java:259)
at org.eclipse.swt.internal.Library.loadLibrary(Library.java:236)
at org.eclipse.swt.internal.C.<clinit>(C.java:16)
at org.eclipse.swt.internal.Converter.wcsToMbcs(Converter.java:63)
at org.eclipse.swt.internal.Converter.wcsToMbcs(Converter.java:54)
at org.eclipse.swt.widgets.Display.<clinit>(Display.java:134)
at org.eclipse.richbeans.test.ui.TestUI$1.run(TestUI.java:56)
at java.lang.Thread.run(Thread.java:748)
No output has been received in the last 10m0s, this potentially indicates a stalled build or something wrong with the build itself.
Check the details on how to adjust your build configuration on: https://docs.travis-ci.com/user/common-build-problems/#Build-times-out-because-no-output-was-received
The build has been terminated
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment