builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1482507186.12 results: success (0) buildid: 20161223062836 builduid: 5178cd0d87334f38ae7bcbdcb11e3629 revision: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.116243) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.116859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.117259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.135786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.136137) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-23 07:33:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-12-23 07:33:06 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074248 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.235425) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.235838) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022251 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.293077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:06.293631) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-23 07:33:07,119 truncating revision to first 12 chars 2016-12-23 07:33:07,119 Setting DEBUG logging. 2016-12-23 07:33:07,119 attempt 1/10 2016-12-23 07:33:07,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bbbd2f7539f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 07:33:07,632 unpacking tar archive at: mozilla-central-bbbd2f7539f2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.844175 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:07.152108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:07.152487) ========= script_repo_revision: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:07.153091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:07.153426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 07:33:07.175768) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-23 07:33:07.176088) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:33:08 INFO - MultiFileLogger online at 20161223 07:33:08 in /builds/slave/test 07:33:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:33:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:33:08 INFO - {'append_to_log': False, 07:33:08 INFO - 'base_work_dir': '/builds/slave/test', 07:33:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:33:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:33:08 INFO - 'branch': 'Firefox-Non-PGO', 07:33:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:33:08 INFO - 'config_files': ('talos/linux_config.py',), 07:33:08 INFO - 'default_actions': ('clobber', 07:33:08 INFO - 'read-buildbot-config', 07:33:08 INFO - 'download-and-extract', 07:33:08 INFO - 'populate-webroot', 07:33:08 INFO - 'create-virtualenv', 07:33:08 INFO - 'install', 07:33:08 INFO - 'run-tests'), 07:33:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:33:08 INFO - 'download_minidump_stackwalk': True, 07:33:08 INFO - 'download_symbols': 'ondemand', 07:33:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:33:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:33:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:33:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:33:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:33:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:33:08 INFO - 'installer_path': 'installer.exe', 07:33:08 INFO - 'log_level': 'info', 07:33:08 INFO - 'log_name': 'talos', 07:33:08 INFO - 'log_to_console': True, 07:33:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:33:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:33:08 INFO - 'opt_config_files': (), 07:33:08 INFO - 'pip_index': False, 07:33:08 INFO - 'sps_profile': False, 07:33:08 INFO - 'sps_profile_interval': 0, 07:33:08 INFO - 'suite': 'dromaeojs', 07:33:08 INFO - 'system_bits': '32', 07:33:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:33:08 INFO - 'title': 'talos-linux64-ix-049', 07:33:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:33:08 INFO - 'use_talos_json': True, 07:33:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:33:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:33:08 INFO - 'work_dir': 'build'} 07:33:08 INFO - [mozharness: 2016-12-23 15:33:08.105869Z] Running clobber step. 07:33:08 INFO - Running pre-action listener: _resource_record_pre_action 07:33:08 INFO - Running main action method: clobber 07:33:08 INFO - rmtree: /builds/slave/test/build 07:33:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:33:08 INFO - Running post-action listener: _resource_record_post_action 07:33:08 INFO - [mozharness: 2016-12-23 15:33:08.564778Z] Finished clobber step (success) 07:33:08 INFO - [mozharness: 2016-12-23 15:33:08.564879Z] Running read-buildbot-config step. 07:33:08 INFO - Running pre-action listener: _resource_record_pre_action 07:33:08 INFO - Running main action method: read_buildbot_config 07:33:08 INFO - Using buildbot properties: 07:33:08 INFO - { 07:33:08 INFO - "project": "", 07:33:08 INFO - "product": "firefox", 07:33:08 INFO - "script_repo_revision": "production", 07:33:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:33:08 INFO - "repository": "", 07:33:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:33:08 INFO - "buildid": "20161223062836", 07:33:08 INFO - "pgo_build": "False", 07:33:08 INFO - "basedir": "/builds/slave/test", 07:33:08 INFO - "buildnumber": 890, 07:33:08 INFO - "slavename": "talos-linux64-ix-049", 07:33:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:33:08 INFO - "platform": "ubuntu64_hw", 07:33:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:33:08 INFO - "branch": "mozilla-central", 07:33:08 INFO - "repo_path": "mozilla-central", 07:33:08 INFO - "revision": "bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3", 07:33:08 INFO - "stage_platform": "linux64", 07:33:08 INFO - "builduid": "5178cd0d87334f38ae7bcbdcb11e3629", 07:33:08 INFO - "slavebuilddir": "test" 07:33:08 INFO - } 07:33:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:33:08 INFO - Running post-action listener: _resource_record_post_action 07:33:08 INFO - [mozharness: 2016-12-23 15:33:08.567343Z] Finished read-buildbot-config step (success) 07:33:08 INFO - [mozharness: 2016-12-23 15:33:08.567434Z] Running download-and-extract step. 07:33:08 INFO - Running pre-action listener: _resource_record_pre_action 07:33:08 INFO - Running main action method: download_and_extract 07:33:08 INFO - mkdir: /builds/slave/test/build/tests 07:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:08 INFO - trying https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:33:08 INFO - Downloading https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:33:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:33:09 INFO - Downloaded 1361 bytes. 07:33:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:33:09 INFO - Using the following test package requirements: 07:33:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:33:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:33:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:33:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'jsshell-linux-x86_64.zip'], 07:33:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:33:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:33:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:33:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:33:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:33:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:33:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:33:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:33:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:33:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:33:09 INFO - Fetch https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:33:09 INFO - Expected file size: 20236300 07:33:09 INFO - Obtained file size: 20236300 07:33:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:33:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:33:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:33:13 INFO - Fetch https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:33:14 INFO - Expected file size: 20236300 07:33:14 INFO - Obtained file size: 20236300 07:33:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:33:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:33:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:33:19 INFO - Expected file size: 14409776 07:33:19 INFO - Obtained file size: 14409776 07:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:20 INFO - trying https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:33:20 INFO - Downloading https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:33:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:33:21 INFO - Downloaded 55924998 bytes. 07:33:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:33:21 INFO - mkdir: /builds/slave/test/properties 07:33:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:33:21 INFO - Writing to file /builds/slave/test/properties/build_url 07:33:21 INFO - Contents: 07:33:21 INFO - build_url:https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:33:21 INFO - Running post-action listener: _resource_record_post_action 07:33:21 INFO - Running post-action listener: set_extra_try_arguments 07:33:21 INFO - [mozharness: 2016-12-23 15:33:21.894226Z] Finished download-and-extract step (success) 07:33:21 INFO - [mozharness: 2016-12-23 15:33:21.894525Z] Running populate-webroot step. 07:33:21 INFO - Running pre-action listener: _resource_record_pre_action 07:33:21 INFO - Running main action method: populate_webroot 07:33:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:33:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:33:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:33:21 INFO - '--tppagecycles', 07:33:21 INFO - '7'], 07:33:21 INFO - 'tests': ['tsvgm']}}, 07:33:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:33:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:33:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:33:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:33:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:33:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['damp', 'tps']}, 07:33:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'tests': ['damp', 'tps']}, 07:33:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['dromaeo_dom']}, 07:33:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:33:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['basic_compositor_video']}, 07:33:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:33:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'other-e10s': {'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['a11yr', 07:33:21 INFO - 'ts_paint', 07:33:21 INFO - 'tpaint', 07:33:21 INFO - 'sessionrestore', 07:33:21 INFO - 'sessionrestore_no_auto_restore', 07:33:21 INFO - 'tabpaint']}, 07:33:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['tsvgx', 07:33:21 INFO - 'tsvgr_opacity', 07:33:21 INFO - 'tart', 07:33:21 INFO - 'tscrollx', 07:33:21 INFO - 'cart', 07:33:21 INFO - 'tsvg_static']}, 07:33:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:33:21 INFO - 'tsvgr_opacity', 07:33:21 INFO - 'tart', 07:33:21 INFO - 'tscrollx', 07:33:21 INFO - 'cart', 07:33:21 INFO - 'tsvg_static']}, 07:33:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'talos_options': ['--disable-e10s'], 07:33:21 INFO - 'tests': ['tp5o']}, 07:33:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:33:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'talos_options': ['--disable-e10s', 07:33:21 INFO - '--xperf_path', 07:33:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:21 INFO - 'tests': ['tp5n']}, 07:33:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:33:21 INFO - 'talos_options': ['--xperf_path', 07:33:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:33:21 INFO - 'tests': ['tp5n']}}, 07:33:21 INFO - 'talos.zip': {'path': '', 07:33:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:33:21 INFO - Running post-action listener: _resource_record_post_action 07:33:21 INFO - [mozharness: 2016-12-23 15:33:21.914398Z] Finished populate-webroot step (success) 07:33:21 INFO - [mozharness: 2016-12-23 15:33:21.914492Z] Running create-virtualenv step. 07:33:21 INFO - Running pre-action listener: _resource_record_pre_action 07:33:21 INFO - Running main action method: create_virtualenv 07:33:21 INFO - Creating virtualenv /builds/slave/test/build/venv 07:33:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:33:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:33:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:33:22 INFO - Using real prefix '/usr' 07:33:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:33:22 INFO - Installing distribute.............................................................................................................................................................................................done. 07:33:24 INFO - Installing pip.................done. 07:33:24 INFO - Return code: 0 07:33:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:33:24 INFO - Reading from file tmpfile_stdout 07:33:24 INFO - Output received: 07:33:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:33:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:33:24 INFO - Using env: {'DISPLAY': ':0', 07:33:24 INFO - 'HOME': '/home/cltbld', 07:33:24 INFO - 'LANG': 'en_US.UTF-8', 07:33:24 INFO - 'LANGUAGE': 'en_US:en', 07:33:24 INFO - 'LOGNAME': 'cltbld', 07:33:24 INFO - 'MAIL': '/var/mail/cltbld', 07:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:24 INFO - 'MOZ_NO_REMOTE': '1', 07:33:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:24 INFO - 'NO_EM_RESTART': '1', 07:33:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:24 INFO - 'PWD': '/builds/slave/test', 07:33:24 INFO - 'SHELL': '/bin/bash', 07:33:24 INFO - 'SHLVL': '1', 07:33:24 INFO - 'TERM': 'linux', 07:33:24 INFO - 'TMOUT': '86400', 07:33:24 INFO - 'USER': 'cltbld', 07:33:24 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:24 INFO - '_': '/tools/buildbot/bin/python'} 07:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:33:24 INFO - Cleaning up... 07:33:24 INFO - Return code: 0 07:33:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:33:24 INFO - Using env: {'DISPLAY': ':0', 07:33:24 INFO - 'HOME': '/home/cltbld', 07:33:24 INFO - 'LANG': 'en_US.UTF-8', 07:33:24 INFO - 'LANGUAGE': 'en_US:en', 07:33:24 INFO - 'LOGNAME': 'cltbld', 07:33:24 INFO - 'MAIL': '/var/mail/cltbld', 07:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:24 INFO - 'MOZ_NO_REMOTE': '1', 07:33:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:24 INFO - 'NO_EM_RESTART': '1', 07:33:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:24 INFO - 'PWD': '/builds/slave/test', 07:33:24 INFO - 'SHELL': '/bin/bash', 07:33:24 INFO - 'SHLVL': '1', 07:33:24 INFO - 'TERM': 'linux', 07:33:24 INFO - 'TMOUT': '86400', 07:33:24 INFO - 'USER': 'cltbld', 07:33:24 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:24 INFO - '_': '/tools/buildbot/bin/python'} 07:33:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:25 INFO - Downloading/unpacking psutil>=3.1.1 07:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:33:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:27 INFO - Installing collected packages: psutil 07:33:27 INFO - Running setup.py install for psutil 07:33:27 INFO - building 'psutil._psutil_linux' extension 07:33:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:33:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:33:27 INFO - building 'psutil._psutil_posix' extension 07:33:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:33:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:33:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:33:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:33:27 INFO - Successfully installed psutil 07:33:27 INFO - Cleaning up... 07:33:27 INFO - Return code: 0 07:33:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:33:27 INFO - Using env: {'DISPLAY': ':0', 07:33:27 INFO - 'HOME': '/home/cltbld', 07:33:27 INFO - 'LANG': 'en_US.UTF-8', 07:33:27 INFO - 'LANGUAGE': 'en_US:en', 07:33:27 INFO - 'LOGNAME': 'cltbld', 07:33:27 INFO - 'MAIL': '/var/mail/cltbld', 07:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:27 INFO - 'MOZ_NO_REMOTE': '1', 07:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:27 INFO - 'NO_EM_RESTART': '1', 07:33:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:27 INFO - 'PWD': '/builds/slave/test', 07:33:27 INFO - 'SHELL': '/bin/bash', 07:33:27 INFO - 'SHLVL': '1', 07:33:27 INFO - 'TERM': 'linux', 07:33:27 INFO - 'TMOUT': '86400', 07:33:27 INFO - 'USER': 'cltbld', 07:33:27 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:27 INFO - '_': '/tools/buildbot/bin/python'} 07:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:33:30 INFO - Installing collected packages: mozsystemmonitor 07:33:30 INFO - Running setup.py install for mozsystemmonitor 07:33:30 INFO - Successfully installed mozsystemmonitor 07:33:30 INFO - Cleaning up... 07:33:30 INFO - Return code: 0 07:33:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:33:30 INFO - Using env: {'DISPLAY': ':0', 07:33:30 INFO - 'HOME': '/home/cltbld', 07:33:30 INFO - 'LANG': 'en_US.UTF-8', 07:33:30 INFO - 'LANGUAGE': 'en_US:en', 07:33:30 INFO - 'LOGNAME': 'cltbld', 07:33:30 INFO - 'MAIL': '/var/mail/cltbld', 07:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:30 INFO - 'MOZ_NO_REMOTE': '1', 07:33:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:30 INFO - 'NO_EM_RESTART': '1', 07:33:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:30 INFO - 'PWD': '/builds/slave/test', 07:33:30 INFO - 'SHELL': '/bin/bash', 07:33:30 INFO - 'SHLVL': '1', 07:33:30 INFO - 'TERM': 'linux', 07:33:30 INFO - 'TMOUT': '86400', 07:33:30 INFO - 'USER': 'cltbld', 07:33:30 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:30 INFO - '_': '/tools/buildbot/bin/python'} 07:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:30 INFO - Downloading/unpacking jsonschema==2.5.1 07:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:33:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:33:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:33:32 INFO - warning: no files found matching '*.txt' 07:33:32 INFO - no previously-included directories found matching 'build' 07:33:32 INFO - no previously-included directories found matching 'dist' 07:33:32 INFO - no previously-included directories found matching '.git*' 07:33:32 INFO - Installing collected packages: jsonschema, functools32 07:33:32 INFO - Running setup.py install for functools32 07:33:32 INFO - warning: no files found matching '*.txt' 07:33:32 INFO - no previously-included directories found matching 'build' 07:33:32 INFO - no previously-included directories found matching 'dist' 07:33:32 INFO - no previously-included directories found matching '.git*' 07:33:32 INFO - Successfully installed jsonschema functools32 07:33:32 INFO - Cleaning up... 07:33:32 INFO - Return code: 0 07:33:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:33:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:33:32 INFO - Using env: {'DISPLAY': ':0', 07:33:32 INFO - 'HOME': '/home/cltbld', 07:33:32 INFO - 'LANG': 'en_US.UTF-8', 07:33:32 INFO - 'LANGUAGE': 'en_US:en', 07:33:32 INFO - 'LOGNAME': 'cltbld', 07:33:32 INFO - 'MAIL': '/var/mail/cltbld', 07:33:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:32 INFO - 'MOZ_NO_REMOTE': '1', 07:33:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:32 INFO - 'NO_EM_RESTART': '1', 07:33:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:32 INFO - 'PWD': '/builds/slave/test', 07:33:32 INFO - 'SHELL': '/bin/bash', 07:33:32 INFO - 'SHLVL': '1', 07:33:32 INFO - 'TERM': 'linux', 07:33:32 INFO - 'TMOUT': '86400', 07:33:32 INFO - 'USER': 'cltbld', 07:33:32 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:32 INFO - '_': '/tools/buildbot/bin/python'} 07:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:33:33 INFO - Cleaning up... 07:33:33 INFO - Return code: 0 07:33:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:33:33 INFO - Using env: {'DISPLAY': ':0', 07:33:33 INFO - 'HOME': '/home/cltbld', 07:33:33 INFO - 'LANG': 'en_US.UTF-8', 07:33:33 INFO - 'LANGUAGE': 'en_US:en', 07:33:33 INFO - 'LOGNAME': 'cltbld', 07:33:33 INFO - 'MAIL': '/var/mail/cltbld', 07:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:33 INFO - 'MOZ_NO_REMOTE': '1', 07:33:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:33 INFO - 'NO_EM_RESTART': '1', 07:33:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:33 INFO - 'PWD': '/builds/slave/test', 07:33:33 INFO - 'SHELL': '/bin/bash', 07:33:33 INFO - 'SHLVL': '1', 07:33:33 INFO - 'TERM': 'linux', 07:33:33 INFO - 'TMOUT': '86400', 07:33:33 INFO - 'USER': 'cltbld', 07:33:33 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:33 INFO - '_': '/tools/buildbot/bin/python'} 07:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:33 INFO - Downloading/unpacking blobuploader==1.2.4 07:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:35 INFO - Downloading blobuploader-1.2.4.tar.gz 07:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:33:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:33:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:36 INFO - Downloading docopt-0.6.1.tar.gz 07:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:33:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:33:36 INFO - Running setup.py install for blobuploader 07:33:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:33:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:33:36 INFO - Running setup.py install for requests 07:33:36 INFO - Running setup.py install for docopt 07:33:36 INFO - Successfully installed blobuploader requests docopt 07:33:36 INFO - Cleaning up... 07:33:36 INFO - Return code: 0 07:33:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:36 INFO - Using env: {'DISPLAY': ':0', 07:33:36 INFO - 'HOME': '/home/cltbld', 07:33:36 INFO - 'LANG': 'en_US.UTF-8', 07:33:36 INFO - 'LANGUAGE': 'en_US:en', 07:33:36 INFO - 'LOGNAME': 'cltbld', 07:33:36 INFO - 'MAIL': '/var/mail/cltbld', 07:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:36 INFO - 'MOZ_NO_REMOTE': '1', 07:33:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:36 INFO - 'NO_EM_RESTART': '1', 07:33:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:36 INFO - 'PWD': '/builds/slave/test', 07:33:36 INFO - 'SHELL': '/bin/bash', 07:33:36 INFO - 'SHLVL': '1', 07:33:36 INFO - 'TERM': 'linux', 07:33:36 INFO - 'TMOUT': '86400', 07:33:36 INFO - 'USER': 'cltbld', 07:33:36 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:36 INFO - '_': '/tools/buildbot/bin/python'} 07:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:37 INFO - Running setup.py (path:/tmp/pip-KwZtqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:37 INFO - Running setup.py (path:/tmp/pip-m_0w_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:37 INFO - Running setup.py (path:/tmp/pip-V8aMxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:37 INFO - Running setup.py (path:/tmp/pip-02Sdlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:37 INFO - Running setup.py (path:/tmp/pip-3lu9J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:37 INFO - Running setup.py (path:/tmp/pip-ZJWav8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:37 INFO - Running setup.py (path:/tmp/pip-DCFyk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:37 INFO - Running setup.py (path:/tmp/pip-JPEeMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:37 INFO - Running setup.py (path:/tmp/pip-HEL4vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:37 INFO - Running setup.py (path:/tmp/pip-eaoVGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:37 INFO - Running setup.py (path:/tmp/pip-GHxhwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:37 INFO - Running setup.py (path:/tmp/pip-2VI5SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:37 INFO - Running setup.py (path:/tmp/pip-OC3tfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:37 INFO - Running setup.py (path:/tmp/pip-K3odiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:38 INFO - Running setup.py (path:/tmp/pip-kamaCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:38 INFO - Running setup.py (path:/tmp/pip-wK6kiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:38 INFO - Running setup.py (path:/tmp/pip-sLnMj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:33:38 INFO - Running setup.py install for manifestparser 07:33:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:33:38 INFO - Running setup.py install for mozcrash 07:33:38 INFO - Running setup.py install for mozdebug 07:33:38 INFO - Running setup.py install for mozdevice 07:33:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:33:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:33:38 INFO - Running setup.py install for mozfile 07:33:38 INFO - Running setup.py install for mozhttpd 07:33:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:33:38 INFO - Running setup.py install for mozinfo 07:33:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:33:38 INFO - Running setup.py install for mozInstall 07:33:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Running setup.py install for mozleak 07:33:39 INFO - Running setup.py install for mozlog 07:33:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Running setup.py install for moznetwork 07:33:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Running setup.py install for mozprocess 07:33:39 INFO - Running setup.py install for mozprofile 07:33:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Running setup.py install for mozrunner 07:33:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:33:39 INFO - Running setup.py install for mozscreenshot 07:33:39 INFO - Running setup.py install for moztest 07:33:39 INFO - Running setup.py install for mozversion 07:33:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:33:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:33:40 INFO - Cleaning up... 07:33:40 INFO - Return code: 0 07:33:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:33:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:40 INFO - Using env: {'DISPLAY': ':0', 07:33:40 INFO - 'HOME': '/home/cltbld', 07:33:40 INFO - 'LANG': 'en_US.UTF-8', 07:33:40 INFO - 'LANGUAGE': 'en_US:en', 07:33:40 INFO - 'LOGNAME': 'cltbld', 07:33:40 INFO - 'MAIL': '/var/mail/cltbld', 07:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:40 INFO - 'MOZ_NO_REMOTE': '1', 07:33:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:40 INFO - 'NO_EM_RESTART': '1', 07:33:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:40 INFO - 'PWD': '/builds/slave/test', 07:33:40 INFO - 'SHELL': '/bin/bash', 07:33:40 INFO - 'SHLVL': '1', 07:33:40 INFO - 'TERM': 'linux', 07:33:40 INFO - 'TMOUT': '86400', 07:33:40 INFO - 'USER': 'cltbld', 07:33:40 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:40 INFO - '_': '/tools/buildbot/bin/python'} 07:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:33:40 INFO - Running setup.py (path:/tmp/pip-6Y_Eod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:33:40 INFO - Running setup.py (path:/tmp/pip-wNrsel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:33:40 INFO - Running setup.py (path:/tmp/pip-WLTP5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:33:40 INFO - Running setup.py (path:/tmp/pip-L3FWt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:33:40 INFO - Running setup.py (path:/tmp/pip-gQ_H5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:33:40 INFO - Running setup.py (path:/tmp/pip-OLoPLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:33:40 INFO - Running setup.py (path:/tmp/pip-jER9YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:33:40 INFO - Running setup.py (path:/tmp/pip-VGQbmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:33:40 INFO - Running setup.py (path:/tmp/pip-6i0PU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:33:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:33:40 INFO - Running setup.py (path:/tmp/pip-Q_tIRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:33:41 INFO - Running setup.py (path:/tmp/pip-ewWhCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:33:41 INFO - Running setup.py (path:/tmp/pip-N5mGei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:33:41 INFO - Running setup.py (path:/tmp/pip-A5S_Pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:33:41 INFO - Running setup.py (path:/tmp/pip-I4QmWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:41 INFO - Running setup.py (path:/tmp/pip-tGMPUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:33:41 INFO - Running setup.py (path:/tmp/pip-8TQKNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:33:41 INFO - Running setup.py (path:/tmp/pip-ymC41h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:33:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:33:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:33:43 INFO - Downloading blessings-1.6.tar.gz 07:33:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:33:43 INFO - Installing collected packages: blessings 07:33:43 INFO - Running setup.py install for blessings 07:33:43 INFO - Successfully installed blessings 07:33:43 INFO - Cleaning up... 07:33:43 INFO - Return code: 0 07:33:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:33:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:43 INFO - Reading from file tmpfile_stdout 07:33:43 INFO - Current package versions: 07:33:43 INFO - argparse == 1.2.1 07:33:43 INFO - blessings == 1.6 07:33:43 INFO - blobuploader == 1.2.4 07:33:43 INFO - docopt == 0.6.1 07:33:43 INFO - functools32 == 3.2.3-2 07:33:43 INFO - jsonschema == 2.5.1 07:33:43 INFO - manifestparser == 1.1 07:33:43 INFO - mozInstall == 1.12 07:33:43 INFO - mozcrash == 1.0 07:33:43 INFO - mozdebug == 0.1 07:33:43 INFO - mozdevice == 0.49 07:33:43 INFO - mozfile == 1.2 07:33:43 INFO - mozhttpd == 0.7 07:33:43 INFO - mozinfo == 0.9 07:33:43 INFO - mozleak == 0.1 07:33:43 INFO - mozlog == 3.4 07:33:43 INFO - moznetwork == 0.27 07:33:43 INFO - mozprocess == 0.23 07:33:43 INFO - mozprofile == 0.28 07:33:43 INFO - mozrunner == 6.13 07:33:43 INFO - mozscreenshot == 0.1 07:33:43 INFO - mozsystemmonitor == 0.3 07:33:43 INFO - moztest == 0.7 07:33:43 INFO - mozversion == 1.4 07:33:43 INFO - psutil == 3.1.1 07:33:43 INFO - requests == 1.2.3 07:33:43 INFO - wsgiref == 0.1.2 07:33:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:33:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:33:43 INFO - Using env: {'DISPLAY': ':0', 07:33:43 INFO - 'HOME': '/home/cltbld', 07:33:43 INFO - 'LANG': 'en_US.UTF-8', 07:33:43 INFO - 'LANGUAGE': 'en_US:en', 07:33:43 INFO - 'LOGNAME': 'cltbld', 07:33:43 INFO - 'MAIL': '/var/mail/cltbld', 07:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:43 INFO - 'MOZ_NO_REMOTE': '1', 07:33:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:43 INFO - 'NO_EM_RESTART': '1', 07:33:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:43 INFO - 'PWD': '/builds/slave/test', 07:33:43 INFO - 'SHELL': '/bin/bash', 07:33:43 INFO - 'SHLVL': '1', 07:33:43 INFO - 'TERM': 'linux', 07:33:43 INFO - 'TMOUT': '86400', 07:33:43 INFO - 'USER': 'cltbld', 07:33:43 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:43 INFO - '_': '/tools/buildbot/bin/python'} 07:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:33:44 INFO - Cleaning up... 07:33:44 INFO - Return code: 0 07:33:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e69630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:33:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:33:44 INFO - Using env: {'DISPLAY': ':0', 07:33:44 INFO - 'HOME': '/home/cltbld', 07:33:44 INFO - 'LANG': 'en_US.UTF-8', 07:33:44 INFO - 'LANGUAGE': 'en_US:en', 07:33:44 INFO - 'LOGNAME': 'cltbld', 07:33:44 INFO - 'MAIL': '/var/mail/cltbld', 07:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:44 INFO - 'MOZ_NO_REMOTE': '1', 07:33:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:33:44 INFO - 'NO_EM_RESTART': '1', 07:33:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:44 INFO - 'PWD': '/builds/slave/test', 07:33:44 INFO - 'SHELL': '/bin/bash', 07:33:44 INFO - 'SHLVL': '1', 07:33:44 INFO - 'TERM': 'linux', 07:33:44 INFO - 'TMOUT': '86400', 07:33:44 INFO - 'USER': 'cltbld', 07:33:44 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:44 INFO - '_': '/tools/buildbot/bin/python'} 07:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:33:44 INFO - Cleaning up... 07:33:44 INFO - Return code: 0 07:33:44 INFO - Running post-action listener: _resource_record_post_action 07:33:44 INFO - Running post-action listener: _start_resource_monitoring 07:33:44 INFO - Starting resource monitoring. 07:33:44 INFO - [mozharness: 2016-12-23 15:33:44.451891Z] Finished create-virtualenv step (success) 07:33:44 INFO - [mozharness: 2016-12-23 15:33:44.452429Z] Running install step. 07:33:44 INFO - Running pre-action listener: _resource_record_pre_action 07:33:44 INFO - Running main action method: install 07:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:44 INFO - Reading from file tmpfile_stdout 07:33:44 INFO - Detecting whether we're running mozinstall >=1.0... 07:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:33:44 INFO - Reading from file tmpfile_stdout 07:33:44 INFO - Output received: 07:33:44 INFO - Usage: mozinstall [options] installer 07:33:44 INFO - Options: 07:33:44 INFO - -h, --help show this help message and exit 07:33:44 INFO - -d DEST, --destination=DEST 07:33:44 INFO - Directory to install application into. [default: 07:33:44 INFO - "/builds/slave/test"] 07:33:44 INFO - --app=APP Application being installed. [default: firefox] 07:33:44 INFO - mkdir: /builds/slave/test/build/application 07:33:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:34:00 INFO - Reading from file tmpfile_stdout 07:34:00 INFO - Output received: 07:34:00 INFO - /builds/slave/test/build/application/firefox/firefox 07:34:00 INFO - Running post-action listener: _resource_record_post_action 07:34:00 INFO - [mozharness: 2016-12-23 15:34:00.863707Z] Finished install step (success) 07:34:00 INFO - [mozharness: 2016-12-23 15:34:00.863955Z] Running run-tests step. 07:34:00 INFO - Running pre-action listener: _resource_record_pre_action 07:34:00 INFO - Running main action method: run_tests 07:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:34:00 INFO - Python 2.7.3 07:34:00 INFO - Return code: 0 07:34:00 INFO - grabbing minidump binary from tooltool 07:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:34:00 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef2850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e676d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e67b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:34:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:34:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:34:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:34:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:34:00 INFO - Return code: 0 07:34:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:34:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:34:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:34:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:34:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:34:00 INFO - Using env: {'DISPLAY': ':0', 07:34:00 INFO - 'HOME': '/home/cltbld', 07:34:00 INFO - 'LANG': 'en_US.UTF-8', 07:34:00 INFO - 'LANGUAGE': 'en_US:en', 07:34:00 INFO - 'LOGNAME': 'cltbld', 07:34:00 INFO - 'MAIL': '/var/mail/cltbld', 07:34:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:34:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:34:00 INFO - 'MOZ_NO_REMOTE': '1', 07:34:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:34:00 INFO - 'NO_EM_RESTART': '1', 07:34:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:34:00 INFO - 'PWD': '/builds/slave/test', 07:34:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:34:00 INFO - 'SHELL': '/bin/bash', 07:34:00 INFO - 'SHLVL': '1', 07:34:00 INFO - 'TERM': 'linux', 07:34:00 INFO - 'TMOUT': '86400', 07:34:00 INFO - 'USER': 'cltbld', 07:34:00 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888', 07:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:34:00 INFO - '_': '/tools/buildbot/bin/python'} 07:34:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:34:01 INFO - mozversion application_buildid: 20161223062836 07:34:01 INFO - mozversion application_changeset: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 07:34:01 INFO - mozversion application_display_name: Nightly 07:34:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:34:01 INFO - mozversion application_name: Firefox 07:34:01 INFO - mozversion application_remotingname: firefox 07:34:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:34:01 INFO - mozversion application_vendor: Mozilla 07:34:01 INFO - mozversion application_version: 53.0a1 07:34:01 INFO - mozversion platform_buildid: 20161223062836 07:34:01 INFO - mozversion platform_changeset: bbbd2f7539f224a482cc6d2dd10e6a5f31c8baf3 07:34:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:34:01 INFO - mozversion platform_version: 53.0a1 07:34:01 INFO - using testdate: 1482507241 07:34:01 INFO - actual date: 1482507241 07:34:01 INFO - starting webserver on 'localhost:40698' 07:34:01 INFO - SUITE-START | Running 2 tests 07:34:01 INFO - TEST-START | dromaeo_css 07:34:01 INFO - operating with platform_type : linux_ 07:34:01 INFO - Initialising browser for dromaeo_css test... 07:34:01 INFO - TEST-INFO | started process 23224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVxnArn/profile http://localhost:40698/getInfo.html) 07:34:01 INFO - PROCESS | 23224 | 1482507241645 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:34:04 INFO - PROCESS | 23224 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:34:04 INFO - PROCESS | 23224 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:34:05 INFO - PROCESS | 23224 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:34:05 INFO - PROCESS | 23224 | __metrics Screen width/height:1600/1200 07:34:05 INFO - PROCESS | 23224 | colorDepth:24 07:34:05 INFO - PROCESS | 23224 | Browser inner width/height: 1024/697 07:34:05 INFO - PROCESS | 23224 | __metrics 07:34:05 INFO - PROCESS | 23224 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:34:05 INFO - PROCESS | 23224 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:34:06 INFO - PROCESS | 23224 | console.error: PushService: 07:34:06 INFO - PROCESS | 23224 | stateChangeProcessEnqueue: Error transitioning state 07:34:06 INFO - PROCESS | 23224 | UnknownError 07:34:07 INFO - TEST-INFO | 23224: exit 0 07:34:07 INFO - Browser initialized. 07:34:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:34:07 INFO - TEST-INFO | started process 23307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVxnArn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:19 INFO - PROCESS | 23307 | RSS: Main: 151502848 07:34:19 INFO - PROCESS | 23307 | 07:35:11 INFO - PROCESS | 23307 | 1482507311150 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:37:23 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40698/tests/dromaeo/cssquery-ext.html) 07:37:25 INFO - PROCESS | 23307 | RSS: Main: 267476992 07:37:25 INFO - PROCESS | 23307 | 07:39:07 INFO - PROCESS | 23307 | ************************* 07:39:07 INFO - PROCESS | 23307 | A coding exception was thrown and uncaught in a Task. 07:39:07 INFO - PROCESS | 23307 | 07:39:07 INFO - PROCESS | 23307 | Full message: ReferenceError: fetch is not defined 07:39:07 INFO - PROCESS | 23307 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:39:07 INFO - PROCESS | 23307 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:39:07 INFO - PROCESS | 23307 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:39:07 INFO - PROCESS | 23307 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:39:07 INFO - PROCESS | 23307 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:39:07 INFO - PROCESS | 23307 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:39:07 INFO - PROCESS | 23307 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:39:07 INFO - PROCESS | 23307 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:39:07 INFO - PROCESS | 23307 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:39:07 INFO - PROCESS | 23307 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:39:07 INFO - PROCESS | 23307 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:39:07 INFO - PROCESS | 23307 | 07:39:07 INFO - PROCESS | 23307 | ************************* 07:40:27 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-ext.html (next: http://localhost:40698/tests/dromaeo/cssquery-jquery.html) 07:40:28 INFO - PROCESS | 23307 | RSS: Main: 239755264 07:40:28 INFO - PROCESS | 23307 | 07:43:30 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40698/tests/dromaeo/cssquery-mootools.html) 07:43:31 INFO - PROCESS | 23307 | RSS: Main: 212422656 07:43:31 INFO - PROCESS | 23307 | 07:46:33 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40698/tests/dromaeo/cssquery-prototype.html) 07:46:34 INFO - PROCESS | 23307 | RSS: Main: 209465344 07:46:34 INFO - PROCESS | 23307 | 07:49:37 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40698/tests/dromaeo/cssquery-yui.html) 07:49:37 INFO - PROCESS | 23307 | RSS: Main: 187621376 07:49:37 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | Cycle 1(1): loaded http://localhost:40698/tests/dromaeo/cssquery-yui.html (next: http://localhost:40698/tests/dromaeo/cssquery-dojo.html) 07:52:41 INFO - PROCESS | 23307 | RSS: Main: 282554368 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | __start_tp_report 07:52:41 INFO - PROCESS | 23307 | _x_x_mozilla_page_load 07:52:41 INFO - PROCESS | 23307 | _x_x_mozilla_page_load_details 07:52:41 INFO - PROCESS | 23307 | |i|pagename|runs| 07:52:41 INFO - PROCESS | 23307 | |0;dojo.html;2573.4209802794367;2595.4290056187656;2606.856622885741;2621.882015309311;2630.526505229058;4592.632589392849;4661.184292748772;4696.060787842428;4719.952800471999;4737.147313483571;4619.74591397473;4641.303804429337;4675.46232183299;4683.812647494096;4717.315989181567;4316.287812510932;4337.067530480946;4377.058932329549;4404.2512772828695;4469.843555475559;3955.8417663293435;4334.241507736149;4388.429504164455;4407.801648925806;4468.285074388083;4185.393117997874;4220.978895105535;4334.198173337911;4340.65274778017;4421;4235.745855248695;4241.045764702948;4246.766427846467;4273.18809426208;4321.416608757809;4287.399764033038;4299.355096735483;4305.9784701076605;4306.676999225039;4349.978250108761;4618.422697162855;4659.44086709597;4660.930086048678;4665.136949664289;4690.695104818176;4821.204501257288;4862.927056094161;4909.288153217799;4939.604831613498;4945.901081978341;4992.375953005874;5002.24966255061;5005.724685142316;5008.649394542384;5052.494750524955;4686.2267725825195;4688.9531104689195;4689.296605509201;4729.929051064237;4736.384270044872;4978.800847966041;5027.069992051483;5030.496950304941;5088.821891233863;5109.105906466353;4900.289458028566;4905.583025442877;4913.557779799835;4959.776810043557;4960.950390496121;4864.440589332272;4909.116359055333;4999;5010.298558201855;5062.519060689228;4903.190973489334;4916.803327866917;4938.308636790852;4945.876353091202;4997.925031124536;4758.714477131383;4796.424429068534;4836.806527738921;4949.57928576075;4950;4169.124483858363;4188.518320393133;4196.8111434984885;4202.285611446062;4240.194363071007;3649.9817500912327;3655.4151335786146;3655.8354874030733;3677.3748462761396;3696.6488183622514;4257.787110644455;4338.956610433918;4407.47110346754;4412.691111622155;4455.68810183284;4893.266010098514;5008.17365134756;5036.11867923112;5042.294078828967;5103.260027296022;4374.606285434326;4396;4397.846075387346;4401.449818772653;4419.315006174048;4486.7083639563325;4499.550044995474;4503.639708823286;4509.391232183613;4536.795844186953;4587.885302867522;4625.861224163281;4671.672982891165;4710.69380490267;4716.198246298206;4990.6506544541535;4995.875103122451;4996.575291100152;5001.474845141208;5009.023240468074;4699.882502937454;4700.2244629635725;4704.6236301096515;4742.383490146259;4745.549172828576;4826.565609095198;4872.123017856819;4889.511048895082;4891.437484689306;4917.213245880785;5013.373328333958;5025.472325405926;5027.49725027509;5059.898802024012;5071.1632580625255;4684.812607495798;4860.6111511078325;4872.902541949212;4878.9512104878495;4923.901521969612;4698.671093023456;4747.905041899212;4862.975685121552;4881.48744381849;4889;4828.975855120842;4845.176320025534;4855.660103792842;4887.828925987574;4903.019396120719;4928.926066109083;4942.728149951788;4942.777575009205;4946.233333833262;4953.257011448168;4675.625949924066;4688.085823264567;4714.22215334466;4729.290606409066;4814.662973591815 07:52:41 INFO - PROCESS | 23307 | |1;ext.html;521.0100808463918;537.6424677589404;543.1635281666235;549.6290004247132;554.9472800083996;29221.561676574867;37621.81189094054;39552.60447395525;39617.20765584687;43581;41076.58923410765;41481.17037659245;41489.58510414895;41511.79244103775;41601.16797664045;7440.441966852508;7714.884276735839;7895.96052019742;7907.051153861545;7913.841723165562;7598.1262154852075;9597.088276613746;9831;9832.164266037395;10112.595496180144;10216.897831021706;10286.537105830255;10288.897111028906;10292.794144117113;10305.536250868692;10113.443760593165;10158.695239142837;10185.847212291821;10190.541425635864;10192.694219173436;9859.802803943952;9894.851577226347;9923.702288931343;9932.801343973153;9946.751331216705;38993.805030974945;39324.60675393238;39384.80307598443;39423;39457.21085578272;2983;3039.924001899948;3185.490321548541;3260.7391408687486;3351.463765797461;28812.42375152506;29239.56140657892;29261.56107658387;29314.85342573295;30048;3360.3447327771096;3408.5568876045954;3421.2815308785184;3427.845746941394;3463.4977928200524;260184;261015;261166;261494;261954;53376.199357009675;53395.73302133464;53434;53612.19581706278;53736.462635373144;55054;55064.72467637636;55069;55109.448905510435;55157.724211378685;13511.121777084458;13888.583342499742;13935.512257070954;13975.301234938212;14012.929935350257;9031.774205644911;9363.625454981724;10431.69603799525;10679.83980240297;10805.351678899291;15216.771748423782;15715.371385144466;15865.682686346208;16012.279447424893;16030.679386412206;5949.25634295713;5955.434233747787;5986.431289027535;6053.4551890330085;6090.512758979271;792.469045739356;866.8353012927523;867.9262262707614;870.4690139015196;916.9633214671472;27412.193768334037;27780.16659500218;27896.302592435353;28078.43843123126;28419.857900710365;900.6307413960245;954.627695198873;966.6954909203719;967.2020583018897;1019.1388276906002;13709.931450342883;13788.586342409744;14415.63960900965;14497.92751036238;14577.781333280009;265826;369480;369553;370862;372548;43799.78100109543;43935;43998.560014400085;44039.77980110143;44098.77950610226;1202.0079218792819;1202.0623913347604;1233.2785320587557;1234.2656119608819;1235.1045492018216;24759.752402475744;25624;26373.34066648349;26378.472430550897;26646.333841654116;38313.616863831005;38510.2297954045;38605.613943860204;38654.80672596619;38674.22651546897;263295;263361.98061184154;263961;265262;340040;21217.257395991684;21832.126714931226;23298.417539561648;23307.3007809766;23705.762942370355;36288.4556731644;36841.63158368382;37015.81492092522;39791.60208397879;39934.80032599935;31168.376632467676;31536;31693;31783;32966.175845603095;31255;31736.841315794198;31903.36193276168;31945.201369965936;32141.517877232327;31545.053648390585;31598.210044749067;31668.524972124975;31808.363832722753;32131.51802723008;26557.68846230723;27950.720492795626;29400.330934391226;29447.558286625288;30068.54897176588;22523.32430027102;22668.546629066997;23149.3055208344;23226.3032109037;23291.88354058219 07:52:41 INFO - PROCESS | 23307 | |2;jquery.html;370.268719279423;378.04920624629057;380.2831662316534;390.190355013347;396.986105486308;15537.378504859806;16199.433019844308;16837.326506939724;16938.491845244633;16975.236114374853;12989.895858851985;15198.848011519882;16764.58088547787;17005.82994170058;17047.74428383572;4573.954260457394;4797.7121372717575;4801.7118972861745;4803.423589169304;4809.735464549449;3701.3152566775098;3855.9228815423676;4763.285507173917;4806.158922188621;5264.368275806909;5460.290162278918;5559.8888022239535;5566.916496252559;5584.441555844423;5591.664500130005;5322.680639161663;5530.170474428827;5549.639273447213;5553.750081246354;5598.24423702799;5502.614816962814;5503.559715222773;5503.944960550383;5507;5509.311336082989;12148.878511214863;12658.05064620148;12716.300603466805;12729.681757956125;12731.872681273162;1884.896330701811;1905.9714104288446;1975.9506012349661;2161.6217161996587;2236.910523579055;8941.642334306685;9053.90946090544;9236.491992940386;9247.167754902091;9468.242540596735;1668.3743596151444;1740.321274702867;1747.9563010924828;1762.903040332781;1798.2986635212278;60958.39041609572;61559;61569.692151538955;61913.690431548006;62230.37769622336;8027.15714849944;8433.28317093054;8452.1547845215;8519.318454523624;8642.956785216034;10936.179786516042;10986.615468458565;10998.890011099948;11001.724956876142;11017.724556885982;6496.6426846522945;6592.24189218246;6674.564968531789;6826.078479405316;6840.657967101625;6661.666916654148;6680.498962577826;6891.483138764513;6895.275996020346;6903.413209877116;3325.48454989477;3336.7487192302888;3397.4733916242526;3499;3524.3303772283184;1642.3758971590719;1651.8265582113945;1704.6164612961984;1739.5390221591288;1744.2499725118066;784.7572560282936;861.3884142259067;884.1777147253116;976.1800087926176;976.6337623391229;15852.920735396248;16139.112348820692;16427.260773265232;16525.58686032859;17097.914510427367;832.6003518311123;855.4525103933488;871.1158174452935;871.5555066915919;892.0811564088999;8954.552272386354;9514.714558563253;9550.474723890115;9579.329446938787;9699.51502424876;335230;336471;336916;337622;341742;54436.727816361454;55532.16701749477;57106.14340784892;59140;75920;906.9455832650062;916.5417291354323;929.0291645230615;958.4393130019017;963.5712108111823;14976.326065326868;15309.923450382677;15629.296681649581;15764.842351576337;15871.52385428439;35006.299874002885;35708.821455892554;35716.64283357237;35846.46230306599;36234.456483152244;46856.297155543376;47397.763011184725;49454.75272623614;49765.751171243915;49917;7342;7406.222346653523;7415.2214017527385;7428.702851885864;7460.626968651654;10868.913108689068;11054.668359949215;11124.443777811239;11338.886611134112;11924;9167.12912273333;9716.805663886824;9736.367136136128;9814.803703925738;9893.851592225978;8937.687180948635;9573.617055317709;9686.128248457673;9730.513474326397;9894.971530836017;9719;9835.508224588884;10105.39367637944;10165;10331.535080921227;7599.962000189964;7644.235576442534;7678.232176782501;7933.404994625311;7943.682252709827;7791.532508049535;9189.908100919172;9364.765880853032;9494.525273736148;9517.381370211191 07:52:41 INFO - PROCESS | 23307 | |3;mootools.html;1315.45408655408;1340.4839136932278;1423.6085076603933;1655.5695519165022;1658.1543412859453;7035.71857125715;7508.249175082489;7513.060867391576;7513.323800857922;7515.173330933607;6647.700853461593;6841.5552989055805;6849.623270720109;6987.755428560002;7404.888926666104;1911.3023746332628;2067.400453868376;2122.8195603373656;2125.5855108253895;2148.430665873545;2149.8280137589036;2151.9139234430604;2154.870707757532;2158.104386679526;2160.762316145223;2094.110003248621;2107.030765847704;2108.441263065289;2109.683547467877;2151.6234658934623;2038.2764118737857;2050.0569737920573;2052.7126202331688;2053.938381848532;2062.2266650006172;2107.1255428996947;2122.9150833966623;2132.76539580646;2152.547964927367;2155.698202251686;7412.629368531606;7447.2552744725635;7553.735619253354;7561.962190189069;7574.053243344582;4386.56134386562;4514.435695538058;4541.046380260149;4546.249868771682;4546.818127274905;2900.1879473747385;2923.8830446782104;2953.9261518461994;3013.442513135077;3016.125323656137;910.1444642036464;921.5162039929091;930.9534523273809;932.021377553566;948.8671585977969;9370.812583748288;9375.015623359586;9422.057794220591;9488.146066854017;9490.90509094907;4102.528209255974;4138.193052354822;4301.591348821857;4312.331588603772;4354.822588705647;7916.920830791619;7969.760907172794;7984.24149705777;7995.800104997422;8012.959935200403;4445.911081778411;4453.688241823106;4464.352668862996;4465.843695470643;4471.32930060487;4059.289624315792;4112.177564487115;4114.629683328515;4221.936670949938;4265.4241677373775;3163.920901977423;3218.436773564617;3249.6425393206728;3320.8007519548905;3431.673990970854;1029.7940411917646;1031.0874875735028;1031.1130630945352;1054.6835949215206;1055.6885718712997;787.8896954426385;811.8213992921548;837.1210229259253;843.9282660973886;867.4014929698487;4771.785269662873;4784.354112194809;4802.639802014863;4835.588974937099;4920.040592084581;827.230675471805;827.5903427803156;856.9700060497853;862.5816479007676;912.6075787411344;3105;3343.7157841583253;3406.6763657453;3415.7950522968204;3519.63043880394;8546.102659220693;8874;9013.324000699973;9050.547472626342;9077.546122693839;8998.370114091951;9006.684766033157;9012.369134160677;9017.458952462874;9019.458832470073;6672.833179170462;6845.212800527904;6896.793096207121;6909.516333856683;6919.827004324932;4885.682430641997;4917.631177661761;4951;4972.477889821661;4974.253861920741;8202.712905048294;8267.62795674184;8378.49729016261;8406.957965210135;8433.367497437594;8411.621477033426;8434.32525397979;8488.618012189343;8506.829863402822;8628.654853805778;6108.969455152695;6191.783287585093;6204.038374052029;6221.47117495018;6228.813135605939;6218.129461875432;6467.514936379696;6665.900011499928;6928.4803639726215;7015.193252775996;6467.320931302145;6477.125588045742;6485;6488.351164883474;6531.771388001587;6198.845028874134;6254.937450625617;6294.842628934313;6407.167068281094;6441.323661015714;5700.629459085146;5810.622309549865;5818.505427038749;5823.505002074871;5843.590948633725;4241.87274381769;4267.338562522814;4276.465441819772;4313.633341166036;4405.7797110145;4249.46881639795;4285.828566857291;4292.399064130962;4329.913401731885;4384.9123017540105 07:52:41 INFO - PROCESS | 23307 | |4;prototype.html;351.98803440109685;356.86795885522355;360.6898067661811;368.4712437651968;370.2835014247433;4817.20516114841;4861.343718597988;4873.853784386465;4888.462269150391;4904.926426103611;4562.357551281544;4620.330052142437;4692.741899195551;4699.436067671884;4781.855315919045;3842.769433833972;3857.305684976703;3862.555806082308;3890.3775395936655;3899.337112690835;3867.245887052026;3887.300285948528;3889.766614003169;3894.610538946111;3912.2762288976487;3656;3672.7245456590863;3734.626537346257;3874.341361968472;3882.0683036071423;3851.5763266040713;3854.672352850011;3869.9226015479535;3879.4568760373572;3883.8058097095313;3507.1232191952013;3509.78941263525;3512.0868574170645;3518.700910422617;3524.9647503524893;4556.453225612948;4722.787474563653;4734.19518681825;4735.247958255468;4745.83389581363;4386.363977223285;4436.201483732927;4442.955570444286;4453.465584129893;4456.108778244332;5022.648414610979;5048.7980480780725;5060.342155519796;5104.03023425548;5107.033791990073;3845.9230815383817;3848.4034974578985;3849.441830934527;3851.9614803852164;3862.942055869164;5034;5164.354455693038;5173.586113110942;5191.636585439021;5231.241469986869;3876.592957739453;3887.3391523441082;3898.6491215790716;3899.590542992945;3901.180752042046;3956.208758248362;3957.4261732049267;3958.5051868516434;3961.623645753649;3964.1675248197625;4281.785910704452;4360.672949528798;4393.538678438782;4405.7797110144365;4412.713173643767;4944;4968.03762513805;4977.303177555145;4982.437557146657;5023.547880690682;1292.1665525735848;1295.6177927511408;1301.9414126364336;1306.4186437035619;1312.7637025335341;900.7477906186278;921.1709461484584;961.2838435365697;963.691618682015;973.3478569358547;4251.914961700749;4273.55127711592;4311.1808756336195;4312.921769557611;4317.201317756162;4987.401511818605;4993.725345106054;4998.925016124761;4999.250112483084;5007.349044624249;4138.565450627701;4326.891827704269;4335.458067741532;4353.804078816461;4366.585174408426;2598.636190933271;2600.4279058607453;2646.192911162114;2664.7335266473583;2666.066876593177;4594.88512787183;4602.194615942197;4673;4709.411323584552;4717.905641887143;4959.479254678206;5066.858547700449;5084.161113416245;5085.898282034339;5266.262723218752;4876.658633895664;4902.289168070681;4918.67156901924;4932.26016097581;4934.802607895644;5162.432132465351;5170.922436163385;5177.430482646831;5193.68837869729;5198.350206224222;4921.9507804921495;5026.773795179299;5065.13892638245;5087.135187018142;5093.821716239914;4885.193942999365;4888.584470320063;4921.286413469955;4931.654784165074;4938.160512712776;3639.9090022748583;3664.5968943416738;3665.138692407229;3674.338619048597;3676.7793932364143;4990.326305948651;5149.639525233348;5230.34620672416;5263.36839579253;5264.236685680478;4996.725180115129;5020.598352131894;5045.217991211368;5057.671251368649;5060.266261392004;5007.874803129951;5025.1959686451455;5053;5060.139776238122;5070.797168113234;4805.7597120144555;4925.729084900365;4952.653314267966;4964.404271487445;5027.472115427974;4828.806847726051;4927.575335142042;4932.334134891744;5052.72210028452;5055.51972562606;4931.827386041615;4933.728644924564;4940.580050695731;4949.490405426235;4951.356323678043 07:52:41 INFO - PROCESS | 23307 | |5;yui.html;645.8805121052603;671.2783757460729;688.6109348218256;700.4816435837479;741.4920779266201;12734.554290599819;14015.719685606282;14016;14123.788143177848;14141.080829746072;12480.687982800435;13499.785019348257;13586.25275609841;13784.655383615414;13801.171929684226;801.6152246921496;813.5200231863199;820.6266148902235;820.6307161777224;820.63481750621;824.6412810427453;825.781167990483;826.409117481001;827.5779352530193;827.8758186272078;865.2602025268368;867.319154463746;869.1395518436765;871.4814685262259;872.759991002473;860.7934095817021;860.9354298427612;861.246409391779;861.5649097205871;862.3489265604492;635.2345423764399;635.6726285962733;635.8060791458603;635.8124353315783;636.0776873533349;11691.7077073073;11824.23142495735;11847.763044739144;12163.878361216362;12199.634010979686;9237.861432078524;10402.479876006246;10791.730206744816;11609.593664221711;11868.050555955502;9614.182794462478;9656;9790.118889299998;9879.703608891745;10004.049615286609;3230.3746323492974;3263.9836800816083;3264.8367581620823;3333;3335.849886755102;62577;62658.37341626571;63305.36694633041;63597.36402636007;63820.68089659568;8226.383021273428;8532.445391049561;8668.219860212612;8709.303255739525;8904.688335908211;16920.32318707238;17449.12754362277;17545.649087018188;17549.473515794547;17663;6547.312532184147;6838.760643377458;6850.520463567503;6868.759593414206;7011.824704382431;3442.1910850950007;3482.3035392921515;3512.4906888501023;3542;3573.2496175802853;2811.5079861024647;2871.913842584684;2882.1497658190883;3187.3306605612615;3337.6662333766913;538.21689441862;540.8674874655735;541.7507946344647;546.9699166545878;554.5646667366099;565.5645153231989;585.6573904265966;597.8864015837064;611.9847003824851;629.2386212682705;8104.878426823602;8442.07137214906;8531;8666.31681535345;8980.416272942239;580.9666168410563;592.5970340168641;599.9440983868336;612.9233845769279;613.1018058544254;3940.822662980173;3967.6429121379215;3994.980025099914;4012.719109662354;4149.502059752849;68906.310936891;69154;69300.30699693039;71814;72677.63661181767;60088.69955650194;60389.396106039254;60675.393246067855;61553.69223153855;63376.68311658412;51511.48488515067;51595.484045159064;52236.73881630644;53410.46589534132;53576;7485.625718713934;7899.486533375312;8150.5924703763385;8328.708495202638;8352.290055345364;38665.6133438662;38770.6122938767;38892.6110738889;38984.02539936411;39537.80231098826;50915;53031;53496.650334966194;53497;53855;9552.761180970532;9903.702888913056;10190.43952582615;10310.32982856112;10412.635557755442;13031.348432578523;13038.804417933548;13113.541026064042;14501;14593.270336482921;11343.943280283545;11815.350155741517;12527.37363131858;12572.057095717675;12918.935405322913;12619.053570982029;12944.09391342597;13000.414981326052;13173.868261317264;13726.519571814939;10332.276740628084;10685.893141068489;10898.78202435931;10937.12502999774;10963.56145754161;10000.949995249977;10246.692599222035;10351.223658225814;10522.579096836042;10552.630657926935;9117.589708463267;9698.27262955267;10184.200324065318;10185.74535636615;10418.843717344398 07:52:41 INFO - PROCESS | 23307 | __end_tp_report 07:52:41 INFO - PROCESS | 23307 | __start_cc_report 07:52:41 INFO - PROCESS | 23307 | _x_x_mozilla_cycle_collect,1154 07:52:41 INFO - PROCESS | 23307 | __end_cc_report 07:52:41 INFO - PROCESS | 23307 | __startTimestamp1482508361350__endTimestamp 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | ------- Summary: start ------- 07:52:41 INFO - PROCESS | 23307 | Number of tests: 6 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#0] dojo.html Cycles:165 Average:4611.79 Median:4716.20 stddev:476.99 (10.1%) stddev-sans-first:450.85 07:52:41 INFO - PROCESS | 23307 | Values: 2573.4 2595.4 2606.9 2621.9 2630.5 4592.6 4661.2 4696.1 4720.0 4737.1 4619.7 4641.3 4675.5 4683.8 4717.3 4316.3 4337.1 4377.1 4404.3 4469.8 3955.8 4334.2 4388.4 4407.8 4468.3 4185.4 4221.0 4334.2 4340.7 4421.0 4235.7 4241.0 4246.8 4273.2 4321.4 4287.4 4299.4 4306.0 4306.7 4350.0 4618.4 4659.4 4660.9 4665.1 4690.7 4821.2 4862.9 4909.3 4939.6 4945.9 4992.4 5002.2 5005.7 5008.6 5052.5 4686.2 4689.0 4689.3 4729.9 4736.4 4978.8 5027.1 5030.5 5088.8 5109.1 4900.3 4905.6 4913.6 4959.8 4961.0 4864.4 4909.1 4999.0 5010.3 5062.5 4903.2 4916.8 4938.3 4945.9 4997.9 4758.7 4796.4 4836.8 4949.6 4950.0 4169.1 4188.5 4196.8 4202.3 4240.2 3650.0 3655.4 3655.8 3677.4 3696.6 4257.8 4339.0 4407.5 4412.7 4455.7 4893.3 5008.2 5036.1 5042.3 5103.3 4374.6 4396.0 4397.8 4401.4 4419.3 4486.7 4499.6 4503.6 4509.4 4536.8 4587.9 4625.9 4671.7 4710.7 4716.2 4990.7 4995.9 4996.6 5001.5 5009.0 4699.9 4700.2 4704.6 4742.4 4745.5 4826.6 4872.1 4889.5 4891.4 4917.2 5013.4 5025.5 5027.5 5059.9 5071.2 4684.8 4860.6 4872.9 4879.0 4923.9 4698.7 4747.9 4863.0 4881.5 4889.0 4829.0 4845.2 4855.7 4887.8 4903.0 4928.9 4942.7 4942.8 4946.2 4953.3 4675.6 4688.1 4714.2 4729.3 4814.7 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#1] ext.html Cycles:180 Average:44674.42 Median:32038.36 stddev:78960.78 (246.5%) stddev-sans-first:79112.69 07:52:41 INFO - PROCESS | 23307 | Values: 521.0 537.6 543.2 549.6 554.9 29221.6 37621.8 39552.6 39617.2 43581.0 41076.6 41481.2 41489.6 41511.8 41601.2 7440.4 7714.9 7896.0 7907.1 7913.8 7598.1 9597.1 9831.0 9832.2 10112.6 10216.9 10286.5 10288.9 10292.8 10305.5 10113.4 10158.7 10185.8 10190.5 10192.7 9859.8 9894.9 9923.7 9932.8 9946.8 38993.8 39324.6 39384.8 39423.0 39457.2 2983.0 3039.9 3185.5 3260.7 3351.5 28812.4 29239.6 29261.6 29314.9 30048.0 3360.3 3408.6 3421.3 3427.8 3463.5 260184.0 261015.0 261166.0 261494.0 261954.0 53376.2 53395.7 53434.0 53612.2 53736.5 55054.0 55064.7 55069.0 55109.4 55157.7 13511.1 13888.6 13935.5 13975.3 14012.9 9031.8 9363.6 10431.7 10679.8 10805.4 15216.8 15715.4 15865.7 16012.3 16030.7 5949.3 5955.4 5986.4 6053.5 6090.5 792.5 866.8 867.9 870.5 917.0 27412.2 27780.2 27896.3 28078.4 28419.9 900.6 954.6 966.7 967.2 1019.1 13709.9 13788.6 14415.6 14497.9 14577.8 265826.0 369480.0 369553.0 370862.0 372548.0 43799.8 43935.0 43998.6 44039.8 44098.8 1202.0 1202.1 1233.3 1234.3 1235.1 24759.8 25624.0 26373.3 26378.5 26646.3 38313.6 38510.2 38605.6 38654.8 38674.2 263295.0 263362.0 263961.0 265262.0 340040.0 21217.3 21832.1 23298.4 23307.3 23705.8 36288.5 36841.6 37015.8 39791.6 39934.8 31168.4 31536.0 31693.0 31783.0 32966.2 31255.0 31736.8 31903.4 31945.2 32141.5 31545.1 31598.2 31668.5 31808.4 32131.5 26557.7 27950.7 29400.3 29447.6 30068.5 22523.3 22668.5 23149.3 23226.3 23291.9 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#2] jquery.html Cycles:180 Average:21526.37 Median:5481.45 stddev:55651.13 (1015.3%) stddev-sans-first:55784.57 07:52:41 INFO - PROCESS | 23307 | Values: 370.3 378.0 380.3 390.2 397.0 15537.4 16199.4 16837.3 16938.5 16975.2 12989.9 15198.8 16764.6 17005.8 17047.7 4574.0 4797.7 4801.7 4803.4 4809.7 3701.3 3855.9 4763.3 4806.2 5264.4 5460.3 5559.9 5566.9 5584.4 5591.7 5322.7 5530.2 5549.6 5553.8 5598.2 5502.6 5503.6 5503.9 5507.0 5509.3 12148.9 12658.1 12716.3 12729.7 12731.9 1884.9 1906.0 1976.0 2161.6 2236.9 8941.6 9053.9 9236.5 9247.2 9468.2 1668.4 1740.3 1748.0 1762.9 1798.3 60958.4 61559.0 61569.7 61913.7 62230.4 8027.2 8433.3 8452.2 8519.3 8643.0 10936.2 10986.6 10998.9 11001.7 11017.7 6496.6 6592.2 6674.6 6826.1 6840.7 6661.7 6680.5 6891.5 6895.3 6903.4 3325.5 3336.7 3397.5 3499.0 3524.3 1642.4 1651.8 1704.6 1739.5 1744.2 784.8 861.4 884.2 976.2 976.6 15852.9 16139.1 16427.3 16525.6 17097.9 832.6 855.5 871.1 871.6 892.1 8954.6 9514.7 9550.5 9579.3 9699.5 335230.0 336471.0 336916.0 337622.0 341742.0 54436.7 55532.2 57106.1 59140.0 75920.0 906.9 916.5 929.0 958.4 963.6 14976.3 15309.9 15629.3 15764.8 15871.5 35006.3 35708.8 35716.6 35846.5 36234.5 46856.3 47397.8 49454.8 49765.8 49917.0 7342.0 7406.2 7415.2 7428.7 7460.6 10868.9 11054.7 11124.4 11338.9 11924.0 9167.1 9716.8 9736.4 9814.8 9893.9 8937.7 9573.6 9686.1 9730.5 9895.0 9719.0 9835.5 10105.4 10165.0 10331.5 7600.0 7644.2 7678.2 7933.4 7943.7 7791.5 9189.9 9364.8 9494.5 9517.4 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#3] mootools.html Cycles:180 Average:4814.98 Median:5755.63 stddev:2611.75 (45.4%) stddev-sans-first:2605.84 07:52:41 INFO - PROCESS | 23307 | Values: 1315.5 1340.5 1423.6 1655.6 1658.2 7035.7 7508.2 7513.1 7513.3 7515.2 6647.7 6841.6 6849.6 6987.8 7404.9 1911.3 2067.4 2122.8 2125.6 2148.4 2149.8 2151.9 2154.9 2158.1 2160.8 2094.1 2107.0 2108.4 2109.7 2151.6 2038.3 2050.1 2052.7 2053.9 2062.2 2107.1 2122.9 2132.8 2152.5 2155.7 7412.6 7447.3 7553.7 7562.0 7574.1 4386.6 4514.4 4541.0 4546.2 4546.8 2900.2 2923.9 2953.9 3013.4 3016.1 910.1 921.5 931.0 932.0 948.9 9370.8 9375.0 9422.1 9488.1 9490.9 4102.5 4138.2 4301.6 4312.3 4354.8 7916.9 7969.8 7984.2 7995.8 8013.0 4445.9 4453.7 4464.4 4465.8 4471.3 4059.3 4112.2 4114.6 4221.9 4265.4 3163.9 3218.4 3249.6 3320.8 3431.7 1029.8 1031.1 1031.1 1054.7 1055.7 787.9 811.8 837.1 843.9 867.4 4771.8 4784.4 4802.6 4835.6 4920.0 827.2 827.6 857.0 862.6 912.6 3105.0 3343.7 3406.7 3415.8 3519.6 8546.1 8874.0 9013.3 9050.5 9077.5 8998.4 9006.7 9012.4 9017.5 9019.5 6672.8 6845.2 6896.8 6909.5 6919.8 4885.7 4917.6 4951.0 4972.5 4974.3 8202.7 8267.6 8378.5 8407.0 8433.4 8411.6 8434.3 8488.6 8506.8 8628.7 6109.0 6191.8 6204.0 6221.5 6228.8 6218.1 6467.5 6665.9 6928.5 7015.2 6467.3 6477.1 6485.0 6488.4 6531.8 6198.8 6254.9 6294.8 6407.2 6441.3 5700.6 5810.6 5818.5 5823.5 5843.6 4241.9 4267.3 4276.5 4313.6 4405.8 4249.5 4285.8 4292.4 4329.9 4384.9 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#4] prototype.html Cycles:180 Average:4201.59 Median:4728.49 stddev:1175.48 (24.9%) stddev-sans-first:1142.71 07:52:41 INFO - PROCESS | 23307 | Values: 352.0 356.9 360.7 368.5 370.3 4817.2 4861.3 4873.9 4888.5 4904.9 4562.4 4620.3 4692.7 4699.4 4781.9 3842.8 3857.3 3862.6 3890.4 3899.3 3867.2 3887.3 3889.8 3894.6 3912.3 3656.0 3672.7 3734.6 3874.3 3882.1 3851.6 3854.7 3869.9 3879.5 3883.8 3507.1 3509.8 3512.1 3518.7 3525.0 4556.5 4722.8 4734.2 4735.2 4745.8 4386.4 4436.2 4443.0 4453.5 4456.1 5022.6 5048.8 5060.3 5104.0 5107.0 3845.9 3848.4 3849.4 3852.0 3862.9 5034.0 5164.4 5173.6 5191.6 5231.2 3876.6 3887.3 3898.6 3899.6 3901.2 3956.2 3957.4 3958.5 3961.6 3964.2 4281.8 4360.7 4393.5 4405.8 4412.7 4944.0 4968.0 4977.3 4982.4 5023.5 1292.2 1295.6 1301.9 1306.4 1312.8 900.7 921.2 961.3 963.7 973.3 4251.9 4273.6 4311.2 4312.9 4317.2 4987.4 4993.7 4998.9 4999.3 5007.3 4138.6 4326.9 4335.5 4353.8 4366.6 2598.6 2600.4 2646.2 2664.7 2666.1 4594.9 4602.2 4673.0 4709.4 4717.9 4959.5 5066.9 5084.2 5085.9 5266.3 4876.7 4902.3 4918.7 4932.3 4934.8 5162.4 5170.9 5177.4 5193.7 5198.4 4922.0 5026.8 5065.1 5087.1 5093.8 4885.2 4888.6 4921.3 4931.7 4938.2 3639.9 3664.6 3665.1 3674.3 3676.8 4990.3 5149.6 5230.3 5263.4 5264.2 4996.7 5020.6 5045.2 5057.7 5060.3 5007.9 5025.2 5053.0 5060.1 5070.8 4805.8 4925.7 4952.7 4964.4 5027.5 4828.8 4927.6 4932.3 5052.7 5055.5 4931.8 4933.7 4940.6 4949.5 4951.4 07:52:41 INFO - PROCESS | 23307 | 07:52:41 INFO - PROCESS | 23307 | [#5] yui.html Cycles:180 Average:15242.14 Median:27196.61 stddev:19551.83 (71.9%) stddev-sans-first:19575.96 07:52:41 INFO - PROCESS | 23307 | Values: 645.9 671.3 688.6 700.5 741.5 12734.6 14015.7 14016.0 14123.8 14141.1 12480.7 13499.8 13586.3 13784.7 13801.2 801.6 813.5 820.6 820.6 820.6 824.6 825.8 826.4 827.6 827.9 865.3 867.3 869.1 871.5 872.8 860.8 860.9 861.2 861.6 862.3 635.2 635.7 635.8 635.8 636.1 11691.7 11824.2 11847.8 12163.9 12199.6 9237.9 10402.5 10791.7 11609.6 11868.1 9614.2 9656.0 9790.1 9879.7 10004.0 3230.4 3264.0 3264.8 3333.0 3335.8 62577.0 62658.4 63305.4 63597.4 63820.7 8226.4 8532.4 8668.2 8709.3 8904.7 16920.3 17449.1 17545.6 17549.5 17663.0 6547.3 6838.8 6850.5 6868.8 7011.8 3442.2 3482.3 3512.5 3542.0 3573.2 2811.5 2871.9 2882.1 3187.3 3337.7 538.2 540.9 541.8 547.0 554.6 565.6 585.7 597.9 612.0 629.2 8104.9 8442.1 8531.0 8666.3 8980.4 581.0 592.6 599.9 612.9 613.1 3940.8 3967.6 3995.0 4012.7 4149.5 68906.3 69154.0 69300.3 71814.0 72677.6 60088.7 60389.4 60675.4 61553.7 63376.7 51511.5 51595.5 52236.7 53410.5 53576.0 7485.6 7899.5 8150.6 8328.7 8352.3 38665.6 38770.6 38892.6 38984.0 39537.8 50915.0 53031.0 53496.7 53497.0 53855.0 9552.8 9903.7 10190.4 10310.3 10412.6 13031.3 13038.8 13113.5 14501.0 14593.3 11343.9 11815.4 12527.4 12572.1 12918.9 12619.1 12944.1 13000.4 13173.9 13726.5 10332.3 10685.9 10898.8 10937.1 10963.6 10000.9 10246.7 10351.2 10522.6 10552.6 9117.6 9698.3 10184.2 10185.7 10418.8 07:52:41 INFO - PROCESS | 23307 | -------- Summary: end -------- 07:52:41 INFO - PROCESS | 23307 | 07:52:42 INFO - TEST-INFO | 23307: exit 0 07:52:42 INFO - TEST-OK | dromaeo_css | took 1121061ms 07:52:42 INFO - TEST-START | kraken 07:52:42 INFO - operating with platform_type : linux_ 07:52:42 INFO - Initialising browser for kraken test... 07:52:42 INFO - TEST-INFO | started process 23643 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZsRUOL/profile http://localhost:40698/getInfo.html) 07:52:42 INFO - PROCESS | 23643 | 1482508362683 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:52:45 INFO - PROCESS | 23643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:52:45 INFO - PROCESS | 23643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:52:46 INFO - PROCESS | 23643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:52:46 INFO - PROCESS | 23643 | __metrics Screen width/height:1600/1200 07:52:46 INFO - PROCESS | 23643 | colorDepth:24 07:52:46 INFO - PROCESS | 23643 | Browser inner width/height: 1024/697 07:52:46 INFO - PROCESS | 23643 | __metrics 07:52:46 INFO - PROCESS | 23643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:52:46 INFO - PROCESS | 23643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:52:47 INFO - PROCESS | 23643 | console.error: PushService: 07:52:47 INFO - PROCESS | 23643 | stateChangeProcessEnqueue: Error transitioning state 07:52:47 INFO - PROCESS | 23643 | UnknownError 07:52:48 INFO - TEST-INFO | 23643: exit 0 07:52:48 INFO - Browser initialized. 07:52:48 INFO - Running cycle 1/1 for kraken test... 07:52:48 INFO - TEST-INFO | started process 23726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZsRUOL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:00 INFO - PROCESS | 23726 | RSS: Main: 151687168 07:53:00 INFO - PROCESS | 23726 | 07:53:03 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40698/tests/kraken/driver.html?testName=audio-beat-detection) 07:53:03 INFO - PROCESS | 23726 | RSS: Main: 205905920 07:53:03 INFO - PROCESS | 23726 | 07:53:06 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40698/tests/kraken/driver.html?testName=audio-dft) 07:53:07 INFO - PROCESS | 23726 | RSS: Main: 184627200 07:53:07 INFO - PROCESS | 23726 | 07:53:10 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40698/tests/kraken/driver.html?testName=audio-fft) 07:53:10 INFO - PROCESS | 23726 | RSS: Main: 267599872 07:53:10 INFO - PROCESS | 23726 | 07:53:13 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40698/tests/kraken/driver.html?testName=audio-oscillator) 07:53:13 INFO - PROCESS | 23726 | RSS: Main: 178892800 07:53:13 INFO - PROCESS | 23726 | 07:53:15 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40698/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:53:16 INFO - PROCESS | 23726 | RSS: Main: 181506048 07:53:16 INFO - PROCESS | 23726 | 07:53:19 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40698/tests/kraken/driver.html?testName=imaging-darkroom) 07:53:20 INFO - PROCESS | 23726 | RSS: Main: 221417472 07:53:20 INFO - PROCESS | 23726 | 07:53:23 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40698/tests/kraken/driver.html?testName=imaging-desaturate) 07:53:24 INFO - PROCESS | 23726 | RSS: Main: 215236608 07:53:24 INFO - PROCESS | 23726 | 07:53:27 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40698/tests/kraken/driver.html?testName=json-parse-financial) 07:53:27 INFO - PROCESS | 23726 | RSS: Main: 216670208 07:53:27 INFO - PROCESS | 23726 | 07:53:29 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40698/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:53:30 INFO - PROCESS | 23726 | RSS: Main: 180105216 07:53:30 INFO - PROCESS | 23726 | 07:53:32 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:32 INFO - PROCESS | 23726 | RSS: Main: 187637760 07:53:32 INFO - PROCESS | 23726 | 07:53:34 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:35 INFO - PROCESS | 23726 | RSS: Main: 196321280 07:53:35 INFO - PROCESS | 23726 | 07:53:38 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:39 INFO - PROCESS | 23726 | RSS: Main: 205545472 07:53:39 INFO - PROCESS | 23726 | 07:53:42 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:42 INFO - PROCESS | 23726 | RSS: Main: 202121216 07:53:42 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | Cycle 1(1): loaded http://localhost:40698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40698/tests/kraken/driver.html?testName=ai-astar) 07:53:44 INFO - PROCESS | 23726 | RSS: Main: 253157376 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | __start_tp_report 07:53:44 INFO - PROCESS | 23726 | _x_x_mozilla_page_load 07:53:44 INFO - PROCESS | 23726 | _x_x_mozilla_page_load_details 07:53:44 INFO - PROCESS | 23726 | |i|pagename|runs| 07:53:44 INFO - PROCESS | 23726 | |0;ai-astar;107;116;117;116;115;113;114;114;113;114 07:53:44 INFO - PROCESS | 23726 | |1;audio-beat-detection;149;126;130;168;128;127;127;125;152;126 07:53:44 INFO - PROCESS | 23726 | |2;audio-dft;160;161;161;162;162;161;162;160;161;162 07:53:44 INFO - PROCESS | 23726 | |3;audio-fft;76;77;77;113;76;77;76;76;100;76 07:53:44 INFO - PROCESS | 23726 | |4;audio-oscillator;85;85;109;84;84;84;103;84;84;107 07:53:44 INFO - PROCESS | 23726 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;117;118 07:53:44 INFO - PROCESS | 23726 | |6;imaging-darkroom;149;150;149;149;150;150;149;149;149;149 07:53:44 INFO - PROCESS | 23726 | |7;imaging-desaturate;95;95;95;95;95;96;96;95;96;95 07:53:44 INFO - PROCESS | 23726 | |8;json-parse-financial;60;59;58;61;60;60;60;60;61;61 07:53:44 INFO - PROCESS | 23726 | |9;json-stringify-tinderbox;68;68;68;68;71;67;68;68;69;69 07:53:44 INFO - PROCESS | 23726 | |10;stanford-crypto-aes;84;83;87;93;88;84;87;83;85;90 07:53:44 INFO - PROCESS | 23726 | |11;stanford-crypto-ccm;140;134;144;141;137;138;134;133;175;145 07:53:44 INFO - PROCESS | 23726 | |12;stanford-crypto-pbkdf2;174;176;177;173;173;175;181;172;173;173 07:53:44 INFO - PROCESS | 23726 | |13;stanford-crypto-sha256-iterative;68;67;72;67;66;67;65;66;65;66 07:53:44 INFO - PROCESS | 23726 | __end_tp_report 07:53:44 INFO - PROCESS | 23726 | __start_cc_report 07:53:44 INFO - PROCESS | 23726 | _x_x_mozilla_cycle_collect,1892 07:53:44 INFO - PROCESS | 23726 | __end_cc_report 07:53:44 INFO - PROCESS | 23726 | __startTimestamp1482508424733__endTimestamp 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | ------- Summary: start ------- 07:53:44 INFO - PROCESS | 23726 | Number of tests: 14 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.50 stddev:2.77 (2.4%) stddev-sans-first:1.41 07:53:44 INFO - PROCESS | 23726 | Values: 107.0 116.0 117.0 116.0 115.0 113.0 114.0 114.0 113.0 114.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:129.00 stddev:15.02 (11.6%) stddev-sans-first:15.16 07:53:44 INFO - PROCESS | 23726 | Values: 149.0 126.0 130.0 168.0 128.0 127.0 127.0 125.0 152.0 126.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.71 07:53:44 INFO - PROCESS | 23726 | Values: 160.0 161.0 161.0 162.0 162.0 161.0 162.0 160.0 161.0 162.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:13.07 (17.2%) stddev-sans-first:13.66 07:53:44 INFO - PROCESS | 23726 | Values: 76.0 77.0 77.0 113.0 76.0 77.0 76.0 76.0 100.0 76.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:84.00 stddev:10.75 (12.8%) stddev-sans-first:11.19 07:53:44 INFO - PROCESS | 23726 | Values: 85.0 85.0 109.0 84.0 84.0 84.0 103.0 84.0 84.0 107.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:53:44 INFO - PROCESS | 23726 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 07:53:44 INFO - PROCESS | 23726 | Values: 149.0 150.0 149.0 149.0 150.0 150.0 149.0 149.0 149.0 149.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 07:53:44 INFO - PROCESS | 23726 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 95.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:60.00 stddev:0.94 (1.6%) stddev-sans-first:1.00 07:53:44 INFO - PROCESS | 23726 | Values: 60.0 59.0 58.0 61.0 60.0 60.0 60.0 60.0 61.0 61.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 07:53:44 INFO - PROCESS | 23726 | Values: 68.0 68.0 68.0 68.0 71.0 67.0 68.0 68.0 69.0 69.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:87.00 stddev:3.27 (3.8%) stddev-sans-first:3.35 07:53:44 INFO - PROCESS | 23726 | Values: 84.0 83.0 87.0 93.0 88.0 84.0 87.0 83.0 85.0 90.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:140.50 stddev:12.28 (8.7%) stddev-sans-first:13.00 07:53:44 INFO - PROCESS | 23726 | Values: 140.0 134.0 144.0 141.0 137.0 138.0 134.0 133.0 175.0 145.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:174.50 stddev:2.71 (1.6%) stddev-sans-first:2.86 07:53:44 INFO - PROCESS | 23726 | Values: 174.0 176.0 177.0 173.0 173.0 175.0 181.0 172.0 173.0 173.0 07:53:44 INFO - PROCESS | 23726 | 07:53:44 INFO - PROCESS | 23726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:2.02 (3.0%) stddev-sans-first:2.11 07:53:44 INFO - PROCESS | 23726 | Values: 68.0 67.0 72.0 67.0 66.0 67.0 65.0 66.0 65.0 66.0 07:53:44 INFO - PROCESS | 23726 | -------- Summary: end -------- 07:53:44 INFO - PROCESS | 23726 | 07:53:45 INFO - TEST-INFO | 23726: exit 0 07:53:45 INFO - TEST-OK | kraken | took 63648ms 07:53:45 INFO - SUITE-END | took 1184s 07:53:45 INFO - Completed test suite (00:19:44) 07:53:45 INFO - javascript benchmark 07:53:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.4209802794367, 2595.4290056187656, 2606.856622885741, 2621.882015309311, 2630.526505229058, 4592.632589392849, 4661.184292748772, 4696.060787842428, 4719.952800471999, 4737.147313483571, 4619.74591397473, 4641.303804429337, 4675.46232183299, 4683.812647494096, 4717.315989181567, 4316.287812510932, 4337.067530480946, 4377.058932329549, 4404.2512772828695, 4469.843555475559, 3955.8417663293435, 4334.241507736149, 4388.429504164455, 4407.801648925806, 4468.285074388083, 4185.393117997874, 4220.978895105535, 4334.198173337911, 4340.65274778017, 4421.0, 4235.745855248695, 4241.045764702948, 4246.766427846467, 4273.18809426208, 4321.416608757809, 4287.399764033038, 4299.355096735483, 4305.9784701076605, 4306.676999225039, 4349.978250108761, 4618.422697162855, 4659.44086709597, 4660.930086048678, 4665.136949664289, 4690.695104818176, 4821.204501257288, 4862.927056094161, 4909.288153217799, 4939.604831613498, 4945.901081978341, 4992.375953005874, 5002.24966255061, 5005.724685142316, 5008.649394542384, 5052.494750524955, 4686.2267725825195, 4688.9531104689195, 4689.296605509201, 4729.929051064237, 4736.384270044872, 4978.800847966041, 5027.069992051483, 5030.496950304941, 5088.821891233863, 5109.105906466353, 4900.289458028566, 4905.583025442877, 4913.557779799835, 4959.776810043557, 4960.950390496121, 4864.440589332272, 4909.116359055333, 4999.0, 5010.298558201855, 5062.519060689228, 4903.190973489334, 4916.803327866917, 4938.308636790852, 4945.876353091202, 4997.925031124536, 4758.714477131383, 4796.424429068534, 4836.806527738921, 4949.57928576075, 4950.0, 4169.124483858363, 4188.518320393133, 4196.8111434984885, 4202.285611446062, 4240.194363071007, 3649.9817500912327, 3655.4151335786146, 3655.8354874030733, 3677.3748462761396, 3696.6488183622514, 4257.787110644455, 4338.956610433918, 4407.47110346754, 4412.691111622155, 4455.68810183284, 4893.266010098514, 5008.17365134756, 5036.11867923112, 5042.294078828967, 5103.260027296022, 4374.606285434326, 4396.0, 4397.846075387346, 4401.449818772653, 4419.315006174048, 4486.7083639563325, 4499.550044995474, 4503.639708823286, 4509.391232183613, 4536.795844186953, 4587.885302867522, 4625.861224163281, 4671.672982891165, 4710.69380490267, 4716.198246298206, 4990.6506544541535, 4995.875103122451, 4996.575291100152, 5001.474845141208, 5009.023240468074, 4699.882502937454, 4700.2244629635725, 4704.6236301096515, 4742.383490146259, 4745.549172828576, 4826.565609095198, 4872.123017856819, 4889.511048895082, 4891.437484689306, 4917.213245880785, 5013.373328333958, 5025.472325405926, 5027.49725027509, 5059.898802024012, 5071.1632580625255, 4684.812607495798, 4860.6111511078325, 4872.902541949212, 4878.9512104878495, 4923.901521969612, 4698.671093023456, 4747.905041899212, 4862.975685121552, 4881.48744381849, 4889.0, 4828.975855120842, 4845.176320025534, 4855.660103792842, 4887.828925987574, 4903.019396120719, 4928.926066109083, 4942.728149951788, 4942.777575009205, 4946.233333833262, 4953.257011448168, 4675.625949924066, 4688.085823264567, 4714.22215334466, 4729.290606409066, 4814.662973591815], "value": 4581.604275627859, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.0100808463918, 537.6424677589404, 543.1635281666235, 549.6290004247132, 554.9472800083996, 29221.561676574867, 37621.81189094054, 39552.60447395525, 39617.20765584687, 43581.0, 41076.58923410765, 41481.17037659245, 41489.58510414895, 41511.79244103775, 41601.16797664045, 7440.441966852508, 7714.884276735839, 7895.96052019742, 7907.051153861545, 7913.841723165562, 7598.1262154852075, 9597.088276613746, 9831.0, 9832.164266037395, 10112.595496180144, 10216.897831021706, 10286.537105830255, 10288.897111028906, 10292.794144117113, 10305.536250868692, 10113.443760593165, 10158.695239142837, 10185.847212291821, 10190.541425635864, 10192.694219173436, 9859.802803943952, 9894.851577226347, 9923.702288931343, 9932.801343973153, 9946.751331216705, 38993.805030974945, 39324.60675393238, 39384.80307598443, 39423.0, 39457.21085578272, 2983.0, 3039.924001899948, 3185.490321548541, 3260.7391408687486, 3351.463765797461, 28812.42375152506, 29239.56140657892, 29261.56107658387, 29314.85342573295, 30048.0, 3360.3447327771096, 3408.5568876045954, 3421.2815308785184, 3427.845746941394, 3463.4977928200524, 260184.0, 261015.0, 261166.0, 261494.0, 261954.0, 53376.199357009675, 53395.73302133464, 53434.0, 53612.19581706278, 53736.462635373144, 55054.0, 55064.72467637636, 55069.0, 55109.448905510435, 55157.724211378685, 13511.121777084458, 13888.583342499742, 13935.512257070954, 13975.301234938212, 14012.929935350257, 9031.774205644911, 9363.625454981724, 10431.69603799525, 10679.83980240297, 10805.351678899291, 15216.771748423782, 15715.371385144466, 15865.682686346208, 16012.279447424893, 16030.679386412206, 5949.25634295713, 5955.434233747787, 5986.431289027535, 6053.4551890330085, 6090.512758979271, 792.469045739356, 866.8353012927523, 867.9262262707614, 870.4690139015196, 916.9633214671472, 27412.193768334037, 27780.16659500218, 27896.302592435353, 28078.43843123126, 28419.857900710365, 900.6307413960245, 954.627695198873, 966.6954909203719, 967.2020583018897, 1019.1388276906002, 13709.931450342883, 13788.586342409744, 14415.63960900965, 14497.92751036238, 14577.781333280009, 265826.0, 369480.0, 369553.0, 370862.0, 372548.0, 43799.78100109543, 43935.0, 43998.560014400085, 44039.77980110143, 44098.77950610226, 1202.0079218792819, 1202.0623913347604, 1233.2785320587557, 1234.2656119608819, 1235.1045492018216, 24759.752402475744, 25624.0, 26373.34066648349, 26378.472430550897, 26646.333841654116, 38313.616863831005, 38510.2297954045, 38605.613943860204, 38654.80672596619, 38674.22651546897, 263295.0, 263361.98061184154, 263961.0, 265262.0, 340040.0, 21217.257395991684, 21832.126714931226, 23298.417539561648, 23307.3007809766, 23705.762942370355, 36288.4556731644, 36841.63158368382, 37015.81492092522, 39791.60208397879, 39934.80032599935, 31168.376632467676, 31536.0, 31693.0, 31783.0, 32966.175845603095, 31255.0, 31736.841315794198, 31903.36193276168, 31945.201369965936, 32141.517877232327, 31545.053648390585, 31598.210044749067, 31668.524972124975, 31808.363832722753, 32131.51802723008, 26557.68846230723, 27950.720492795626, 29400.330934391226, 29447.558286625288, 30068.54897176588, 22523.32430027102, 22668.546629066997, 23149.3055208344, 23226.3032109037, 23291.88354058219], "value": 17125.959722142557, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.268719279423, 378.04920624629057, 380.2831662316534, 390.190355013347, 396.986105486308, 15537.378504859806, 16199.433019844308, 16837.326506939724, 16938.491845244633, 16975.236114374853, 12989.895858851985, 15198.848011519882, 16764.58088547787, 17005.82994170058, 17047.74428383572, 4573.954260457394, 4797.7121372717575, 4801.7118972861745, 4803.423589169304, 4809.735464549449, 3701.3152566775098, 3855.9228815423676, 4763.285507173917, 4806.158922188621, 5264.368275806909, 5460.290162278918, 5559.8888022239535, 5566.916496252559, 5584.441555844423, 5591.664500130005, 5322.680639161663, 5530.170474428827, 5549.639273447213, 5553.750081246354, 5598.24423702799, 5502.614816962814, 5503.559715222773, 5503.944960550383, 5507.0, 5509.311336082989, 12148.878511214863, 12658.05064620148, 12716.300603466805, 12729.681757956125, 12731.872681273162, 1884.896330701811, 1905.9714104288446, 1975.9506012349661, 2161.6217161996587, 2236.910523579055, 8941.642334306685, 9053.90946090544, 9236.491992940386, 9247.167754902091, 9468.242540596735, 1668.3743596151444, 1740.321274702867, 1747.9563010924828, 1762.903040332781, 1798.2986635212278, 60958.39041609572, 61559.0, 61569.692151538955, 61913.690431548006, 62230.37769622336, 8027.15714849944, 8433.28317093054, 8452.1547845215, 8519.318454523624, 8642.956785216034, 10936.179786516042, 10986.615468458565, 10998.890011099948, 11001.724956876142, 11017.724556885982, 6496.6426846522945, 6592.24189218246, 6674.564968531789, 6826.078479405316, 6840.657967101625, 6661.666916654148, 6680.498962577826, 6891.483138764513, 6895.275996020346, 6903.413209877116, 3325.48454989477, 3336.7487192302888, 3397.4733916242526, 3499.0, 3524.3303772283184, 1642.3758971590719, 1651.8265582113945, 1704.6164612961984, 1739.5390221591288, 1744.2499725118066, 784.7572560282936, 861.3884142259067, 884.1777147253116, 976.1800087926176, 976.6337623391229, 15852.920735396248, 16139.112348820692, 16427.260773265232, 16525.58686032859, 17097.914510427367, 832.6003518311123, 855.4525103933488, 871.1158174452935, 871.5555066915919, 892.0811564088999, 8954.552272386354, 9514.714558563253, 9550.474723890115, 9579.329446938787, 9699.51502424876, 335230.0, 336471.0, 336916.0, 337622.0, 341742.0, 54436.727816361454, 55532.16701749477, 57106.14340784892, 59140.0, 75920.0, 906.9455832650062, 916.5417291354323, 929.0291645230615, 958.4393130019017, 963.5712108111823, 14976.326065326868, 15309.923450382677, 15629.296681649581, 15764.842351576337, 15871.52385428439, 35006.299874002885, 35708.821455892554, 35716.64283357237, 35846.46230306599, 36234.456483152244, 46856.297155543376, 47397.763011184725, 49454.75272623614, 49765.751171243915, 49917.0, 7342.0, 7406.222346653523, 7415.2214017527385, 7428.702851885864, 7460.626968651654, 10868.913108689068, 11054.668359949215, 11124.443777811239, 11338.886611134112, 11924.0, 9167.12912273333, 9716.805663886824, 9736.367136136128, 9814.803703925738, 9893.851592225978, 8937.687180948635, 9573.617055317709, 9686.128248457673, 9730.513474326397, 9894.971530836017, 9719.0, 9835.508224588884, 10105.39367637944, 10165.0, 10331.535080921227, 7599.962000189964, 7644.235576442534, 7678.232176782501, 7933.404994625311, 7943.682252709827, 7791.532508049535, 9189.908100919172, 9364.765880853032, 9494.525273736148, 9517.381370211191], "value": 7681.013374454029, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.45408655408, 1340.4839136932278, 1423.6085076603933, 1655.5695519165022, 1658.1543412859453, 7035.71857125715, 7508.249175082489, 7513.060867391576, 7513.323800857922, 7515.173330933607, 6647.700853461593, 6841.5552989055805, 6849.623270720109, 6987.755428560002, 7404.888926666104, 1911.3023746332628, 2067.400453868376, 2122.8195603373656, 2125.5855108253895, 2148.430665873545, 2149.8280137589036, 2151.9139234430604, 2154.870707757532, 2158.104386679526, 2160.762316145223, 2094.110003248621, 2107.030765847704, 2108.441263065289, 2109.683547467877, 2151.6234658934623, 2038.2764118737857, 2050.0569737920573, 2052.7126202331688, 2053.938381848532, 2062.2266650006172, 2107.1255428996947, 2122.9150833966623, 2132.76539580646, 2152.547964927367, 2155.698202251686, 7412.629368531606, 7447.2552744725635, 7553.735619253354, 7561.962190189069, 7574.053243344582, 4386.56134386562, 4514.435695538058, 4541.046380260149, 4546.249868771682, 4546.818127274905, 2900.1879473747385, 2923.8830446782104, 2953.9261518461994, 3013.442513135077, 3016.125323656137, 910.1444642036464, 921.5162039929091, 930.9534523273809, 932.021377553566, 948.8671585977969, 9370.812583748288, 9375.015623359586, 9422.057794220591, 9488.146066854017, 9490.90509094907, 4102.528209255974, 4138.193052354822, 4301.591348821857, 4312.331588603772, 4354.822588705647, 7916.920830791619, 7969.760907172794, 7984.24149705777, 7995.800104997422, 8012.959935200403, 4445.911081778411, 4453.688241823106, 4464.352668862996, 4465.843695470643, 4471.32930060487, 4059.289624315792, 4112.177564487115, 4114.629683328515, 4221.936670949938, 4265.4241677373775, 3163.920901977423, 3218.436773564617, 3249.6425393206728, 3320.8007519548905, 3431.673990970854, 1029.7940411917646, 1031.0874875735028, 1031.1130630945352, 1054.6835949215206, 1055.6885718712997, 787.8896954426385, 811.8213992921548, 837.1210229259253, 843.9282660973886, 867.4014929698487, 4771.785269662873, 4784.354112194809, 4802.639802014863, 4835.588974937099, 4920.040592084581, 827.230675471805, 827.5903427803156, 856.9700060497853, 862.5816479007676, 912.6075787411344, 3105.0, 3343.7157841583253, 3406.6763657453, 3415.7950522968204, 3519.63043880394, 8546.102659220693, 8874.0, 9013.324000699973, 9050.547472626342, 9077.546122693839, 8998.370114091951, 9006.684766033157, 9012.369134160677, 9017.458952462874, 9019.458832470073, 6672.833179170462, 6845.212800527904, 6896.793096207121, 6909.516333856683, 6919.827004324932, 4885.682430641997, 4917.631177661761, 4951.0, 4972.477889821661, 4974.253861920741, 8202.712905048294, 8267.62795674184, 8378.49729016261, 8406.957965210135, 8433.367497437594, 8411.621477033426, 8434.32525397979, 8488.618012189343, 8506.829863402822, 8628.654853805778, 6108.969455152695, 6191.783287585093, 6204.038374052029, 6221.47117495018, 6228.813135605939, 6218.129461875432, 6467.514936379696, 6665.900011499928, 6928.4803639726215, 7015.193252775996, 6467.320931302145, 6477.125588045742, 6485.0, 6488.351164883474, 6531.771388001587, 6198.845028874134, 6254.937450625617, 6294.842628934313, 6407.167068281094, 6441.323661015714, 5700.629459085146, 5810.622309549865, 5818.505427038749, 5823.505002074871, 5843.590948633725, 4241.87274381769, 4267.338562522814, 4276.465441819772, 4313.633341166036, 4405.7797110145, 4249.46881639795, 4285.828566857291, 4292.399064130962, 4329.913401731885, 4384.9123017540105], "value": 3925.091306955196, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.98803440109685, 356.86795885522355, 360.6898067661811, 368.4712437651968, 370.2835014247433, 4817.20516114841, 4861.343718597988, 4873.853784386465, 4888.462269150391, 4904.926426103611, 4562.357551281544, 4620.330052142437, 4692.741899195551, 4699.436067671884, 4781.855315919045, 3842.769433833972, 3857.305684976703, 3862.555806082308, 3890.3775395936655, 3899.337112690835, 3867.245887052026, 3887.300285948528, 3889.766614003169, 3894.610538946111, 3912.2762288976487, 3656.0, 3672.7245456590863, 3734.626537346257, 3874.341361968472, 3882.0683036071423, 3851.5763266040713, 3854.672352850011, 3869.9226015479535, 3879.4568760373572, 3883.8058097095313, 3507.1232191952013, 3509.78941263525, 3512.0868574170645, 3518.700910422617, 3524.9647503524893, 4556.453225612948, 4722.787474563653, 4734.19518681825, 4735.247958255468, 4745.83389581363, 4386.363977223285, 4436.201483732927, 4442.955570444286, 4453.465584129893, 4456.108778244332, 5022.648414610979, 5048.7980480780725, 5060.342155519796, 5104.03023425548, 5107.033791990073, 3845.9230815383817, 3848.4034974578985, 3849.441830934527, 3851.9614803852164, 3862.942055869164, 5034.0, 5164.354455693038, 5173.586113110942, 5191.636585439021, 5231.241469986869, 3876.592957739453, 3887.3391523441082, 3898.6491215790716, 3899.590542992945, 3901.180752042046, 3956.208758248362, 3957.4261732049267, 3958.5051868516434, 3961.623645753649, 3964.1675248197625, 4281.785910704452, 4360.672949528798, 4393.538678438782, 4405.7797110144365, 4412.713173643767, 4944.0, 4968.03762513805, 4977.303177555145, 4982.437557146657, 5023.547880690682, 1292.1665525735848, 1295.6177927511408, 1301.9414126364336, 1306.4186437035619, 1312.7637025335341, 900.7477906186278, 921.1709461484584, 961.2838435365697, 963.691618682015, 973.3478569358547, 4251.914961700749, 4273.55127711592, 4311.1808756336195, 4312.921769557611, 4317.201317756162, 4987.401511818605, 4993.725345106054, 4998.925016124761, 4999.250112483084, 5007.349044624249, 4138.565450627701, 4326.891827704269, 4335.458067741532, 4353.804078816461, 4366.585174408426, 2598.636190933271, 2600.4279058607453, 2646.192911162114, 2664.7335266473583, 2666.066876593177, 4594.88512787183, 4602.194615942197, 4673.0, 4709.411323584552, 4717.905641887143, 4959.479254678206, 5066.858547700449, 5084.161113416245, 5085.898282034339, 5266.262723218752, 4876.658633895664, 4902.289168070681, 4918.67156901924, 4932.26016097581, 4934.802607895644, 5162.432132465351, 5170.922436163385, 5177.430482646831, 5193.68837869729, 5198.350206224222, 4921.9507804921495, 5026.773795179299, 5065.13892638245, 5087.135187018142, 5093.821716239914, 4885.193942999365, 4888.584470320063, 4921.286413469955, 4931.654784165074, 4938.160512712776, 3639.9090022748583, 3664.5968943416738, 3665.138692407229, 3674.338619048597, 3676.7793932364143, 4990.326305948651, 5149.639525233348, 5230.34620672416, 5263.36839579253, 5264.236685680478, 4996.725180115129, 5020.598352131894, 5045.217991211368, 5057.671251368649, 5060.266261392004, 5007.874803129951, 5025.1959686451455, 5053.0, 5060.139776238122, 5070.797168113234, 4805.7597120144555, 4925.729084900365, 4952.653314267966, 4964.404271487445, 5027.472115427974, 4828.806847726051, 4927.575335142042, 4932.334134891744, 5052.72210028452, 5055.51972562606, 4931.827386041615, 4933.728644924564, 4940.580050695731, 4949.490405426235, 4951.356323678043], "value": 3847.601768453383, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.8805121052603, 671.2783757460729, 688.6109348218256, 700.4816435837479, 741.4920779266201, 12734.554290599819, 14015.719685606282, 14016.0, 14123.788143177848, 14141.080829746072, 12480.687982800435, 13499.785019348257, 13586.25275609841, 13784.655383615414, 13801.171929684226, 801.6152246921496, 813.5200231863199, 820.6266148902235, 820.6307161777224, 820.63481750621, 824.6412810427453, 825.781167990483, 826.409117481001, 827.5779352530193, 827.8758186272078, 865.2602025268368, 867.319154463746, 869.1395518436765, 871.4814685262259, 872.759991002473, 860.7934095817021, 860.9354298427612, 861.246409391779, 861.5649097205871, 862.3489265604492, 635.2345423764399, 635.6726285962733, 635.8060791458603, 635.8124353315783, 636.0776873533349, 11691.7077073073, 11824.23142495735, 11847.763044739144, 12163.878361216362, 12199.634010979686, 9237.861432078524, 10402.479876006246, 10791.730206744816, 11609.593664221711, 11868.050555955502, 9614.182794462478, 9656.0, 9790.118889299998, 9879.703608891745, 10004.049615286609, 3230.3746323492974, 3263.9836800816083, 3264.8367581620823, 3333.0, 3335.849886755102, 62577.0, 62658.37341626571, 63305.36694633041, 63597.36402636007, 63820.68089659568, 8226.383021273428, 8532.445391049561, 8668.219860212612, 8709.303255739525, 8904.688335908211, 16920.32318707238, 17449.12754362277, 17545.649087018188, 17549.473515794547, 17663.0, 6547.312532184147, 6838.760643377458, 6850.520463567503, 6868.759593414206, 7011.824704382431, 3442.1910850950007, 3482.3035392921515, 3512.4906888501023, 3542.0, 3573.2496175802853, 2811.5079861024647, 2871.913842584684, 2882.1497658190883, 3187.3306605612615, 3337.6662333766913, 538.21689441862, 540.8674874655735, 541.7507946344647, 546.9699166545878, 554.5646667366099, 565.5645153231989, 585.6573904265966, 597.8864015837064, 611.9847003824851, 629.2386212682705, 8104.878426823602, 8442.07137214906, 8531.0, 8666.31681535345, 8980.416272942239, 580.9666168410563, 592.5970340168641, 599.9440983868336, 612.9233845769279, 613.1018058544254, 3940.822662980173, 3967.6429121379215, 3994.980025099914, 4012.719109662354, 4149.502059752849, 68906.310936891, 69154.0, 69300.30699693039, 71814.0, 72677.63661181767, 60088.69955650194, 60389.396106039254, 60675.393246067855, 61553.69223153855, 63376.68311658412, 51511.48488515067, 51595.484045159064, 52236.73881630644, 53410.46589534132, 53576.0, 7485.625718713934, 7899.486533375312, 8150.5924703763385, 8328.708495202638, 8352.290055345364, 38665.6133438662, 38770.6122938767, 38892.6110738889, 38984.02539936411, 39537.80231098826, 50915.0, 53031.0, 53496.650334966194, 53497.0, 53855.0, 9552.761180970532, 9903.702888913056, 10190.43952582615, 10310.32982856112, 10412.635557755442, 13031.348432578523, 13038.804417933548, 13113.541026064042, 14501.0, 14593.270336482921, 11343.943280283545, 11815.350155741517, 12527.37363131858, 12572.057095717675, 12918.935405322913, 12619.053570982029, 12944.09391342597, 13000.414981326052, 13173.868261317264, 13726.519571814939, 10332.276740628084, 10685.893141068489, 10898.78202435931, 10937.12502999774, 10963.56145754161, 10000.949995249977, 10246.692599222035, 10351.223658225814, 10522.579096836042, 10552.630657926935, 9117.589708463267, 9698.27262955267, 10184.200324065318, 10185.74535636615, 10418.843717344398], "value": 6332.471298280096, "unit": "score"}], "name": "dromaeo_css", "value": 6215.254855231775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 117.0, 116.0, 115.0, 113.0, 114.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 126.0, 130.0, 168.0, 128.0, 127.0, 127.0, 125.0, 152.0, 126.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 162.0, 162.0, 161.0, 162.0, 160.0, 161.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 113.0, 76.0, 77.0, 76.0, 76.0, 100.0, 76.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 109.0, 84.0, 84.0, 84.0, 103.0, 84.0, 84.0, 107.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 149.0, 150.0, 150.0, 149.0, 149.0, 149.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 71.0, 67.0, 68.0, 68.0, 69.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 87.0, 93.0, 88.0, 84.0, 87.0, 83.0, 85.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 134.0, 144.0, 141.0, 137.0, 138.0, 134.0, 133.0, 175.0, 145.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 177.0, 173.0, 173.0, 175.0, 181.0, 172.0, 173.0, 173.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 67.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1544.5000000000002, "alertThreshold": 2.0}]} 07:53:45 INFO - Return code: 0 07:53:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:46 INFO - # TBPL SUCCESS # 07:53:46 INFO - Running post-action listener: _resource_record_post_action 07:53:46 INFO - [mozharness: 2016-12-23 15:53:46.016058Z] Finished run-tests step (success) 07:53:46 INFO - Running post-run listener: _resource_record_post_run 07:53:46 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 2293760; Write bytes: 394354688; Read time: 1272; Write time: 274176 07:53:46 INFO - TinderboxPrint: CPU usage
13.1% 07:53:46 INFO - TinderboxPrint: I/O read bytes / time
2,293,760 / 1,272 07:53:46 INFO - TinderboxPrint: I/O write bytes / time
394,354,688 / 274,176 07:53:46 INFO - TinderboxPrint: CPU idle
8,330.5 (86.9%) 07:53:46 INFO - TinderboxPrint: CPU user
1,210.8 (12.6%) 07:53:46 INFO - TinderboxPrint: Swap in / out
0 / 0 07:53:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110534656; Read time: 0; Write time: 66784 07:53:46 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 2289664; Write bytes: 283820032; Read time: 1264; Write time: 207392 07:53:46 INFO - Running post-run listener: _upload_blobber_files 07:53:46 INFO - Blob upload gear active. 07:53:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:53:47 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:53:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:48 INFO - (blobuploader) - INFO - Done attempting. 07:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:53:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:49 INFO - (blobuploader) - INFO - Done attempting. 07:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:49 INFO - (blobuploader) - INFO - Done attempting. 07:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:53 INFO - (blobuploader) - INFO - Done attempting. 07:53:53 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:53 INFO - Return code: 0 07:53:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc510e7a59f7b5beb747951e085fb6ec1a2b0692d931cad24783c56aa5ba751b25c6e120fb3cb17efa213f6c91dcc72339f7712743a13af6e0632e32d4b3b2c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eff468d2a7a8384de34b0f25a26cbc528ae7e7c2a64428ee3258403f3eef6d27c1ae485c1b9d29b6902decaf8874d15b219747ed948eed35039bc9ae682d2908", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7da5055f927dd5084bb8d41530ee6bbe75f3445f7b227b22409a2de0051948ea101c665fe7aa02797e7a107d6b981673b0dfbe367053ec9850c812296966139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d019e08eafe7c8312ad83885c8c62b37e6e023e28276cb3602a77957bae1754a49b3570430cb4b46ec16297148a61add326765d9cdbc8f5457689ac546479fca"} 07:53:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:53 INFO - Contents: 07:53:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc510e7a59f7b5beb747951e085fb6ec1a2b0692d931cad24783c56aa5ba751b25c6e120fb3cb17efa213f6c91dcc72339f7712743a13af6e0632e32d4b3b2c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eff468d2a7a8384de34b0f25a26cbc528ae7e7c2a64428ee3258403f3eef6d27c1ae485c1b9d29b6902decaf8874d15b219747ed948eed35039bc9ae682d2908", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7da5055f927dd5084bb8d41530ee6bbe75f3445f7b227b22409a2de0051948ea101c665fe7aa02797e7a107d6b981673b0dfbe367053ec9850c812296966139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d019e08eafe7c8312ad83885c8c62b37e6e023e28276cb3602a77957bae1754a49b3570430cb4b46ec16297148a61add326765d9cdbc8f5457689ac546479fca"} 07:53:53 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:53 INFO - Copying logs to upload dir... 07:53:53 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.202688 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-23 07:53:52.388237) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 07:53:52.391761) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc510e7a59f7b5beb747951e085fb6ec1a2b0692d931cad24783c56aa5ba751b25c6e120fb3cb17efa213f6c91dcc72339f7712743a13af6e0632e32d4b3b2c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eff468d2a7a8384de34b0f25a26cbc528ae7e7c2a64428ee3258403f3eef6d27c1ae485c1b9d29b6902decaf8874d15b219747ed948eed35039bc9ae682d2908", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7da5055f927dd5084bb8d41530ee6bbe75f3445f7b227b22409a2de0051948ea101c665fe7aa02797e7a107d6b981673b0dfbe367053ec9850c812296966139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d019e08eafe7c8312ad83885c8c62b37e6e023e28276cb3602a77957bae1754a49b3570430cb4b46ec16297148a61add326765d9cdbc8f5457689ac546479fca"} build_url:https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011470 build_url: 'https://queue.taskcluster.net/v1/task/BHKAX81ISOmuQSIFn1e4aQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc510e7a59f7b5beb747951e085fb6ec1a2b0692d931cad24783c56aa5ba751b25c6e120fb3cb17efa213f6c91dcc72339f7712743a13af6e0632e32d4b3b2c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eff468d2a7a8384de34b0f25a26cbc528ae7e7c2a64428ee3258403f3eef6d27c1ae485c1b9d29b6902decaf8874d15b219747ed948eed35039bc9ae682d2908", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7da5055f927dd5084bb8d41530ee6bbe75f3445f7b227b22409a2de0051948ea101c665fe7aa02797e7a107d6b981673b0dfbe367053ec9850c812296966139", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d019e08eafe7c8312ad83885c8c62b37e6e023e28276cb3602a77957bae1754a49b3570430cb4b46ec16297148a61add326765d9cdbc8f5457689ac546479fca"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 07:53:52.416921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:53:52.417217) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1482506757.337090-765933888 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 07:53:52.502045) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 07:53:52.502418) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 07:53:52.502722) ========= ========= Total master_lag: 0.18 =========