builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1463045065.87 results: success (0) buildid: 20160511231539 builduid: 5b161d21f8dc4b419520ba23160afeef revision: ea5f22cee4e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:25.870224) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:25.870935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:25.871408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:25.885857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:25.886281) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 02:24:25-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-12 02:24:26 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127408 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:26.027450) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:26.027982) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019861 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:26.080001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 02:24:26.080602) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ea5f22cee4e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ea5f22cee4e9 --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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 02:24:26,218 Setting DEBUG logging. 2016-05-12 02:24:26,218 attempt 1/10 2016-05-12 02:24:26,218 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ea5f22cee4e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 02:24:27,448 unpacking tar archive at: mozilla-inbound-ea5f22cee4e9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509443 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 02:24:27.603367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:27.603698) ========= script_repo_revision: ea5f22cee4e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:27.604078) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:27.604341) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:24:27.613663) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-12 02:24:27.614036) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:24:27 INFO - MultiFileLogger online at 20160512 02:24:27 in /builds/slave/test 02:24:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 02:24:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:27 INFO - {'append_to_log': False, 02:24:27 INFO - 'base_work_dir': '/builds/slave/test', 02:24:27 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 02:24:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:27 INFO - 'branch': 'Mozilla-Inbound', 02:24:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:27 INFO - 'config_files': ('talos/linux_config.py',), 02:24:27 INFO - 'default_actions': ('clobber', 02:24:27 INFO - 'read-buildbot-config', 02:24:27 INFO - 'download-and-extract', 02:24:27 INFO - 'populate-webroot', 02:24:27 INFO - 'create-virtualenv', 02:24:27 INFO - 'install', 02:24:27 INFO - 'run-tests'), 02:24:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:27 INFO - 'download_minidump_stackwalk': True, 02:24:27 INFO - 'download_symbols': 'ondemand', 02:24:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:27 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:27 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:27 INFO - 'installer_path': 'installer.exe', 02:24:27 INFO - 'log_level': 'info', 02:24:27 INFO - 'log_name': 'talos', 02:24:27 INFO - 'log_to_console': True, 02:24:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:24:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:24:27 INFO - 'opt_config_files': (), 02:24:27 INFO - 'pip_index': False, 02:24:27 INFO - 'sps_profile': False, 02:24:27 INFO - 'sps_profile_interval': 0, 02:24:27 INFO - 'suite': 'dromaeojs-e10s', 02:24:27 INFO - 'system_bits': '32', 02:24:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:27 INFO - 'title': 'talos-linux64-ix-006', 02:24:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:27 INFO - 'use_talos_json': True, 02:24:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:27 INFO - 'work_dir': 'build'} 02:24:27 INFO - ##### 02:24:27 INFO - ##### Running clobber step. 02:24:27 INFO - ##### 02:24:27 INFO - Running pre-action listener: _resource_record_pre_action 02:24:27 INFO - Running main action method: clobber 02:24:27 INFO - rmtree: /builds/slave/test/build 02:24:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:24:28 INFO - Running post-action listener: _resource_record_post_action 02:24:28 INFO - ##### 02:24:28 INFO - ##### Finished clobber step (success) 02:24:28 INFO - ##### 02:24:28 INFO - ##### 02:24:28 INFO - ##### Running read-buildbot-config step. 02:24:28 INFO - ##### 02:24:28 INFO - Running pre-action listener: _resource_record_pre_action 02:24:28 INFO - Running main action method: read_buildbot_config 02:24:28 INFO - Using buildbot properties: 02:24:28 INFO - { 02:24:28 INFO - "project": "", 02:24:28 INFO - "product": "firefox", 02:24:28 INFO - "script_repo_revision": "production", 02:24:28 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 02:24:28 INFO - "repository": "", 02:24:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 02:24:28 INFO - "buildid": "20160511231539", 02:24:28 INFO - "pgo_build": "True", 02:24:28 INFO - "basedir": "/builds/slave/test", 02:24:28 INFO - "buildnumber": 528, 02:24:28 INFO - "slavename": "talos-linux64-ix-006", 02:24:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:24:28 INFO - "platform": "ubuntu64_hw", 02:24:28 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 02:24:28 INFO - "branch": "mozilla-inbound", 02:24:28 INFO - "repo_path": "integration/mozilla-inbound", 02:24:28 INFO - "revision": "ea5f22cee4e9", 02:24:28 INFO - "stage_platform": "linux64-pgo", 02:24:28 INFO - "builduid": "5b161d21f8dc4b419520ba23160afeef", 02:24:28 INFO - "slavebuilddir": "test" 02:24:28 INFO - } 02:24:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 02:24:28 INFO - Running post-action listener: _resource_record_post_action 02:24:28 INFO - ##### 02:24:28 INFO - ##### Finished read-buildbot-config step (success) 02:24:28 INFO - ##### 02:24:28 INFO - ##### 02:24:28 INFO - ##### Running download-and-extract step. 02:24:28 INFO - ##### 02:24:28 INFO - Running pre-action listener: _resource_record_pre_action 02:24:28 INFO - Running main action method: download_and_extract 02:24:28 INFO - mkdir: /builds/slave/test/build/tests 02:24:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:28 INFO - https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:24:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:24:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:24:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:24:29 INFO - Downloaded 1361 bytes. 02:24:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:24:29 INFO - Using the following test package requirements: 02:24:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:24:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:24:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:24:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'jsshell-linux-x86_64.zip'], 02:24:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:24:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:24:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:24:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:24:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:24:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:24:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:24:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:24:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:29 INFO - https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:24:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:24:30 INFO - Downloaded 23903374 bytes. 02:24:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:24:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:24:31 INFO - Return code: 0 02:24:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:24:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:31 INFO - https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:24:33 INFO - Downloaded 23903374 bytes. 02:24:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:24:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:24:34 INFO - Return code: 0 02:24:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:34 INFO - https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:24:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:24:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:24:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:24:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:24:35 INFO - Downloaded 10840239 bytes. 02:24:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:24:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:24:35 INFO - Return code: 0 02:24:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:35 INFO - https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:24:37 INFO - Downloaded 58377482 bytes. 02:24:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:24:37 INFO - mkdir: /builds/slave/test/properties 02:24:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:24:37 INFO - Writing to file /builds/slave/test/properties/build_url 02:24:37 INFO - Contents: 02:24:37 INFO - build_url:https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:24:38 INFO - Running post-action listener: _resource_record_post_action 02:24:38 INFO - Running post-action listener: set_extra_try_arguments 02:24:38 INFO - ##### 02:24:38 INFO - ##### Finished download-and-extract step (success) 02:24:38 INFO - ##### 02:24:38 INFO - ##### 02:24:38 INFO - ##### Running populate-webroot step. 02:24:38 INFO - ##### 02:24:38 INFO - Running pre-action listener: _resource_record_pre_action 02:24:38 INFO - Running main action method: populate_webroot 02:24:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:24:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:24:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:24:38 INFO - '--tppagecycles', 02:24:38 INFO - '7'], 02:24:38 INFO - 'tests': ['tsvgm']}}, 02:24:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:24:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:24:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:24:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:24:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['damp', 'tps']}, 02:24:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'tests': ['damp', 'tps']}, 02:24:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['dromaeo_dom']}, 02:24:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:24:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'other-e10s': {'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['a11yr', 02:24:38 INFO - 'ts_paint', 02:24:38 INFO - 'tpaint', 02:24:38 INFO - 'sessionrestore', 02:24:38 INFO - 'sessionrestore_no_auto_restore', 02:24:38 INFO - 'tabpaint']}, 02:24:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['tsvgx', 02:24:38 INFO - 'tsvgr_opacity', 02:24:38 INFO - 'tart', 02:24:38 INFO - 'tscrollx', 02:24:38 INFO - 'cart']}, 02:24:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:24:38 INFO - 'tsvgr_opacity', 02:24:38 INFO - 'tart', 02:24:38 INFO - 'tscrollx', 02:24:38 INFO - 'cart']}, 02:24:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'talos_options': ['--disable-e10s'], 02:24:38 INFO - 'tests': ['tp5o']}, 02:24:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'tests': ['tp5o']}, 02:24:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'talos_options': ['--disable-e10s', 02:24:38 INFO - '--xperf_path', 02:24:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:38 INFO - 'tests': ['tp5n']}, 02:24:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:38 INFO - 'talos_options': ['--xperf_path', 02:24:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:38 INFO - 'tests': ['tp5n']}}, 02:24:38 INFO - 'talos.zip': {'path': '', 02:24:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:24:38 INFO - Running post-action listener: _resource_record_post_action 02:24:38 INFO - ##### 02:24:38 INFO - ##### Finished populate-webroot step (success) 02:24:38 INFO - ##### 02:24:38 INFO - ##### 02:24:38 INFO - ##### Running create-virtualenv step. 02:24:38 INFO - ##### 02:24:38 INFO - Running pre-action listener: _resource_record_pre_action 02:24:38 INFO - Running main action method: create_virtualenv 02:24:38 INFO - Creating virtualenv /builds/slave/test/build/venv 02:24:38 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 02:24:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:24:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:24:39 INFO - Using real prefix '/usr' 02:24:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:24:39 INFO - Installing distribute.............................................................................................................................................................................................done. 02:24:41 INFO - Installing pip.................done. 02:24:41 INFO - Return code: 0 02:24:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:24:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:41 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:41 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 02:24:41 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 02:24:41 INFO - Using env: {'DISPLAY': ':0', 02:24:41 INFO - 'HOME': '/home/cltbld', 02:24:41 INFO - 'LANG': 'en_US.UTF-8', 02:24:41 INFO - 'LANGUAGE': 'en_US:en', 02:24:41 INFO - 'LOGNAME': 'cltbld', 02:24:41 INFO - 'MAIL': '/var/mail/cltbld', 02:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:41 INFO - 'MOZ_NO_REMOTE': '1', 02:24:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:41 INFO - 'NO_EM_RESTART': '1', 02:24:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:41 INFO - 'PWD': '/builds/slave/test', 02:24:41 INFO - 'SHELL': '/bin/bash', 02:24:41 INFO - 'SHLVL': '1', 02:24:41 INFO - 'TERM': 'linux', 02:24:41 INFO - 'TMOUT': '86400', 02:24:41 INFO - 'USER': 'cltbld', 02:24:41 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:41 INFO - '_': '/tools/buildbot/bin/python'} 02:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:42 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 02:24:42 INFO - Cleaning up... 02:24:42 INFO - Return code: 0 02:24:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:24:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:42 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:42 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>=0.7.1'] in /builds/slave/test/build 02:24:42 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>=0.7.1 02:24:42 INFO - Using env: {'DISPLAY': ':0', 02:24:42 INFO - 'HOME': '/home/cltbld', 02:24:42 INFO - 'LANG': 'en_US.UTF-8', 02:24:42 INFO - 'LANGUAGE': 'en_US:en', 02:24:42 INFO - 'LOGNAME': 'cltbld', 02:24:42 INFO - 'MAIL': '/var/mail/cltbld', 02:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:42 INFO - 'MOZ_NO_REMOTE': '1', 02:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:42 INFO - 'NO_EM_RESTART': '1', 02:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:42 INFO - 'PWD': '/builds/slave/test', 02:24:42 INFO - 'SHELL': '/bin/bash', 02:24:42 INFO - 'SHLVL': '1', 02:24:42 INFO - 'TERM': 'linux', 02:24:42 INFO - 'TMOUT': '86400', 02:24:42 INFO - 'USER': 'cltbld', 02:24:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:42 INFO - '_': '/tools/buildbot/bin/python'} 02:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:42 INFO - Downloading/unpacking psutil>=0.7.1 02:24:42 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 02:24:42 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 02:24:42 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 02:24:42 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 02:24:42 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 02:24:42 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 02:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:24:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:44 INFO - Installing collected packages: psutil 02:24:44 INFO - Running setup.py install for psutil 02:24:44 INFO - building 'psutil._psutil_linux' extension 02:24:44 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 02:24:44 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 02:24:44 INFO - building 'psutil._psutil_posix' extension 02:24:44 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 02:24:44 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 02:24:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:44 INFO - Successfully installed psutil 02:24:44 INFO - Cleaning up... 02:24:44 INFO - Return code: 0 02:24:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:24: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:24: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 mozsystemmonitor==0.0.0 02:24:44 INFO - Using env: {'DISPLAY': ':0', 02:24:44 INFO - 'HOME': '/home/cltbld', 02:24:44 INFO - 'LANG': 'en_US.UTF-8', 02:24:44 INFO - 'LANGUAGE': 'en_US:en', 02:24:44 INFO - 'LOGNAME': 'cltbld', 02:24:44 INFO - 'MAIL': '/var/mail/cltbld', 02:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:44 INFO - 'MOZ_NO_REMOTE': '1', 02:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:44 INFO - 'NO_EM_RESTART': '1', 02:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:44 INFO - 'PWD': '/builds/slave/test', 02:24:44 INFO - 'SHELL': '/bin/bash', 02:24:44 INFO - 'SHLVL': '1', 02:24:44 INFO - 'TERM': 'linux', 02:24:44 INFO - 'TMOUT': '86400', 02:24:44 INFO - 'USER': 'cltbld', 02:24:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:44 INFO - '_': '/tools/buildbot/bin/python'} 02:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:24:45 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 02:24:45 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 02:24:45 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 02:24:45 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 02:24:45 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 02:24:45 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 02:24:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:24:47 INFO - Installing collected packages: mozsystemmonitor 02:24:47 INFO - Running setup.py install for mozsystemmonitor 02:24:47 INFO - Successfully installed mozsystemmonitor 02:24:47 INFO - Cleaning up... 02:24:47 INFO - Return code: 0 02:24:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:24:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:47 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:47 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 02:24:47 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 02:24:47 INFO - Using env: {'DISPLAY': ':0', 02:24:47 INFO - 'HOME': '/home/cltbld', 02:24:47 INFO - 'LANG': 'en_US.UTF-8', 02:24:47 INFO - 'LANGUAGE': 'en_US:en', 02:24:47 INFO - 'LOGNAME': 'cltbld', 02:24:47 INFO - 'MAIL': '/var/mail/cltbld', 02:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:47 INFO - 'MOZ_NO_REMOTE': '1', 02:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:47 INFO - 'NO_EM_RESTART': '1', 02:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:47 INFO - 'PWD': '/builds/slave/test', 02:24:47 INFO - 'SHELL': '/bin/bash', 02:24:47 INFO - 'SHLVL': '1', 02:24:47 INFO - 'TERM': 'linux', 02:24:47 INFO - 'TMOUT': '86400', 02:24:47 INFO - 'USER': 'cltbld', 02:24:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:47 INFO - '_': '/tools/buildbot/bin/python'} 02:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:47 INFO - Downloading/unpacking blobuploader==1.2.4 02:24:47 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 02:24:47 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 02:24:47 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 02:24:47 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 02:24:47 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 02:24:47 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 02:24:49 INFO - Downloading blobuploader-1.2.4.tar.gz 02:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:24:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:24:49 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 02:24:49 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 02:24:49 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 02:24:49 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 02:24:49 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 02:24:49 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 02:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:24:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 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 02:24:50 INFO - Downloading docopt-0.6.1.tar.gz 02:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:24:50 INFO - Installing collected packages: blobuploader, requests, docopt 02:24:50 INFO - Running setup.py install for blobuploader 02:24:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:24:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:24:50 INFO - Running setup.py install for requests 02:24:51 INFO - Running setup.py install for docopt 02:24:51 INFO - Successfully installed blobuploader requests docopt 02:24:51 INFO - Cleaning up... 02:24:51 INFO - Return code: 0 02:24:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:51 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:51 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 02:24:51 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 02:24:51 INFO - Using env: {'DISPLAY': ':0', 02:24:51 INFO - 'HOME': '/home/cltbld', 02:24:51 INFO - 'LANG': 'en_US.UTF-8', 02:24:51 INFO - 'LANGUAGE': 'en_US:en', 02:24:51 INFO - 'LOGNAME': 'cltbld', 02:24:51 INFO - 'MAIL': '/var/mail/cltbld', 02:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:51 INFO - 'MOZ_NO_REMOTE': '1', 02:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:51 INFO - 'NO_EM_RESTART': '1', 02:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:51 INFO - 'PWD': '/builds/slave/test', 02:24:51 INFO - 'SHELL': '/bin/bash', 02:24:51 INFO - 'SHLVL': '1', 02:24:51 INFO - 'TERM': 'linux', 02:24:51 INFO - 'TMOUT': '86400', 02:24:51 INFO - 'USER': 'cltbld', 02:24:51 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:51 INFO - '_': '/tools/buildbot/bin/python'} 02:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:51 INFO - Running setup.py (path:/tmp/pip-bkYrWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:51 INFO - Running setup.py (path:/tmp/pip-WmKoQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 INFO - Running setup.py (path:/tmp/pip-XnVAJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 INFO - Running setup.py (path:/tmp/pip-avO90n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:51 INFO - Running setup.py (path:/tmp/pip-mrEuHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 INFO - Running setup.py (path:/tmp/pip-e7OJmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 INFO - Running setup.py (path:/tmp/pip-Cym6x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:51 INFO - Running setup.py (path:/tmp/pip-FLXXcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:52 INFO - Running setup.py (path:/tmp/pip-X5SGbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:52 INFO - Running setup.py (path:/tmp/pip-WXD2ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:52 INFO - Running setup.py (path:/tmp/pip-xOQwt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Running setup.py (path:/tmp/pip-5j5UaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 INFO - Running setup.py (path:/tmp/pip-8isjNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Running setup.py (path:/tmp/pip-khIi94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 INFO - Running setup.py (path:/tmp/pip-A85Xl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:52 INFO - Running setup.py (path:/tmp/pip-V0pKH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:52 INFO - Running setup.py (path:/tmp/pip-3ypfEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:24:52 INFO - Running setup.py install for manifestparser 02:24:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:24:52 INFO - Running setup.py install for mozcrash 02:24:52 INFO - Running setup.py install for mozdebug 02:24:52 INFO - Running setup.py install for mozdevice 02:24:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozfile 02:24:53 INFO - Running setup.py install for mozhttpd 02:24:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozinfo 02:24:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozInstall 02:24:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozleak 02:24:53 INFO - Running setup.py install for mozlog 02:24:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for moznetwork 02:24:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozprocess 02:24:53 INFO - Running setup.py install for mozprofile 02:24:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for mozrunner 02:24:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for mozscreenshot 02:24:54 INFO - Running setup.py install for moztest 02:24:54 INFO - Running setup.py install for mozversion 02:24:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:24:54 INFO - Cleaning up... 02:24:54 INFO - Return code: 0 02:24:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:54 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:54 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 02:24:54 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 02:24:54 INFO - Using env: {'DISPLAY': ':0', 02:24:54 INFO - 'HOME': '/home/cltbld', 02:24:54 INFO - 'LANG': 'en_US.UTF-8', 02:24:54 INFO - 'LANGUAGE': 'en_US:en', 02:24:54 INFO - 'LOGNAME': 'cltbld', 02:24:54 INFO - 'MAIL': '/var/mail/cltbld', 02:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:54 INFO - 'MOZ_NO_REMOTE': '1', 02:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:54 INFO - 'NO_EM_RESTART': '1', 02:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:54 INFO - 'PWD': '/builds/slave/test', 02:24:54 INFO - 'SHELL': '/bin/bash', 02:24:54 INFO - 'SHLVL': '1', 02:24:54 INFO - 'TERM': 'linux', 02:24:54 INFO - 'TMOUT': '86400', 02:24:54 INFO - 'USER': 'cltbld', 02:24:54 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:54 INFO - '_': '/tools/buildbot/bin/python'} 02:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:54 INFO - Running setup.py (path:/tmp/pip-K3ksT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:54 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)) 02:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:54 INFO - Running setup.py (path:/tmp/pip-PXKpPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 02:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:54 INFO - Running setup.py (path:/tmp/pip-yynfCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:54 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)) 02:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:54 INFO - Running setup.py (path:/tmp/pip-thuyz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:55 INFO - Running setup.py (path:/tmp/pip-DoweLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:55 INFO - Running setup.py (path:/tmp/pip-iRVBsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:55 INFO - Running setup.py (path:/tmp/pip-l7m2EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:55 INFO - Running setup.py (path:/tmp/pip-2M7yal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:55 INFO - Running setup.py (path:/tmp/pip-MTPmZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:55 INFO - Running setup.py (path:/tmp/pip-M7zi4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:55 INFO - Running setup.py (path:/tmp/pip-iAmrsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:55 INFO - Running setup.py (path:/tmp/pip-HWjcEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:55 INFO - Running setup.py (path:/tmp/pip-4GwaoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:55 INFO - Running setup.py (path:/tmp/pip-1Cw8m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:55 INFO - Running setup.py (path:/tmp/pip-59vUg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:55 INFO - Running setup.py (path:/tmp/pip-bkIRrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:55 INFO - Running setup.py (path:/tmp/pip-HRI4qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:55 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)) 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:24:55 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)) 02:24:55 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:24:55 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:24:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:24:55 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 02:24:55 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 02:24:55 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 02:24:55 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 02:24:55 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 02:24:55 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 02:24:57 INFO - Downloading blessings-1.6.tar.gz 02:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:24:57 INFO - Installing collected packages: blessings 02:24:57 INFO - Running setup.py install for blessings 02:24:58 INFO - Successfully installed blessings 02:24:58 INFO - Cleaning up... 02:24:58 INFO - Return code: 0 02:24:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:24:58 INFO - Reading from file tmpfile_stdout 02:24:58 INFO - Current package versions: 02:24:58 INFO - argparse == 1.2.1 02:24:58 INFO - blessings == 1.6 02:24:58 INFO - blobuploader == 1.2.4 02:24:58 INFO - docopt == 0.6.1 02:24:58 INFO - manifestparser == 1.1 02:24:58 INFO - mozInstall == 1.12 02:24:58 INFO - mozcrash == 0.17 02:24:58 INFO - mozdebug == 0.1 02:24:58 INFO - mozdevice == 0.48 02:24:58 INFO - mozfile == 1.2 02:24:58 INFO - mozhttpd == 0.7 02:24:58 INFO - mozinfo == 0.9 02:24:58 INFO - mozleak == 0.1 02:24:58 INFO - mozlog == 3.2 02:24:58 INFO - moznetwork == 0.27 02:24:58 INFO - mozprocess == 0.22 02:24:58 INFO - mozprofile == 0.28 02:24:58 INFO - mozrunner == 6.11 02:24:58 INFO - mozscreenshot == 0.1 02:24:58 INFO - mozsystemmonitor == 0.0 02:24:58 INFO - moztest == 0.7 02:24:58 INFO - mozversion == 1.4 02:24:58 INFO - psutil == 3.1.1 02:24:58 INFO - requests == 1.2.3 02:24:58 INFO - wsgiref == 0.1.2 02:24:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:58 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 02:24:58 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 02:24:58 INFO - Using env: {'DISPLAY': ':0', 02:24:58 INFO - 'HOME': '/home/cltbld', 02:24:58 INFO - 'LANG': 'en_US.UTF-8', 02:24:58 INFO - 'LANGUAGE': 'en_US:en', 02:24:58 INFO - 'LOGNAME': 'cltbld', 02:24:58 INFO - 'MAIL': '/var/mail/cltbld', 02:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:58 INFO - 'MOZ_NO_REMOTE': '1', 02:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:58 INFO - 'NO_EM_RESTART': '1', 02:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:58 INFO - 'PWD': '/builds/slave/test', 02:24:58 INFO - 'SHELL': '/bin/bash', 02:24:58 INFO - 'SHLVL': '1', 02:24:58 INFO - 'TERM': 'linux', 02:24:58 INFO - 'TMOUT': '86400', 02:24:58 INFO - 'USER': 'cltbld', 02:24:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:58 INFO - '_': '/tools/buildbot/bin/python'} 02:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 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)) 02:24:58 INFO - Cleaning up... 02:24:58 INFO - Return code: 0 02:24:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:24:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:58 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 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:24:58 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 02:24:58 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 02:24:58 INFO - Using env: {'DISPLAY': ':0', 02:24:58 INFO - 'HOME': '/home/cltbld', 02:24:58 INFO - 'LANG': 'en_US.UTF-8', 02:24:58 INFO - 'LANGUAGE': 'en_US:en', 02:24:58 INFO - 'LOGNAME': 'cltbld', 02:24:58 INFO - 'MAIL': '/var/mail/cltbld', 02:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:58 INFO - 'MOZ_NO_REMOTE': '1', 02:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:24:58 INFO - 'NO_EM_RESTART': '1', 02:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:58 INFO - 'PWD': '/builds/slave/test', 02:24:58 INFO - 'SHELL': '/bin/bash', 02:24:58 INFO - 'SHLVL': '1', 02:24:58 INFO - 'TERM': 'linux', 02:24:58 INFO - 'TMOUT': '86400', 02:24:58 INFO - 'USER': 'cltbld', 02:24:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:58 INFO - '_': '/tools/buildbot/bin/python'} 02:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:58 INFO - Downloading/unpacking jsonschema 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:24:58 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 02:25:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:25:00 INFO - Downloading/unpacking functools32 (from jsonschema) 02:25:00 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 02:25:00 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 02:25:00 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 02:25:00 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 02:25:00 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 02:25:00 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 02:25:00 INFO - Downloading functools32-3.2.3-2.tar.gz 02:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:25:00 INFO - warning: no files found matching '*.txt' 02:25:00 INFO - no previously-included directories found matching 'build' 02:25:00 INFO - no previously-included directories found matching 'dist' 02:25:00 INFO - no previously-included directories found matching '.git*' 02:25:00 INFO - Installing collected packages: jsonschema, functools32 02:25:00 INFO - Running setup.py install for functools32 02:25:01 INFO - warning: no files found matching '*.txt' 02:25:01 INFO - no previously-included directories found matching 'build' 02:25:01 INFO - no previously-included directories found matching 'dist' 02:25:01 INFO - no previously-included directories found matching '.git*' 02:25:01 INFO - Successfully installed jsonschema functools32 02:25:01 INFO - Cleaning up... 02:25:01 INFO - Return code: 0 02:25:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:25:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:01 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3eb40d2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f3770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', '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 02:25:01 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', 'flake8'] in /builds/slave/test/build 02:25:01 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 flake8 02:25:01 INFO - Using env: {'DISPLAY': ':0', 02:25:01 INFO - 'HOME': '/home/cltbld', 02:25:01 INFO - 'LANG': 'en_US.UTF-8', 02:25:01 INFO - 'LANGUAGE': 'en_US:en', 02:25:01 INFO - 'LOGNAME': 'cltbld', 02:25:01 INFO - 'MAIL': '/var/mail/cltbld', 02:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:01 INFO - 'MOZ_NO_REMOTE': '1', 02:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:01 INFO - 'NO_EM_RESTART': '1', 02:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:01 INFO - 'PWD': '/builds/slave/test', 02:25:01 INFO - 'SHELL': '/bin/bash', 02:25:01 INFO - 'SHLVL': '1', 02:25:01 INFO - 'TERM': 'linux', 02:25:01 INFO - 'TMOUT': '86400', 02:25:01 INFO - 'USER': 'cltbld', 02:25:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:01 INFO - '_': '/tools/buildbot/bin/python'} 02:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:01 INFO - Downloading/unpacking flake8 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:01 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 02:25:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:25:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:25:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 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 02:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:25:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:25:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:25:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:25:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:25:03 INFO - no previously-included directories found matching 'docs/_build' 02:25:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:25:03 INFO - Running setup.py install for pep8 02:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:25:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:25:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:25:04 INFO - no previously-included directories found matching 'docs/_build' 02:25:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:25:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:25:04 INFO - Cleaning up... 02:25:04 INFO - Return code: 0 02:25:04 INFO - Running post-action listener: _resource_record_post_action 02:25:04 INFO - Running post-action listener: _start_resource_monitoring 02:25:04 INFO - Starting resource monitoring. 02:25:04 INFO - ##### 02:25:04 INFO - ##### Finished create-virtualenv step (success) 02:25:04 INFO - ##### 02:25:04 INFO - ##### 02:25:04 INFO - ##### Running install step. 02:25:04 INFO - ##### 02:25:04 INFO - Running pre-action listener: _resource_record_pre_action 02:25:04 INFO - Running main action method: install 02:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:25:04 INFO - Reading from file tmpfile_stdout 02:25:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:25:04 INFO - Reading from file tmpfile_stdout 02:25:04 INFO - Output received: 02:25:04 INFO - Usage: mozinstall [options] installer 02:25:04 INFO - Options: 02:25:04 INFO - -h, --help show this help message and exit 02:25:04 INFO - -d DEST, --destination=DEST 02:25:04 INFO - Directory to install application into. [default: 02:25:04 INFO - "/builds/slave/test"] 02:25:04 INFO - --app=APP Application being installed. [default: firefox] 02:25:04 INFO - mkdir: /builds/slave/test/build/application 02:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:25:21 INFO - Reading from file tmpfile_stdout 02:25:21 INFO - Output received: 02:25:21 INFO - /builds/slave/test/build/application/firefox/firefox 02:25:21 INFO - Running post-action listener: _resource_record_post_action 02:25:21 INFO - ##### 02:25:21 INFO - ##### Finished install step (success) 02:25:21 INFO - ##### 02:25:21 INFO - ##### 02:25:21 INFO - ##### Running run-tests step. 02:25:21 INFO - ##### 02:25:21 INFO - Running pre-action listener: _resource_record_pre_action 02:25:21 INFO - Running main action method: run_tests 02:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:25:21 INFO - Python 2.7.3 02:25:21 INFO - Return code: 0 02:25:21 INFO - grabbing minidump binary from tooltool 02:25:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:25:21 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 0x18829c0>, '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 0x18838c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1883d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:25:21 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 02:25:21 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 02:25:21 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 02:25:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:25:21 INFO - Return code: 0 02:25:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:25:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:25:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:25:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:25:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:25:22 INFO - Return code: 0 02:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:25:22 INFO - Using env: {'DISPLAY': ':0', 02:25:22 INFO - 'HOME': '/home/cltbld', 02:25:22 INFO - 'LANG': 'en_US.UTF-8', 02:25:22 INFO - 'LANGUAGE': 'en_US:en', 02:25:22 INFO - 'LOGNAME': 'cltbld', 02:25:22 INFO - 'MAIL': '/var/mail/cltbld', 02:25:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:25:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:22 INFO - 'MOZ_NO_REMOTE': '1', 02:25:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:25:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:25:22 INFO - 'NO_EM_RESTART': '1', 02:25:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:22 INFO - 'PWD': '/builds/slave/test', 02:25:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:25:22 INFO - 'SHELL': '/bin/bash', 02:25:22 INFO - 'SHLVL': '1', 02:25:22 INFO - 'TERM': 'linux', 02:25:22 INFO - 'TMOUT': '86400', 02:25:22 INFO - 'USER': 'cltbld', 02:25:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824', 02:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:22 INFO - '_': '/tools/buildbot/bin/python'} 02:25:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:25:22 INFO - mozversion application_buildid: 20160511231539 02:25:22 INFO - mozversion application_changeset: ea5f22cee4e9144cc6fa918c889d19a08bbb87a4 02:25:22 INFO - mozversion application_display_name: Nightly 02:25:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:25:22 INFO - mozversion application_name: Firefox 02:25:22 INFO - mozversion application_remotingname: firefox 02:25:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:25:22 INFO - mozversion application_vendor: Mozilla 02:25:22 INFO - mozversion application_version: 49.0a1 02:25:22 INFO - mozversion platform_buildid: 20160511231539 02:25:22 INFO - mozversion platform_changeset: ea5f22cee4e9144cc6fa918c889d19a08bbb87a4 02:25:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:25:22 INFO - mozversion platform_version: 49.0a1 02:25:22 INFO - using testdate: 1463045122 02:25:22 INFO - actual date: 1463045122 02:25:22 INFO - starting webserver on 'localhost:36149' 02:25:22 INFO - SUITE-START | Running 2 tests 02:25:22 INFO - TEST-START | dromaeo_css 02:25:22 INFO - operating with platform_type : linux_ 02:25:22 INFO - Initialising browser for dromaeo_css test... 02:25:22 INFO - TEST-INFO | started process 4938 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp31tVMT/profile http://localhost:36149/getInfo.html) 02:25:25 INFO - PROCESS | 4938 | 02:25:25 INFO - PROCESS | 4938 | (process:5006): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:25:26 INFO - PROCESS | 4938 | __metrics Screen width/height:1600/1200 02:25:26 INFO - PROCESS | 4938 | colorDepth:24 02:25:26 INFO - PROCESS | 4938 | Browser inner width/height: 1024/697 02:25:26 INFO - PROCESS | 4938 | __metrics 02:25:27 ERROR - PROCESS | 4938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:25:28 INFO - TEST-INFO | 4938: exit 0 02:25:28 INFO - Browser initialized. 02:25:28 INFO - Running cycle 1/1 for dromaeo_css test... 02:25:28 INFO - TEST-INFO | started process 5057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp31tVMT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:30 INFO - PROCESS | 5057 | 02:25:30 INFO - PROCESS | 5057 | (process:5118): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:25:40 INFO - PROCESS | 5057 | RSS: Main: 163500032 02:25:40 INFO - PROCESS | 5057 | 02:26:29 INFO - PROCESS | 5057 | 1463045189639 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] 02:28:37 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36149/tests/dromaeo/cssquery-ext.html) 02:28:38 INFO - PROCESS | 5057 | RSS: Main: 166551552 02:28:38 INFO - PROCESS | 5057 | 02:31:40 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-ext.html (next: http://localhost:36149/tests/dromaeo/cssquery-jquery.html) 02:31:41 INFO - PROCESS | 5057 | RSS: Main: 166342656 02:31:41 INFO - PROCESS | 5057 | 02:34:42 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36149/tests/dromaeo/cssquery-mootools.html) 02:34:43 INFO - PROCESS | 5057 | RSS: Main: 167161856 02:34:43 INFO - PROCESS | 5057 | 02:37:45 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36149/tests/dromaeo/cssquery-prototype.html) 02:37:45 INFO - PROCESS | 5057 | RSS: Main: 164704256 02:37:45 INFO - PROCESS | 5057 | 02:40:48 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36149/tests/dromaeo/cssquery-yui.html) 02:40:49 INFO - PROCESS | 5057 | RSS: Main: 166375424 02:40:49 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | Cycle 1(1): loaded http://localhost:36149/tests/dromaeo/cssquery-yui.html (next: http://localhost:36149/tests/dromaeo/cssquery-dojo.html) 02:43:51 INFO - PROCESS | 5057 | RSS: Main: 168284160 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | __start_tp_report 02:43:51 INFO - PROCESS | 5057 | _x_x_mozilla_page_load 02:43:51 INFO - PROCESS | 5057 | _x_x_mozilla_page_load_details 02:43:51 INFO - PROCESS | 5057 | |i|pagename|runs| 02:43:51 INFO - PROCESS | 5057 | |0;dojo.html;3181.2524056846637;3190.856411461484;3215.3087086276455;3227.1770698471887;3237.1907023244203;5488.121900495927;5520.503154716075;5544.695041772701;5575.498205161534;5584.078627026546;5330.840074797753;5533.557315414767;5540.944590554093;5542.722863856801;5592.3009623797025;4937.827176048839;5008.774605142778;5117.590592752589;5156.896862062757;5176.456472070435;847.0309763792347;4861.392325959255;4951.084049450845;4963.429205641343;4983.252512123175;4982.476839931809;4989.3513843200335;5007.4491805901325;5025.748712564394;5029.522195391451;4982.128127577659;4999.250112483121;4999.500049995007;5002.24966255061;5042.520960508746;4793.18515852306;4924.556789888928;4952.653314268003;4990;5009.499050094998;5439.374471935699;5457.590680698963;5482.095454250044;5507.862303442406;5528.75120619573;5635.3519345275;5937.165742145752;5947.791827286068;5957.791477298317;5992.250968628921;5895.469407753323;5916.733746981353;5917.2307600012155;5991.970040149771;5995.100734889801;5550.306211723535;5567.777288908439;5571.777128914839;5577.107662773978;5588.720563971826;5839;5965.448983264382;6093.390660933871;6095.573309868267;6105.358937311607;5812.593118481665;5856.414358564195;5867.178594996704;5885.970570147122;5898.587098903122;5948.196993405922;5953.821385358359;5956.642601443928;5965.254343207099;5966.791162309299;5633;5792.913106303409;5812.331581868055;5833.416658334218;5845.064789633639;5855.765769369177;5897.49871260939;5898.380670029585;5902.852428689232;5943.910841337383;4410.360497727812;4412.33814927757;4424.80088396023;4425.269830477935;4443.600075993176;3918.6277303656107;3952.644262016375;3955.050787810962;3967.3652215645357;3970.1662650843646;5411.56707463402;5461.508464238158;5479.726013699299;5624.85937851557;5625.324961004706;5972.462478376967;6003.849903752441;6010.579259451883;6015.488683461867;6022.608530445595;5507.504324610724;5571.832845014575;5686.090225563973;5700.344460387026;5718.085106382959;4838.395200599925;4845.248986407112;4865.659403841697;4896.2655601660035;4915.0415668944215;6066.3630318815885;6104.816855494342;6106.267247930188;6118.296395914527;6123.601965872204;5722.7710891563875;5922.585419020714;5928.051511758098;5945.613535120204;5987.520998320211;6177.7837775677635;6218.9378106218355;6227.314995350598;6232.719527621359;6242.812715618538;5894.587378883437;5905.143754155538;5910.349861515144;5924.7037648118285;5930.614510056833;5759.9136012959;5815;5838.503727183237;5888.676122813115;6010.579259451796;5705.172749951317;5739.684317362585;5744.339400968942;5745.971270143623;5792.218050563289;5672.12082127271;5712.371639119777;5719.170720245625;5719.199312096393;5724;5446.183072539151;5600.019996500678;5628.634138780966;5686.459786320293;5689.288838895138;5835.358110607915;5880.852978675567;5886.646801191942;5894.970525147347;5900.144479050599;5749.741261643319;5756.136579513107;5768.94231057684;5773.538116950718;5780.94219057804 02:43:51 INFO - PROCESS | 5057 | |1;ext.html;589.7316720891995;633.7465013994403;644.8806970710416;649.8375406148465;660.2671035150985;43215.78392108039;44829.55170448295;44904;48631.27053094206;48848.26727599089;42578.36132458008;43415.348769768476;43479.34780978288;43802.561974380245;43866.12267754643;8569.485830850137;8784.560771961409;8787.340949428786;8792.120787921222;8793.560321983907;8571.95714021429;8677.69628063018;8734;8734.606942687562;8768.254698350647;8843.204111629952;8887.866681999774;8901.688440904569;8914.286857051453;8925.375223734341;8804.119588041176;8853.867191992125;8856.424332418372;8856.601452934634;8860.911390886105;8727.476351418873;8758.47449153053;8816.986046604658;8843.69047083349;8847.690330838454;38555.42166867499;39141.80429097836;39144.60855391438;39265;39466.802665986776;3469.930601387958;3598.9280214395567;3614.6204648512053;4124.381342798604;4445.711028783151;29516.557251641243;29898.551521727193;30017.849910750305;30453.39093218123;30857.382852343053;4245.957540424587;4311.892202694958;4316.978415107905;4333.739975601474;4342.676667982646;261033;262757;263236;264393;266168;55286.44713552813;56199.71900140473;56310.15534766982;56477;56565.43434565684;36676.81661591675;36700;36826.8158659205;39315.41026884599;39338.21323573512;16327.918360408123;16507.587310317096;16534.66930661404;16553.337866485206;16600.08699521538;13105.934470327587;13150.736985260433;13219.537316193888;13277.933610331887;13281.535146270027;19380.70928936067;19435.228341051694;19740.70388944167;19958.800411995984;19970.50073748139;7505.796753817879;7551.395888328921;7602.885956710654;7750.224977502205;7793.090692780305;955.2761593918009;1039.542601255445;1082.3073233130804;1185.0105162189495;1250.7185883176212;31676.20809479735;31924.361512769614;31971.36057278888;32279.51580726291;32806.83596582002;1120.1598800899326;1154.215133709069;1182.0071140242237;1205.8552973643218;1294.8964082873347;16569.25438355277;16833.663326733396;16943.745843812354;16970.57573560646;17131.828681713025;486055;492868;493162;494063;494875;57046.42953570494;57538.71230643904;57774;58108;58189.70905145447;1399.1814788348727;1435.626737048375;1455.9927200364143;1456.0026381928624;1473.454821715972;30083.699163008965;30375.102473356645;30977.380452391277;31236.219094522818;31424.214394640316;49679;49679.503204967485;49752.25371619356;50084.74957625189;50221.74889125531;381070;381218;383481;383703;384115;26009.349766255997;27710.86144569264;28301;28305.292367690974;30098.097057088322;46901.06197876092;47445.762771185924;48291.5170848287;48518.5148148514;48855.51144488652;39538;39983.60016399799;40053.39919901262;40433.191336172524;41156;39395.40906886342;40414.59585404109;40415.3937690929;40633.79683101566;41372.793136034124;38855.80572097235;40473.595264046984;41010.589894100674;41823;41969.160616788104;34132.31735365229;35238.11904702403;35994.64005359913;36220.4566931491;36358.8182059088;28149.43701125925;28186.15441536757;28222.294442639097;28481.43037139287;28486.43027139404 02:43:51 INFO - PROCESS | 5057 | |2;jquery.html;404.25616864968435;409.7418626265453;412.07489186775695;416.1905094591021;424.2956691891459;11282.774344513105;11795.764084718301;18266;19866.205351785928;19937.900310498444;20057.39827805168;20712.378628641127;21371;21464.356069317946;21539.353819385404;6237.126802247678;6239.532035097363;6490.415862572368;6508.609483430985;6524.184476940382;6211.503079753609;6443.83890402739;6455.774047908324;6459.773907913224;6468.902966455507;6379.808605741812;6453.451456626192;6454.22549294083;6458.709358078898;6464.80605581831;6198.938010619904;6414.9679251603675;6431.581947173442;6462.450691691212;6476.384743449365;6268.310485846553;6275.529335299825;6290.119383286344;6296.244450665904;6321.209848768904;23910.760892391027;24873.875630621733;25300.367490812878;26217.868910655514;26376.60435093475;2417.709874815016;2443.841150325241;2466.7779899808925;2486.6021436570245;2567.8383713818307;14960.775588366183;15303.387864485407;15644.296006679726;15681.451149209723;16046.1174635395;2071.6685330347073;2085.3952353817526;2089.6761002044555;2111.247664438528;2177.5536015116863;98038;98653.50673246587;101379;102260.48869755678;103435.96564034339;14605.707885842223;18180;18294;18771.3430029951;18899.433017009513;33072.834635826664;33167.170820729676;33861.153471163416;33928;33990;10201;10397.116245119098;10461.895381046092;10513.211509136845;10670.57317707283;8774.868376974351;8779.20987111163;8790.472571645721;8961.193492585568;9041.954790226138;8964.551772411354;8978.281737460988;9024.954875225712;9080.727578172666;9267.165955064076;3510.1575621851084;3512.1921957949817;3544.432890737521;3558.9822050890098;3599.964000359963;947.3415975896686;998.7852611688522;1012.3217444312328;1038.8182068138046;1105;19338.323158689378;19610.117544710236;19707;19808.60382792336;19811.60376792456;1013.5185786751234;1040.552562398176;1043.6190790361463;1051.3166441812882;1076.7631121153333;10720.892791072145;11322.094232461382;11411.885881141248;11431.256968297033;11462.254953428168;369560;369875;372106;456391;460349;89991;98764;99295;100444;100862.49568752109;1095.0746619106842;1102.4377567440504;1130.5025788653131;1133.8582677165189;1144.5364627325946;18217.362392316736;18217.635647286716;18231.726524101883;18312.084395780425;18339;41550;42558.361624576275;43228.78385608052;43962.78018610014;44767.328490072025;54815.45184548103;55140.72429637826;58551.4144858563;58774.412255876894;59840.10239846493;6178.443940045418;7434.810430331121;8450.281726053354;8473.618687158969;9005.909940900769;12099.274043557414;13849.19960405141;13966.371513281723;14061.929690351482;14219.786703199667;8802.81162043116;8918.598663060047;12194.207376520499;12472.251664900135;12507.499700012262;11936;12020.699482513008;12029;12282.75434491323;12337.444814983168;9588.56851441673;11751.069187417454;12378.62864114078;12382.81425778632;12752.48990040388;8405.789855253668;8454.323654107533;8491.23578877904;8594.871076933976;8721.127887211229;11827;12227.388630568614;12337.876621233672;12360.381980901098;12405.131640785059 02:43:51 INFO - PROCESS | 5057 | |3;mootools.html;1484.473012080711;1555.8133024037113;1682.7980642322916;1814.2380200315865;1843.6220574782171;7802.531848089118;8834.425762325445;8888.600012999414;9387.953060234699;9421.717348479548;8913.420627659198;9061.546922653857;9075.319351048665;9159.450432974027;9173.403728757627;2350.059976009601;2381.5117900830373;2389.486260454002;2391.449966507704;2394.724606670229;2313.2713195343413;2353.482233908537;2355;2370.040133745827;2372.418757404438;2313.525727225923;2342.636891281854;2351.635496498037;2374.9406264843346;2376.0733314007553;2276.8064714499287;2283.7830406111393;2317.2121478697322;2326.0230703104653;2330.4523436992376;2363.9054437822465;2367.715874095102;2383.165891937808;2383.797377222938;2403.9639405408934;8944.463332200088;8984.46093234408;9085.591148398338;9088.727338179866;9092.590833412512;5120.487951204887;5192.532672059533;5433.891322173535;5500.202470641734;5508.862278443031;3292.4732042873266;3305.7851239669344;3339.5658564386476;3357.4292370297107;3389.762716609838;1136.4829002803704;1144.2619510415818;1149.5574203931462;1166.3410173252084;1191.4936152135308;12244.571439999647;12324.630261092181;12366.381680916009;12380;12392.62822115338;4671.346011558385;4705.55297246761;4837.443693975238;4871.391076115486;4904.019196160711;10310.639127630648;10312.639057632947;10312.845307320396;10341.793164136676;10348.896511034944;5362.410134885159;5474.3704473986;5487.64330318528;5506.972465137648;5520.447955204448;4388.495323037828;4874.53691899269;4984.925226121611;5015.874603134878;5017.37282839645;4181.435506206684;4219.409282700424;4222.3244280914905;4236.6187043166265;4333.176696427731;1355.9525416610372;1365.5630198336437;1374.0519041861085;1382.4815694114707;1405.3535373727996;885.9424137431047;902.2195800632405;939.769756409684;943.6461327002237;970.3935040599625;5622.85942851432;5930.2883653960935;5994.1608174855555;6010.3689112643415;6263.812085637438;983.1986930651497;1026.7741096958657;1062.9096526795154;1080.9459527023616;1128.8871112888646;3803.7147213959065;3817.6373244541724;3940.2907476653904;3952.3676211805973;3966.702497312713;11348.906560636184;11693.064554835762;11703.941480292715;11882.821757673642;11914.642560723192;11513.942430287794;11555.595554155565;11597.6520704379;11765.588204412976;11836.763264734656;8547.615357308936;8563.143685631387;8672.78318042054;8710.52092134926;8727.519986400683;5822.94177058224;5915.40845915405;6007.429294217042;6014.0678194879865;6021.367756385692;10487.16102711766;10532.946705329407;10585.364878107339;10589.576416943237;10616.469176541297;10820.513076911715;10821.513031913426;10837.783244335227;10838;10958.835617465904;7718.729844455417;7775.22247775218;7865.488743231672;7867;7875.448718589644;8841.071687472722;8886.66699995006;8891.599878005376;8932.972708138388;8962.058983806866;8199.180081991753;8291.917080829115;8305.501669899826;8329;8403.453775504817;7633.198514156155;7639;7831.725889594065;7846.372290216883;8008.679652814055;6924.965375173092;7021.05215795861;7052.118485189351;7066.4700147490175;7098.6450677466955;5012.699238045729;5046.394432667957;5121.590272778094;5176.585873130065;5266.9736651318035;5112.821051263188;5153.871153221199;5177.042247184283;5273.89452210946;5325.813596524103 02:43:51 INFO - PROCESS | 5057 | |4;prototype.html;320.92297848516347;333.76136062715153;337.9611344695361;338.5074716287801;344.1688322700678;5503.6147469677135;5594.356648985368;5651.971740141299;5683.31800183978;5722;5663.745131469083;5678.375378708339;5679.261695979517;5724.885502289952;5756.366799652035;4411.205982923073;4419.62433193179;4429.047754732732;4431.202383570956;4471.8658440246845;4141.461609990697;4381.3647021181905;4385.934210986838;4468.597826195642;4477.932831007537;4279;4300.258395349872;4307.5261721210645;4321.157374312011;4345;4279.122779830127;4293.377460268259;4296.334068219431;4322.546132656089;4331.783410829477;3668.963310366889;3751.549814022335;3779.0174554615996;3782.3191825471404;3802;5391.81128660499;5409.188621706736;5432.320959880015;5443.618946673734;5468.031508656881;4968.850934471971;4987.202047672391;4988.02733466974;4988.5260900214425;5000.449950505442;5612.270404847343;5913.438223368773;5925.377835327272;5928.6739229342365;5952.613080149777;4100;4117.91764164718;4124.360724087771;4125.690573206992;4130.297849365615;6194.225721784777;6194.8141555752945;6208.609685271158;6252.155958945529;6263.154474146023;4103.676002546199;4192.958070419257;4205.137946720976;4248.766317852486;4252.107057517894;4286.635635970977;4303.698741088094;4307.203167414038;4311.461067366579;4315.784210789448;4889.706617602955;4922.8769280768265;4947.950520494749;4952.727599981963;4971.502849715;5920.763169473173;5965.957361745541;6046.3651316612;6064;6112.144299798032;1455.5560554031122;1467.955961321162;1473.7199932013098;1477.7808308145823;1485.2103215625145;888.4447220487195;920.4339331311195;921.8801555797703;926;932.4545141092401;5090.541851233407;5105.8723531912;5131.384233891957;5135.229715542699;5154.7628569143135;5459.754311056012;5470.863228419322;5491.75287112073;5495.477929596682;5531.585131115102;4869.342638743795;5037.949620503821;5049.636598118487;5248.7900483980975;5299.046171689133;2890.869910854017;2948.9262768430963;2974.82494414705;2983.880644774185;2990.9551356729667;5484.095124304445;5642.351129620064;5642.379338272786;5662.943370566324;5673.716314184357;5150.672481835837;5467.9610873933825;5497.615166938356;5517.227588137664;5549.139883318092;5565.3599836019375;5565.916511252334;5697.344805347355;5699.601027928088;5722.942770572325;5262;5474;5533.170024496358;5544.168374743821;5554.500284923129;5441.605693494849;5455.427180146027;5455.672659640434;5478.835634930959;5489.149181876815;5604.915926261027;5714.285714285715;5721.22763426932;5728.198052272602;6124.785632502841;4162.646175075031;4184.769837658836;4215.704900657047;4249.957500424956;4254.87235382939;5998;6042.848928776816;6051.243594550681;6075.665838378932;6090.23872015998;5618.803341883015;5919.904817608756;5938.643681378958;5940.1683764272175;5950.880982380415;5919.69766651336;5957.702114894151;5973.432523910222;6030.969845150747;6035.305939816802;5758.942410576008;5903.409659034062;5915.349311575809;5916.408359164049;5937.109433584997;5908.3205431375945;5986.88026239464;6010.3088144863905;6023.1567580537885;6034.969825150846;5801.883962320814;5873.794417195378;5929.881402372014;5934.347221805511;5952.642841429528 02:43:51 INFO - PROCESS | 5057 | |5;yui.html;788.4835432791524;798.1380109481756;846.0989046665301;872.258580206824;881.3566096749369;12941.288229147392;13398.464061437542;13439.126456780315;13487.595372138827;13515.59453216405;10930.672079837597;12880.16278941868;12888.42002109905;12898.93550532246;12932.418041188146;807.7576726981912;834.1158372125566;834.6995081770558;835.0438747633985;836.38107800228;812.163471624225;813.5972693516718;813.7924829168554;813.8046868751517;819.5902048975512;863.5336918064238;874.1171416868976;874.54960695242;874.7332063720563;879.4811061473729;865.7359505350865;867.197841996151;867.41449521573;869.0353707384797;871.7254064969578;635.9904601430982;637.0380725105078;637.9856028914003;639.1658885154872;639.2871947778241;12694.11141220115;12756.80864787029;12828.486860525662;12837.229766214057;12847.743045139045;14204.928975355057;14379.209143497104;14384.35270412834;14451.421943122263;14539.70920581593;11434.199606027581;11510.30938143714;11542.07663386927;11693.824592631117;11781.702567999244;3882.437046628251;3915.647591716731;3945.940810887839;3985.163115745697;4006.659433948118;55486.16770748442;56536.71731641357;59130.40869591335;59535.70232148855;59752;11540.942295288638;11784.351860647746;11815.468303926344;11871;11991.100667449977;20622.79377206239;20795.27216547444;20895.791042089688;21110.261140860304;21177.258795942067;8120.187981201832;8308.626111824982;8363.95818020906;8386.958065209636;8475.618597163142;4394.406755088086;4462.709923854939;4497.797599107983;4536;4850.854474365775;3271.6237632672073;3379.7803142795638;3396.745244106702;3400.7449441292;3403.50649155871;578.4360248757428;591.6479694581717;602.9276486821609;610.7923306075955;620.8851362497952;651.9601236028527;694.437505620439;701.9543729657556;752.6199269368933;755.2372104174823;8565.743027709179;8573.957130214434;9351.485668288173;9483.668071617598;9649.565769540388;608.528390497368;763.3587786259569;769.442154438028;772.8917951486796;779.2791667707461;3983.721139520264;4209.97895010523;4234.513031001413;4275.358696195533;4311.741295522279;66693;67639;68233.31766682368;68966;69097.65451172712;53192;53694.46305536973;57664.71167644135;60798.39201608016;62654.37345626576;53133.46866531285;55834.16248756272;56795.71602141963;58898.41101589015;59054;8961.686340978034;9097.1812536872;9193.908060919306;9314.534273286445;9346.719598412059;35473.64526354807;35523.82238088793;35999.46000809938;36673.81663091668;36822.631773682995;42802;44961.32558011567;48622;48892.75553622209;49574;11051.226414150933;11127.387993660426;11277.887221128012;11598.362090085126;11847.822282665938;12330.383480825743;12767.297798620793;12769.233845969271;12833;12925.28910909909;11761.529538818693;11913.463894124612;12079.51681932748;12334.259944403364;12822.422990965615;12225.327606981702;12446.502139914302;12592.307423091817;12701.36493175356;13085.607431776682;11874.287542747463;12114.091443141624;12220.266783992989;12293.938530307649;12394;9906.207503399855;10040.096391324816;10336;10355.749641871129;10414.375137491776;9418.199453046567;9514.714558563253;9572.664956726481;9579.616815327308;9818.509074546388 02:43:51 INFO - PROCESS | 5057 | __end_tp_report 02:43:51 INFO - PROCESS | 5057 | __start_cc_report 02:43:51 INFO - PROCESS | 5057 | _x_x_mozilla_cycle_collect,340 02:43:51 INFO - PROCESS | 5057 | __end_cc_report 02:43:51 INFO - PROCESS | 5057 | __startTimestamp1463046231706__endTimestamp 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | ------- Summary: start ------- 02:43:51 INFO - PROCESS | 5057 | Number of tests: 6 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#0] dojo.html Cycles:165 Average:5452.91 Median:5712.37 stddev:739.85 (13.0%) stddev-sans-first:720.33 02:43:51 INFO - PROCESS | 5057 | Values: 3181.3 3190.9 3215.3 3227.2 3237.2 5488.1 5520.5 5544.7 5575.5 5584.1 5330.8 5533.6 5540.9 5542.7 5592.3 4937.8 5008.8 5117.6 5156.9 5176.5 847.0 4861.4 4951.1 4963.4 4983.3 4982.5 4989.4 5007.4 5025.7 5029.5 4982.1 4999.3 4999.5 5002.2 5042.5 4793.2 4924.6 4952.7 4990.0 5009.5 5439.4 5457.6 5482.1 5507.9 5528.8 5635.4 5937.2 5947.8 5957.8 5992.3 5895.5 5916.7 5917.2 5992.0 5995.1 5550.3 5567.8 5571.8 5577.1 5588.7 5839.0 5965.4 6093.4 6095.6 6105.4 5812.6 5856.4 5867.2 5886.0 5898.6 5948.2 5953.8 5956.6 5965.3 5966.8 5633.0 5792.9 5812.3 5833.4 5845.1 5855.8 5897.5 5898.4 5902.9 5943.9 4410.4 4412.3 4424.8 4425.3 4443.6 3918.6 3952.6 3955.1 3967.4 3970.2 5411.6 5461.5 5479.7 5624.9 5625.3 5972.5 6003.8 6010.6 6015.5 6022.6 5507.5 5571.8 5686.1 5700.3 5718.1 4838.4 4845.2 4865.7 4896.3 4915.0 6066.4 6104.8 6106.3 6118.3 6123.6 5722.8 5922.6 5928.1 5945.6 5987.5 6177.8 6218.9 6227.3 6232.7 6242.8 5894.6 5905.1 5910.3 5924.7 5930.6 5759.9 5815.0 5838.5 5888.7 6010.6 5705.2 5739.7 5744.3 5746.0 5792.2 5672.1 5712.4 5719.2 5719.2 5724.0 5446.2 5600.0 5628.6 5686.5 5689.3 5835.4 5880.9 5886.6 5895.0 5900.1 5749.7 5756.1 5768.9 5773.5 5780.9 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#1] ext.html Cycles:180 Average:54268.49 Median:40018.50 stddev:103392.81 (258.4%) stddev-sans-first:103604.30 02:43:51 INFO - PROCESS | 5057 | Values: 589.7 633.7 644.9 649.8 660.3 43215.8 44829.6 44904.0 48631.3 48848.3 42578.4 43415.3 43479.3 43802.6 43866.1 8569.5 8784.6 8787.3 8792.1 8793.6 8572.0 8677.7 8734.0 8734.6 8768.3 8843.2 8887.9 8901.7 8914.3 8925.4 8804.1 8853.9 8856.4 8856.6 8860.9 8727.5 8758.5 8817.0 8843.7 8847.7 38555.4 39141.8 39144.6 39265.0 39466.8 3469.9 3598.9 3614.6 4124.4 4445.7 29516.6 29898.6 30017.8 30453.4 30857.4 4246.0 4311.9 4317.0 4333.7 4342.7 261033.0 262757.0 263236.0 264393.0 266168.0 55286.4 56199.7 56310.2 56477.0 56565.4 36676.8 36700.0 36826.8 39315.4 39338.2 16327.9 16507.6 16534.7 16553.3 16600.1 13105.9 13150.7 13219.5 13277.9 13281.5 19380.7 19435.2 19740.7 19958.8 19970.5 7505.8 7551.4 7602.9 7750.2 7793.1 955.3 1039.5 1082.3 1185.0 1250.7 31676.2 31924.4 31971.4 32279.5 32806.8 1120.2 1154.2 1182.0 1205.9 1294.9 16569.3 16833.7 16943.7 16970.6 17131.8 486055.0 492868.0 493162.0 494063.0 494875.0 57046.4 57538.7 57774.0 58108.0 58189.7 1399.2 1435.6 1456.0 1456.0 1473.5 30083.7 30375.1 30977.4 31236.2 31424.2 49679.0 49679.5 49752.3 50084.7 50221.7 381070.0 381218.0 383481.0 383703.0 384115.0 26009.3 27710.9 28301.0 28305.3 30098.1 46901.1 47445.8 48291.5 48518.5 48855.5 39538.0 39983.6 40053.4 40433.2 41156.0 39395.4 40414.6 40415.4 40633.8 41372.8 38855.8 40473.6 41010.6 41823.0 41969.2 34132.3 35238.1 35994.6 36220.5 36358.8 28149.4 28186.2 28222.3 28481.4 28486.4 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#2] jquery.html Cycles:180 Average:28689.29 Median:25087.12 stddev:68296.24 (272.2%) stddev-sans-first:68454.81 02:43:51 INFO - PROCESS | 5057 | Values: 404.3 409.7 412.1 416.2 424.3 11282.8 11795.8 18266.0 19866.2 19937.9 20057.4 20712.4 21371.0 21464.4 21539.4 6237.1 6239.5 6490.4 6508.6 6524.2 6211.5 6443.8 6455.8 6459.8 6468.9 6379.8 6453.5 6454.2 6458.7 6464.8 6198.9 6415.0 6431.6 6462.5 6476.4 6268.3 6275.5 6290.1 6296.2 6321.2 23910.8 24873.9 25300.4 26217.9 26376.6 2417.7 2443.8 2466.8 2486.6 2567.8 14960.8 15303.4 15644.3 15681.5 16046.1 2071.7 2085.4 2089.7 2111.2 2177.6 98038.0 98653.5 101379.0 102260.5 103436.0 14605.7 18180.0 18294.0 18771.3 18899.4 33072.8 33167.2 33861.2 33928.0 33990.0 10201.0 10397.1 10461.9 10513.2 10670.6 8774.9 8779.2 8790.5 8961.2 9042.0 8964.6 8978.3 9025.0 9080.7 9267.2 3510.2 3512.2 3544.4 3559.0 3600.0 947.3 998.8 1012.3 1038.8 1105.0 19338.3 19610.1 19707.0 19808.6 19811.6 1013.5 1040.6 1043.6 1051.3 1076.8 10720.9 11322.1 11411.9 11431.3 11462.3 369560.0 369875.0 372106.0 456391.0 460349.0 89991.0 98764.0 99295.0 100444.0 100862.5 1095.1 1102.4 1130.5 1133.9 1144.5 18217.4 18217.6 18231.7 18312.1 18339.0 41550.0 42558.4 43228.8 43962.8 44767.3 54815.5 55140.7 58551.4 58774.4 59840.1 6178.4 7434.8 8450.3 8473.6 9005.9 12099.3 13849.2 13966.4 14061.9 14219.8 8802.8 8918.6 12194.2 12472.3 12507.5 11936.0 12020.7 12029.0 12282.8 12337.4 9588.6 11751.1 12378.6 12382.8 12752.5 8405.8 8454.3 8491.2 8594.9 8721.1 11827.0 12227.4 12337.9 12360.4 12405.1 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#3] mootools.html Cycles:180 Average:5974.95 Median:4688.45 stddev:3438.77 (73.3%) stddev-sans-first:3431.86 02:43:51 INFO - PROCESS | 5057 | Values: 1484.5 1555.8 1682.8 1814.2 1843.6 7802.5 8834.4 8888.6 9388.0 9421.7 8913.4 9061.5 9075.3 9159.5 9173.4 2350.1 2381.5 2389.5 2391.4 2394.7 2313.3 2353.5 2355.0 2370.0 2372.4 2313.5 2342.6 2351.6 2374.9 2376.1 2276.8 2283.8 2317.2 2326.0 2330.5 2363.9 2367.7 2383.2 2383.8 2404.0 8944.5 8984.5 9085.6 9088.7 9092.6 5120.5 5192.5 5433.9 5500.2 5508.9 3292.5 3305.8 3339.6 3357.4 3389.8 1136.5 1144.3 1149.6 1166.3 1191.5 12244.6 12324.6 12366.4 12380.0 12392.6 4671.3 4705.6 4837.4 4871.4 4904.0 10310.6 10312.6 10312.8 10341.8 10348.9 5362.4 5474.4 5487.6 5507.0 5520.4 4388.5 4874.5 4984.9 5015.9 5017.4 4181.4 4219.4 4222.3 4236.6 4333.2 1356.0 1365.6 1374.1 1382.5 1405.4 885.9 902.2 939.8 943.6 970.4 5622.9 5930.3 5994.2 6010.4 6263.8 983.2 1026.8 1062.9 1080.9 1128.9 3803.7 3817.6 3940.3 3952.4 3966.7 11348.9 11693.1 11703.9 11882.8 11914.6 11513.9 11555.6 11597.7 11765.6 11836.8 8547.6 8563.1 8672.8 8710.5 8727.5 5822.9 5915.4 6007.4 6014.1 6021.4 10487.2 10532.9 10585.4 10589.6 10616.5 10820.5 10821.5 10837.8 10838.0 10958.8 7718.7 7775.2 7865.5 7867.0 7875.4 8841.1 8886.7 8891.6 8933.0 8962.1 8199.2 8291.9 8305.5 8329.0 8403.5 7633.2 7639.0 7831.7 7846.4 8008.7 6925.0 7021.1 7052.1 7066.5 7098.6 5012.7 5046.4 5121.6 5176.6 5267.0 5112.8 5153.9 5177.0 5273.9 5325.8 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#4] prototype.html Cycles:180 Average:4814.36 Median:5468.00 stddev:1416.34 (25.9%) stddev-sans-first:1379.57 02:43:51 INFO - PROCESS | 5057 | Values: 320.9 333.8 338.0 338.5 344.2 5503.6 5594.4 5652.0 5683.3 5722.0 5663.7 5678.4 5679.3 5724.9 5756.4 4411.2 4419.6 4429.0 4431.2 4471.9 4141.5 4381.4 4385.9 4468.6 4477.9 4279.0 4300.3 4307.5 4321.2 4345.0 4279.1 4293.4 4296.3 4322.5 4331.8 3669.0 3751.5 3779.0 3782.3 3802.0 5391.8 5409.2 5432.3 5443.6 5468.0 4968.9 4987.2 4988.0 4988.5 5000.4 5612.3 5913.4 5925.4 5928.7 5952.6 4100.0 4117.9 4124.4 4125.7 4130.3 6194.2 6194.8 6208.6 6252.2 6263.2 4103.7 4193.0 4205.1 4248.8 4252.1 4286.6 4303.7 4307.2 4311.5 4315.8 4889.7 4922.9 4948.0 4952.7 4971.5 5920.8 5966.0 6046.4 6064.0 6112.1 1455.6 1468.0 1473.7 1477.8 1485.2 888.4 920.4 921.9 926.0 932.5 5090.5 5105.9 5131.4 5135.2 5154.8 5459.8 5470.9 5491.8 5495.5 5531.6 4869.3 5037.9 5049.6 5248.8 5299.0 2890.9 2948.9 2974.8 2983.9 2991.0 5484.1 5642.4 5642.4 5662.9 5673.7 5150.7 5468.0 5497.6 5517.2 5549.1 5565.4 5565.9 5697.3 5699.6 5722.9 5262.0 5474.0 5533.2 5544.2 5554.5 5441.6 5455.4 5455.7 5478.8 5489.1 5604.9 5714.3 5721.2 5728.2 6124.8 4162.6 4184.8 4215.7 4250.0 4254.9 5998.0 6042.8 6051.2 6075.7 6090.2 5618.8 5919.9 5938.6 5940.2 5950.9 5919.7 5957.7 5973.4 6031.0 6035.3 5758.9 5903.4 5915.3 5916.4 5937.1 5908.3 5986.9 6010.3 6023.2 6035.0 5801.9 5873.8 5929.9 5934.3 5952.6 02:43:51 INFO - PROCESS | 5057 | 02:43:51 INFO - PROCESS | 5057 | [#5] yui.html Cycles:180 Average:15318.77 Median:26736.43 stddev:18546.05 (69.4%) stddev-sans-first:18565.98 02:43:51 INFO - PROCESS | 5057 | Values: 788.5 798.1 846.1 872.3 881.4 12941.3 13398.5 13439.1 13487.6 13515.6 10930.7 12880.2 12888.4 12898.9 12932.4 807.8 834.1 834.7 835.0 836.4 812.2 813.6 813.8 813.8 819.6 863.5 874.1 874.5 874.7 879.5 865.7 867.2 867.4 869.0 871.7 636.0 637.0 638.0 639.2 639.3 12694.1 12756.8 12828.5 12837.2 12847.7 14204.9 14379.2 14384.4 14451.4 14539.7 11434.2 11510.3 11542.1 11693.8 11781.7 3882.4 3915.6 3945.9 3985.2 4006.7 55486.2 56536.7 59130.4 59535.7 59752.0 11540.9 11784.4 11815.5 11871.0 11991.1 20622.8 20795.3 20895.8 21110.3 21177.3 8120.2 8308.6 8364.0 8387.0 8475.6 4394.4 4462.7 4497.8 4536.0 4850.9 3271.6 3379.8 3396.7 3400.7 3403.5 578.4 591.6 602.9 610.8 620.9 652.0 694.4 702.0 752.6 755.2 8565.7 8574.0 9351.5 9483.7 9649.6 608.5 763.4 769.4 772.9 779.3 3983.7 4210.0 4234.5 4275.4 4311.7 66693.0 67639.0 68233.3 68966.0 69097.7 53192.0 53694.5 57664.7 60798.4 62654.4 53133.5 55834.2 56795.7 58898.4 59054.0 8961.7 9097.2 9193.9 9314.5 9346.7 35473.6 35523.8 35999.5 36673.8 36822.6 42802.0 44961.3 48622.0 48892.8 49574.0 11051.2 11127.4 11277.9 11598.4 11847.8 12330.4 12767.3 12769.2 12833.0 12925.3 11761.5 11913.5 12079.5 12334.3 12822.4 12225.3 12446.5 12592.3 12701.4 13085.6 11874.3 12114.1 12220.3 12293.9 12394.0 9906.2 10040.1 10336.0 10355.7 10414.4 9418.2 9514.7 9572.7 9579.6 9818.5 02:43:51 INFO - PROCESS | 5057 | -------- Summary: end -------- 02:43:51 INFO - PROCESS | 5057 | 02:43:52 INFO - TEST-INFO | 5057: exit 0 02:43:52 INFO - TEST-OK | dromaeo_css | took 1109983ms 02:43:52 INFO - TEST-START | kraken 02:43:52 INFO - operating with platform_type : linux_ 02:43:52 INFO - Initialising browser for kraken test... 02:43:52 INFO - TEST-INFO | started process 5291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq6SwNl/profile http://localhost:36149/getInfo.html) 02:43:55 INFO - PROCESS | 5291 | 02:43:55 INFO - PROCESS | 5291 | (process:5360): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:43:56 INFO - PROCESS | 5291 | __metrics Screen width/height:1600/1200 02:43:56 INFO - PROCESS | 5291 | colorDepth:24 02:43:56 INFO - PROCESS | 5291 | Browser inner width/height: 1024/697 02:43:56 INFO - PROCESS | 5291 | __metrics 02:43:57 ERROR - PROCESS | 5291 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:43:58 INFO - TEST-INFO | 5291: exit 0 02:43:58 INFO - Browser initialized. 02:43:58 INFO - Running cycle 1/1 for kraken test... 02:43:58 INFO - TEST-INFO | started process 5411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq6SwNl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:44:00 INFO - PROCESS | 5411 | 02:44:00 INFO - PROCESS | 5411 | (process:5474): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 02:44:10 INFO - PROCESS | 5411 | RSS: Main: 163028992 02:44:10 INFO - PROCESS | 5411 | 02:44:12 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36149/tests/kraken/driver.html?testName=audio-beat-detection) 02:44:13 INFO - PROCESS | 5411 | RSS: Main: 167227392 02:44:13 INFO - PROCESS | 5411 | 02:44:16 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36149/tests/kraken/driver.html?testName=audio-dft) 02:44:16 INFO - PROCESS | 5411 | RSS: Main: 168095744 02:44:16 INFO - PROCESS | 5411 | 02:44:19 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36149/tests/kraken/driver.html?testName=audio-fft) 02:44:20 INFO - PROCESS | 5411 | RSS: Main: 162127872 02:44:20 INFO - PROCESS | 5411 | 02:44:22 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36149/tests/kraken/driver.html?testName=audio-oscillator) 02:44:23 INFO - PROCESS | 5411 | RSS: Main: 162639872 02:44:23 INFO - PROCESS | 5411 | 02:44:25 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36149/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:44:26 INFO - PROCESS | 5411 | RSS: Main: 163676160 02:44:26 INFO - PROCESS | 5411 | 02:44:29 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36149/tests/kraken/driver.html?testName=imaging-darkroom) 02:44:29 INFO - PROCESS | 5411 | RSS: Main: 163393536 02:44:29 INFO - PROCESS | 5411 | 02:44:33 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36149/tests/kraken/driver.html?testName=imaging-desaturate) 02:44:34 INFO - PROCESS | 5411 | RSS: Main: 162172928 02:44:34 INFO - PROCESS | 5411 | 02:44:36 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36149/tests/kraken/driver.html?testName=json-parse-financial) 02:44:37 INFO - PROCESS | 5411 | RSS: Main: 162840576 02:44:37 INFO - PROCESS | 5411 | 02:44:39 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36149/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:44:39 INFO - PROCESS | 5411 | RSS: Main: 162840576 02:44:39 INFO - PROCESS | 5411 | 02:44:41 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:44:42 INFO - PROCESS | 5411 | RSS: Main: 163229696 02:44:42 INFO - PROCESS | 5411 | 02:44:44 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:44:45 INFO - PROCESS | 5411 | RSS: Main: 163053568 02:44:45 INFO - PROCESS | 5411 | 02:44:47 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:44:48 INFO - PROCESS | 5411 | RSS: Main: 163729408 02:44:48 INFO - PROCESS | 5411 | 02:44:51 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:44:51 INFO - PROCESS | 5411 | RSS: Main: 164163584 02:44:51 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | Cycle 1(1): loaded http://localhost:36149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36149/tests/kraken/driver.html?testName=ai-astar) 02:44:53 INFO - PROCESS | 5411 | RSS: Main: 165289984 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | __start_tp_report 02:44:53 INFO - PROCESS | 5411 | _x_x_mozilla_page_load 02:44:53 INFO - PROCESS | 5411 | _x_x_mozilla_page_load_details 02:44:53 INFO - PROCESS | 5411 | |i|pagename|runs| 02:44:53 INFO - PROCESS | 5411 | |0;ai-astar;107;113;104;111;111;113;105;111;110;110 02:44:53 INFO - PROCESS | 5411 | |1;audio-beat-detection;150;126;151;218;127;164;150;136;177;126 02:44:53 INFO - PROCESS | 5411 | |2;audio-dft;162;164;161;161;165;168;163;162;160;161 02:44:53 INFO - PROCESS | 5411 | |3;audio-fft;75;75;74;161;77;76;76;75;162;75 02:44:53 INFO - PROCESS | 5411 | |4;audio-oscillator;91;93;170;92;91;91;147;91;92;144 02:44:53 INFO - PROCESS | 5411 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;118;117;117 02:44:53 INFO - PROCESS | 5411 | |6;imaging-darkroom;147;147;146;146;146;146;146;147;146;146 02:44:53 INFO - PROCESS | 5411 | |7;imaging-desaturate;95;96;97;95;95;95;95;96;96;95 02:44:53 INFO - PROCESS | 5411 | |8;json-parse-financial;56;58;57;58;58;58;58;108;57;56 02:44:53 INFO - PROCESS | 5411 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;60;59;59 02:44:53 INFO - PROCESS | 5411 | |10;stanford-crypto-aes;79;79;80;79;80;79;84;86;81;86 02:44:53 INFO - PROCESS | 5411 | |11;stanford-crypto-ccm;126;133;127;133;122;132;124;136;195;129 02:44:53 INFO - PROCESS | 5411 | |12;stanford-crypto-pbkdf2;176;172;168;168;173;170;170;170;169;167 02:44:53 INFO - PROCESS | 5411 | |13;stanford-crypto-sha256-iterative;67;68;66;65;64;65;65;65;68;65 02:44:53 INFO - PROCESS | 5411 | __end_tp_report 02:44:53 INFO - PROCESS | 5411 | __start_cc_report 02:44:53 INFO - PROCESS | 5411 | _x_x_mozilla_cycle_collect,834 02:44:53 INFO - PROCESS | 5411 | __end_cc_report 02:44:53 INFO - PROCESS | 5411 | __startTimestamp1463046293885__endTimestamp 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | ------- Summary: start ------- 02:44:53 INFO - PROCESS | 5411 | Number of tests: 14 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.14 (2.8%) stddev-sans-first:3.19 02:44:53 INFO - PROCESS | 5411 | Values: 107.0 113.0 104.0 111.0 111.0 113.0 105.0 111.0 110.0 110.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#1] audio-beat-detection Cycles:10 Average:152.50 Median:150.50 stddev:28.61 (19.0%) stddev-sans-first:30.33 02:44:53 INFO - PROCESS | 5411 | Values: 150.0 126.0 151.0 218.0 127.0 164.0 150.0 136.0 177.0 126.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.50 stddev:2.41 (1.5%) stddev-sans-first:2.54 02:44:53 INFO - PROCESS | 5411 | Values: 162.0 164.0 161.0 161.0 165.0 168.0 163.0 162.0 160.0 161.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#3] audio-fft Cycles:10 Average:92.60 Median:75.00 stddev:36.32 (48.4%) stddev-sans-first:37.96 02:44:53 INFO - PROCESS | 5411 | Values: 75.0 75.0 74.0 161.0 77.0 76.0 76.0 75.0 162.0 75.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:91.00 stddev:30.74 (33.8%) stddev-sans-first:31.81 02:44:53 INFO - PROCESS | 5411 | Values: 91.0 93.0 170.0 92.0 91.0 91.0 147.0 91.0 92.0 144.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:44:53 INFO - PROCESS | 5411 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 02:44:53 INFO - PROCESS | 5411 | Values: 147.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 02:44:53 INFO - PROCESS | 5411 | Values: 95.0 96.0 97.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:58.00 stddev:16.04 (27.7%) stddev-sans-first:16.85 02:44:53 INFO - PROCESS | 5411 | Values: 56.0 58.0 57.0 58.0 58.0 58.0 58.0 108.0 57.0 56.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 02:44:53 INFO - PROCESS | 5411 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 59.0 59.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:80.50 stddev:2.91 (3.6%) stddev-sans-first:2.96 02:44:53 INFO - PROCESS | 5411 | Values: 79.0 79.0 80.0 79.0 80.0 79.0 84.0 86.0 81.0 86.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:132.50 stddev:21.30 (16.1%) stddev-sans-first:22.30 02:44:53 INFO - PROCESS | 5411 | Values: 126.0 133.0 127.0 133.0 122.0 132.0 124.0 136.0 195.0 129.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:170.00 stddev:2.71 (1.6%) stddev-sans-first:1.94 02:44:53 INFO - PROCESS | 5411 | Values: 176.0 172.0 168.0 168.0 173.0 170.0 170.0 170.0 169.0 167.0 02:44:53 INFO - PROCESS | 5411 | 02:44:53 INFO - PROCESS | 5411 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:1.40 (2.1%) stddev-sans-first:1.41 02:44:53 INFO - PROCESS | 5411 | Values: 67.0 68.0 66.0 65.0 64.0 65.0 65.0 65.0 68.0 65.0 02:44:53 INFO - PROCESS | 5411 | -------- Summary: end -------- 02:44:53 INFO - PROCESS | 5411 | 02:44:54 INFO - TEST-INFO | 5411: exit 0 02:44:54 INFO - TEST-OK | kraken | took 62392ms 02:44:54 INFO - SUITE-END | took 1172s 02:44:55 INFO - Completed test suite (00:19:32) 02:44:55 INFO - javascript benchmark 02:44:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7337.218821339566, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.2524056846637, 3190.856411461484, 3215.3087086276455, 3227.1770698471887, 3237.1907023244203, 5488.121900495927, 5520.503154716075, 5544.695041772701, 5575.498205161534, 5584.078627026546, 5330.840074797753, 5533.557315414767, 5540.944590554093, 5542.722863856801, 5592.3009623797025, 4937.827176048839, 5008.774605142778, 5117.590592752589, 5156.896862062757, 5176.456472070435, 847.0309763792347, 4861.392325959255, 4951.084049450845, 4963.429205641343, 4983.252512123175, 4982.476839931809, 4989.3513843200335, 5007.4491805901325, 5025.748712564394, 5029.522195391451, 4982.128127577659, 4999.250112483121, 4999.500049995007, 5002.24966255061, 5042.520960508746, 4793.18515852306, 4924.556789888928, 4952.653314268003, 4990.0, 5009.499050094998, 5439.374471935699, 5457.590680698963, 5482.095454250044, 5507.862303442406, 5528.75120619573, 5635.3519345275, 5937.165742145752, 5947.791827286068, 5957.791477298317, 5992.250968628921, 5895.469407753323, 5916.733746981353, 5917.2307600012155, 5991.970040149771, 5995.100734889801, 5550.306211723535, 5567.777288908439, 5571.777128914839, 5577.107662773978, 5588.720563971826, 5839.0, 5965.448983264382, 6093.390660933871, 6095.573309868267, 6105.358937311607, 5812.593118481665, 5856.414358564195, 5867.178594996704, 5885.970570147122, 5898.587098903122, 5948.196993405922, 5953.821385358359, 5956.642601443928, 5965.254343207099, 5966.791162309299, 5633.0, 5792.913106303409, 5812.331581868055, 5833.416658334218, 5845.064789633639, 5855.765769369177, 5897.49871260939, 5898.380670029585, 5902.852428689232, 5943.910841337383, 4410.360497727812, 4412.33814927757, 4424.80088396023, 4425.269830477935, 4443.600075993176, 3918.6277303656107, 3952.644262016375, 3955.050787810962, 3967.3652215645357, 3970.1662650843646, 5411.56707463402, 5461.508464238158, 5479.726013699299, 5624.85937851557, 5625.324961004706, 5972.462478376967, 6003.849903752441, 6010.579259451883, 6015.488683461867, 6022.608530445595, 5507.504324610724, 5571.832845014575, 5686.090225563973, 5700.344460387026, 5718.085106382959, 4838.395200599925, 4845.248986407112, 4865.659403841697, 4896.2655601660035, 4915.0415668944215, 6066.3630318815885, 6104.816855494342, 6106.267247930188, 6118.296395914527, 6123.601965872204, 5722.7710891563875, 5922.585419020714, 5928.051511758098, 5945.613535120204, 5987.520998320211, 6177.7837775677635, 6218.9378106218355, 6227.314995350598, 6232.719527621359, 6242.812715618538, 5894.587378883437, 5905.143754155538, 5910.349861515144, 5924.7037648118285, 5930.614510056833, 5759.9136012959, 5815.0, 5838.503727183237, 5888.676122813115, 6010.579259451796, 5705.172749951317, 5739.684317362585, 5744.339400968942, 5745.971270143623, 5792.218050563289, 5672.12082127271, 5712.371639119777, 5719.170720245625, 5719.199312096393, 5724.0, 5446.183072539151, 5600.019996500678, 5628.634138780966, 5686.459786320293, 5689.288838895138, 5835.358110607915, 5880.852978675567, 5886.646801191942, 5894.970525147347, 5900.144479050599, 5749.741261643319, 5756.136579513107, 5768.94231057684, 5773.538116950718, 5780.94219057804], "value": 5402.969264504279, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.7316720891995, 633.7465013994403, 644.8806970710416, 649.8375406148465, 660.2671035150985, 43215.78392108039, 44829.55170448295, 44904.0, 48631.27053094206, 48848.26727599089, 42578.36132458008, 43415.348769768476, 43479.34780978288, 43802.561974380245, 43866.12267754643, 8569.485830850137, 8784.560771961409, 8787.340949428786, 8792.120787921222, 8793.560321983907, 8571.95714021429, 8677.69628063018, 8734.0, 8734.606942687562, 8768.254698350647, 8843.204111629952, 8887.866681999774, 8901.688440904569, 8914.286857051453, 8925.375223734341, 8804.119588041176, 8853.867191992125, 8856.424332418372, 8856.601452934634, 8860.911390886105, 8727.476351418873, 8758.47449153053, 8816.986046604658, 8843.69047083349, 8847.690330838454, 38555.42166867499, 39141.80429097836, 39144.60855391438, 39265.0, 39466.802665986776, 3469.930601387958, 3598.9280214395567, 3614.6204648512053, 4124.381342798604, 4445.711028783151, 29516.557251641243, 29898.551521727193, 30017.849910750305, 30453.39093218123, 30857.382852343053, 4245.957540424587, 4311.892202694958, 4316.978415107905, 4333.739975601474, 4342.676667982646, 261033.0, 262757.0, 263236.0, 264393.0, 266168.0, 55286.44713552813, 56199.71900140473, 56310.15534766982, 56477.0, 56565.43434565684, 36676.81661591675, 36700.0, 36826.8158659205, 39315.41026884599, 39338.21323573512, 16327.918360408123, 16507.587310317096, 16534.66930661404, 16553.337866485206, 16600.08699521538, 13105.934470327587, 13150.736985260433, 13219.537316193888, 13277.933610331887, 13281.535146270027, 19380.70928936067, 19435.228341051694, 19740.70388944167, 19958.800411995984, 19970.50073748139, 7505.796753817879, 7551.395888328921, 7602.885956710654, 7750.224977502205, 7793.090692780305, 955.2761593918009, 1039.542601255445, 1082.3073233130804, 1185.0105162189495, 1250.7185883176212, 31676.20809479735, 31924.361512769614, 31971.36057278888, 32279.51580726291, 32806.83596582002, 1120.1598800899326, 1154.215133709069, 1182.0071140242237, 1205.8552973643218, 1294.8964082873347, 16569.25438355277, 16833.663326733396, 16943.745843812354, 16970.57573560646, 17131.828681713025, 486055.0, 492868.0, 493162.0, 494063.0, 494875.0, 57046.42953570494, 57538.71230643904, 57774.0, 58108.0, 58189.70905145447, 1399.1814788348727, 1435.626737048375, 1455.9927200364143, 1456.0026381928624, 1473.454821715972, 30083.699163008965, 30375.102473356645, 30977.380452391277, 31236.219094522818, 31424.214394640316, 49679.0, 49679.503204967485, 49752.25371619356, 50084.74957625189, 50221.74889125531, 381070.0, 381218.0, 383481.0, 383703.0, 384115.0, 26009.349766255997, 27710.86144569264, 28301.0, 28305.292367690974, 30098.097057088322, 46901.06197876092, 47445.762771185924, 48291.5170848287, 48518.5148148514, 48855.51144488652, 39538.0, 39983.60016399799, 40053.39919901262, 40433.191336172524, 41156.0, 39395.40906886342, 40414.59585404109, 40415.3937690929, 40633.79683101566, 41372.793136034124, 38855.80572097235, 40473.595264046984, 41010.589894100674, 41823.0, 41969.160616788104, 34132.31735365229, 35238.11904702403, 35994.64005359913, 36220.4566931491, 36358.8182059088, 28149.43701125925, 28186.15441536757, 28222.294442639097, 28481.43037139287, 28486.43027139404], "value": 19666.338780745136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.25616864968435, 409.7418626265453, 412.07489186775695, 416.1905094591021, 424.2956691891459, 11282.774344513105, 11795.764084718301, 18266.0, 19866.205351785928, 19937.900310498444, 20057.39827805168, 20712.378628641127, 21371.0, 21464.356069317946, 21539.353819385404, 6237.126802247678, 6239.532035097363, 6490.415862572368, 6508.609483430985, 6524.184476940382, 6211.503079753609, 6443.83890402739, 6455.774047908324, 6459.773907913224, 6468.902966455507, 6379.808605741812, 6453.451456626192, 6454.22549294083, 6458.709358078898, 6464.80605581831, 6198.938010619904, 6414.9679251603675, 6431.581947173442, 6462.450691691212, 6476.384743449365, 6268.310485846553, 6275.529335299825, 6290.119383286344, 6296.244450665904, 6321.209848768904, 23910.760892391027, 24873.875630621733, 25300.367490812878, 26217.868910655514, 26376.60435093475, 2417.709874815016, 2443.841150325241, 2466.7779899808925, 2486.6021436570245, 2567.8383713818307, 14960.775588366183, 15303.387864485407, 15644.296006679726, 15681.451149209723, 16046.1174635395, 2071.6685330347073, 2085.3952353817526, 2089.6761002044555, 2111.247664438528, 2177.5536015116863, 98038.0, 98653.50673246587, 101379.0, 102260.48869755678, 103435.96564034339, 14605.707885842223, 18180.0, 18294.0, 18771.3430029951, 18899.433017009513, 33072.834635826664, 33167.170820729676, 33861.153471163416, 33928.0, 33990.0, 10201.0, 10397.116245119098, 10461.895381046092, 10513.211509136845, 10670.57317707283, 8774.868376974351, 8779.20987111163, 8790.472571645721, 8961.193492585568, 9041.954790226138, 8964.551772411354, 8978.281737460988, 9024.954875225712, 9080.727578172666, 9267.165955064076, 3510.1575621851084, 3512.1921957949817, 3544.432890737521, 3558.9822050890098, 3599.964000359963, 947.3415975896686, 998.7852611688522, 1012.3217444312328, 1038.8182068138046, 1105.0, 19338.323158689378, 19610.117544710236, 19707.0, 19808.60382792336, 19811.60376792456, 1013.5185786751234, 1040.552562398176, 1043.6190790361463, 1051.3166441812882, 1076.7631121153333, 10720.892791072145, 11322.094232461382, 11411.885881141248, 11431.256968297033, 11462.254953428168, 369560.0, 369875.0, 372106.0, 456391.0, 460349.0, 89991.0, 98764.0, 99295.0, 100444.0, 100862.49568752109, 1095.0746619106842, 1102.4377567440504, 1130.5025788653131, 1133.8582677165189, 1144.5364627325946, 18217.362392316736, 18217.635647286716, 18231.726524101883, 18312.084395780425, 18339.0, 41550.0, 42558.361624576275, 43228.78385608052, 43962.78018610014, 44767.328490072025, 54815.45184548103, 55140.72429637826, 58551.4144858563, 58774.412255876894, 59840.10239846493, 6178.443940045418, 7434.810430331121, 8450.281726053354, 8473.618687158969, 9005.909940900769, 12099.274043557414, 13849.19960405141, 13966.371513281723, 14061.929690351482, 14219.786703199667, 8802.81162043116, 8918.598663060047, 12194.207376520499, 12472.251664900135, 12507.499700012262, 11936.0, 12020.699482513008, 12029.0, 12282.75434491323, 12337.444814983168, 9588.56851441673, 11751.069187417454, 12378.62864114078, 12382.81425778632, 12752.48990040388, 8405.789855253668, 8454.323654107533, 8491.23578877904, 8594.871076933976, 8721.127887211229, 11827.0, 12227.388630568614, 12337.876621233672, 12360.381980901098, 12405.131640785059], "value": 10436.489175157929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.473012080711, 1555.8133024037113, 1682.7980642322916, 1814.2380200315865, 1843.6220574782171, 7802.531848089118, 8834.425762325445, 8888.600012999414, 9387.953060234699, 9421.717348479548, 8913.420627659198, 9061.546922653857, 9075.319351048665, 9159.450432974027, 9173.403728757627, 2350.059976009601, 2381.5117900830373, 2389.486260454002, 2391.449966507704, 2394.724606670229, 2313.2713195343413, 2353.482233908537, 2355.0, 2370.040133745827, 2372.418757404438, 2313.525727225923, 2342.636891281854, 2351.635496498037, 2374.9406264843346, 2376.0733314007553, 2276.8064714499287, 2283.7830406111393, 2317.2121478697322, 2326.0230703104653, 2330.4523436992376, 2363.9054437822465, 2367.715874095102, 2383.165891937808, 2383.797377222938, 2403.9639405408934, 8944.463332200088, 8984.46093234408, 9085.591148398338, 9088.727338179866, 9092.590833412512, 5120.487951204887, 5192.532672059533, 5433.891322173535, 5500.202470641734, 5508.862278443031, 3292.4732042873266, 3305.7851239669344, 3339.5658564386476, 3357.4292370297107, 3389.762716609838, 1136.4829002803704, 1144.2619510415818, 1149.5574203931462, 1166.3410173252084, 1191.4936152135308, 12244.571439999647, 12324.630261092181, 12366.381680916009, 12380.0, 12392.62822115338, 4671.346011558385, 4705.55297246761, 4837.443693975238, 4871.391076115486, 4904.019196160711, 10310.639127630648, 10312.639057632947, 10312.845307320396, 10341.793164136676, 10348.896511034944, 5362.410134885159, 5474.3704473986, 5487.64330318528, 5506.972465137648, 5520.447955204448, 4388.495323037828, 4874.53691899269, 4984.925226121611, 5015.874603134878, 5017.37282839645, 4181.435506206684, 4219.409282700424, 4222.3244280914905, 4236.6187043166265, 4333.176696427731, 1355.9525416610372, 1365.5630198336437, 1374.0519041861085, 1382.4815694114707, 1405.3535373727996, 885.9424137431047, 902.2195800632405, 939.769756409684, 943.6461327002237, 970.3935040599625, 5622.85942851432, 5930.2883653960935, 5994.1608174855555, 6010.3689112643415, 6263.812085637438, 983.1986930651497, 1026.7741096958657, 1062.9096526795154, 1080.9459527023616, 1128.8871112888646, 3803.7147213959065, 3817.6373244541724, 3940.2907476653904, 3952.3676211805973, 3966.702497312713, 11348.906560636184, 11693.064554835762, 11703.941480292715, 11882.821757673642, 11914.642560723192, 11513.942430287794, 11555.595554155565, 11597.6520704379, 11765.588204412976, 11836.763264734656, 8547.615357308936, 8563.143685631387, 8672.78318042054, 8710.52092134926, 8727.519986400683, 5822.94177058224, 5915.40845915405, 6007.429294217042, 6014.0678194879865, 6021.367756385692, 10487.16102711766, 10532.946705329407, 10585.364878107339, 10589.576416943237, 10616.469176541297, 10820.513076911715, 10821.513031913426, 10837.783244335227, 10838.0, 10958.835617465904, 7718.729844455417, 7775.22247775218, 7865.488743231672, 7867.0, 7875.448718589644, 8841.071687472722, 8886.66699995006, 8891.599878005376, 8932.972708138388, 8962.058983806866, 8199.180081991753, 8291.917080829115, 8305.501669899826, 8329.0, 8403.453775504817, 7633.198514156155, 7639.0, 7831.725889594065, 7846.372290216883, 8008.679652814055, 6924.965375173092, 7021.05215795861, 7052.118485189351, 7066.4700147490175, 7098.6450677466955, 5012.699238045729, 5046.394432667957, 5121.590272778094, 5176.585873130065, 5266.9736651318035, 5112.821051263188, 5153.871153221199, 5177.042247184283, 5273.89452210946, 5325.813596524103], "value": 4771.058792777352, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.92297848516347, 333.76136062715153, 337.9611344695361, 338.5074716287801, 344.1688322700678, 5503.6147469677135, 5594.356648985368, 5651.971740141299, 5683.31800183978, 5722.0, 5663.745131469083, 5678.375378708339, 5679.261695979517, 5724.885502289952, 5756.366799652035, 4411.205982923073, 4419.62433193179, 4429.047754732732, 4431.202383570956, 4471.8658440246845, 4141.461609990697, 4381.3647021181905, 4385.934210986838, 4468.597826195642, 4477.932831007537, 4279.0, 4300.258395349872, 4307.5261721210645, 4321.157374312011, 4345.0, 4279.122779830127, 4293.377460268259, 4296.334068219431, 4322.546132656089, 4331.783410829477, 3668.963310366889, 3751.549814022335, 3779.0174554615996, 3782.3191825471404, 3802.0, 5391.81128660499, 5409.188621706736, 5432.320959880015, 5443.618946673734, 5468.031508656881, 4968.850934471971, 4987.202047672391, 4988.02733466974, 4988.5260900214425, 5000.449950505442, 5612.270404847343, 5913.438223368773, 5925.377835327272, 5928.6739229342365, 5952.613080149777, 4100.0, 4117.91764164718, 4124.360724087771, 4125.690573206992, 4130.297849365615, 6194.225721784777, 6194.8141555752945, 6208.609685271158, 6252.155958945529, 6263.154474146023, 4103.676002546199, 4192.958070419257, 4205.137946720976, 4248.766317852486, 4252.107057517894, 4286.635635970977, 4303.698741088094, 4307.203167414038, 4311.461067366579, 4315.784210789448, 4889.706617602955, 4922.8769280768265, 4947.950520494749, 4952.727599981963, 4971.502849715, 5920.763169473173, 5965.957361745541, 6046.3651316612, 6064.0, 6112.144299798032, 1455.5560554031122, 1467.955961321162, 1473.7199932013098, 1477.7808308145823, 1485.2103215625145, 888.4447220487195, 920.4339331311195, 921.8801555797703, 926.0, 932.4545141092401, 5090.541851233407, 5105.8723531912, 5131.384233891957, 5135.229715542699, 5154.7628569143135, 5459.754311056012, 5470.863228419322, 5491.75287112073, 5495.477929596682, 5531.585131115102, 4869.342638743795, 5037.949620503821, 5049.636598118487, 5248.7900483980975, 5299.046171689133, 2890.869910854017, 2948.9262768430963, 2974.82494414705, 2983.880644774185, 2990.9551356729667, 5484.095124304445, 5642.351129620064, 5642.379338272786, 5662.943370566324, 5673.716314184357, 5150.672481835837, 5467.9610873933825, 5497.615166938356, 5517.227588137664, 5549.139883318092, 5565.3599836019375, 5565.916511252334, 5697.344805347355, 5699.601027928088, 5722.942770572325, 5262.0, 5474.0, 5533.170024496358, 5544.168374743821, 5554.500284923129, 5441.605693494849, 5455.427180146027, 5455.672659640434, 5478.835634930959, 5489.149181876815, 5604.915926261027, 5714.285714285715, 5721.22763426932, 5728.198052272602, 6124.785632502841, 4162.646175075031, 4184.769837658836, 4215.704900657047, 4249.957500424956, 4254.87235382939, 5998.0, 6042.848928776816, 6051.243594550681, 6075.665838378932, 6090.23872015998, 5618.803341883015, 5919.904817608756, 5938.643681378958, 5940.1683764272175, 5950.880982380415, 5919.69766651336, 5957.702114894151, 5973.432523910222, 6030.969845150747, 6035.305939816802, 5758.942410576008, 5903.409659034062, 5915.349311575809, 5916.408359164049, 5937.109433584997, 5908.3205431375945, 5986.88026239464, 6010.3088144863905, 6023.1567580537885, 6034.969825150846, 5801.883962320814, 5873.794417195378, 5929.881402372014, 5934.347221805511, 5952.642841429528], "value": 4356.110870513934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.4835432791524, 798.1380109481756, 846.0989046665301, 872.258580206824, 881.3566096749369, 12941.288229147392, 13398.464061437542, 13439.126456780315, 13487.595372138827, 13515.59453216405, 10930.672079837597, 12880.16278941868, 12888.42002109905, 12898.93550532246, 12932.418041188146, 807.7576726981912, 834.1158372125566, 834.6995081770558, 835.0438747633985, 836.38107800228, 812.163471624225, 813.5972693516718, 813.7924829168554, 813.8046868751517, 819.5902048975512, 863.5336918064238, 874.1171416868976, 874.54960695242, 874.7332063720563, 879.4811061473729, 865.7359505350865, 867.197841996151, 867.41449521573, 869.0353707384797, 871.7254064969578, 635.9904601430982, 637.0380725105078, 637.9856028914003, 639.1658885154872, 639.2871947778241, 12694.11141220115, 12756.80864787029, 12828.486860525662, 12837.229766214057, 12847.743045139045, 14204.928975355057, 14379.209143497104, 14384.35270412834, 14451.421943122263, 14539.70920581593, 11434.199606027581, 11510.30938143714, 11542.07663386927, 11693.824592631117, 11781.702567999244, 3882.437046628251, 3915.647591716731, 3945.940810887839, 3985.163115745697, 4006.659433948118, 55486.16770748442, 56536.71731641357, 59130.40869591335, 59535.70232148855, 59752.0, 11540.942295288638, 11784.351860647746, 11815.468303926344, 11871.0, 11991.100667449977, 20622.79377206239, 20795.27216547444, 20895.791042089688, 21110.261140860304, 21177.258795942067, 8120.187981201832, 8308.626111824982, 8363.95818020906, 8386.958065209636, 8475.618597163142, 4394.406755088086, 4462.709923854939, 4497.797599107983, 4536.0, 4850.854474365775, 3271.6237632672073, 3379.7803142795638, 3396.745244106702, 3400.7449441292, 3403.50649155871, 578.4360248757428, 591.6479694581717, 602.9276486821609, 610.7923306075955, 620.8851362497952, 651.9601236028527, 694.437505620439, 701.9543729657556, 752.6199269368933, 755.2372104174823, 8565.743027709179, 8573.957130214434, 9351.485668288173, 9483.668071617598, 9649.565769540388, 608.528390497368, 763.3587786259569, 769.442154438028, 772.8917951486796, 779.2791667707461, 3983.721139520264, 4209.97895010523, 4234.513031001413, 4275.358696195533, 4311.741295522279, 66693.0, 67639.0, 68233.31766682368, 68966.0, 69097.65451172712, 53192.0, 53694.46305536973, 57664.71167644135, 60798.39201608016, 62654.37345626576, 53133.46866531285, 55834.16248756272, 56795.71602141963, 58898.41101589015, 59054.0, 8961.686340978034, 9097.1812536872, 9193.908060919306, 9314.534273286445, 9346.719598412059, 35473.64526354807, 35523.82238088793, 35999.46000809938, 36673.81663091668, 36822.631773682995, 42802.0, 44961.32558011567, 48622.0, 48892.75553622209, 49574.0, 11051.226414150933, 11127.387993660426, 11277.887221128012, 11598.362090085126, 11847.822282665938, 12330.383480825743, 12767.297798620793, 12769.233845969271, 12833.0, 12925.28910909909, 11761.529538818693, 11913.463894124612, 12079.51681932748, 12334.259944403364, 12822.422990965615, 12225.327606981702, 12446.502139914302, 12592.307423091817, 12701.36493175356, 13085.607431776682, 11874.287542747463, 12114.091443141624, 12220.266783992989, 12293.938530307649, 12394.0, 9906.207503399855, 10040.096391324816, 10336.0, 10355.749641871129, 10414.375137491776, 9418.199453046567, 9514.714558563253, 9572.664956726481, 9579.616815327308, 9818.509074546388], "value": 6768.943500384736, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 104.0, 111.0, 111.0, 113.0, 105.0, 111.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 151.0, 218.0, 127.0, 164.0, 150.0, 136.0, 177.0, 126.0], "value": 152.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 161.0, 165.0, 168.0, 163.0, 162.0, 160.0, 161.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 161.0, 77.0, 76.0, 76.0, 75.0, 162.0, 75.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 170.0, 92.0, 91.0, 91.0, 147.0, 91.0, 92.0, 144.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 58.0, 58.0, 58.0, 58.0, 108.0, 57.0, 56.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 80.0, 79.0, 84.0, 86.0, 81.0, 86.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 127.0, 133.0, 122.0, 132.0, 124.0, 136.0, 195.0, 129.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 172.0, 168.0, 168.0, 173.0, 170.0, 170.0, 170.0, 169.0, 167.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 65.0, 64.0, 65.0, 65.0, 65.0, 68.0, 65.0], "value": 65.8, "unit": "score"}]}]} 02:44:55 INFO - Return code: 0 02:44:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:44:55 ERROR - # TBPL SUCCESS # 02:44:55 INFO - Running post-action listener: _resource_record_post_action 02:44:55 INFO - ##### 02:44:55 INFO - ##### Finished run-tests step (success) 02:44:55 INFO - ##### 02:44:55 INFO - Running post-run listener: _resource_record_post_run 02:44:56 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 294268928; Read time: 188; Write time: 231796 02:44:56 INFO - TinderboxPrint: CPU usage
13.4% 02:44:56 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 188 02:44:56 INFO - TinderboxPrint: I/O write bytes / time
294,268,928 / 231,796 02:44:56 INFO - TinderboxPrint: CPU idle
8,217.0 (86.5%) 02:44:56 INFO - TinderboxPrint: CPU user
1,222.6 (12.9%) 02:44:56 INFO - TinderboxPrint: Swap in / out
0 / 0 02:44:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5201920; Read time: 0; Write time: 340 02:44:56 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289067008; Read time: 188; Write time: 231456 02:44:56 INFO - Running post-run listener: _upload_blobber_files 02:44:56 INFO - Blob upload gear active. 02:44:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:44:56 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 02:44:56 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:44:56 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:44:56 INFO - (blobuploader) - INFO - Open directory for files ... 02:44:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:44:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:44:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:44:57 INFO - (blobuploader) - INFO - Done attempting. 02:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:44:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:44:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:44:58 INFO - (blobuploader) - INFO - Done attempting. 02:44:58 INFO - (blobuploader) - INFO - Iteration through files over. 02:44:58 INFO - Return code: 0 02:44:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:44:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:44:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/622f2a6345bb4ea47650ce6d5bcd62e79868ed6126ae7d7f7a32d7c515117ea686388910b145c6316061dcb30e27940dc1a53db559c7bbde6acd07453e8353d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c63331aded47f5fd1232dfafe94c3c568f52bb263c93d6580c3ca4e0b9a7ebab149c401544e2826081c6ba3f2e9c70dba2d9e5240b01a0a9ec8d3fc7b2e1dd0"} 02:44:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:44:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:44:58 INFO - Contents: 02:44:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/622f2a6345bb4ea47650ce6d5bcd62e79868ed6126ae7d7f7a32d7c515117ea686388910b145c6316061dcb30e27940dc1a53db559c7bbde6acd07453e8353d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c63331aded47f5fd1232dfafe94c3c568f52bb263c93d6580c3ca4e0b9a7ebab149c401544e2826081c6ba3f2e9c70dba2d9e5240b01a0a9ec8d3fc7b2e1dd0"} 02:44:58 INFO - Running post-run listener: copy_logs_to_upload_dir 02:44:58 INFO - Copying logs to upload dir... 02:44:58 INFO - mkdir: /builds/slave/test/build/upload/logs 02:44:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.578848 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-12 02:44:58.207097) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:44:58.210956) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/622f2a6345bb4ea47650ce6d5bcd62e79868ed6126ae7d7f7a32d7c515117ea686388910b145c6316061dcb30e27940dc1a53db559c7bbde6acd07453e8353d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c63331aded47f5fd1232dfafe94c3c568f52bb263c93d6580c3ca4e0b9a7ebab149c401544e2826081c6ba3f2e9c70dba2d9e5240b01a0a9ec8d3fc7b2e1dd0"} build_url:https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/RA3CzRjISEiaCWxWquviMA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/622f2a6345bb4ea47650ce6d5bcd62e79868ed6126ae7d7f7a32d7c515117ea686388910b145c6316061dcb30e27940dc1a53db559c7bbde6acd07453e8353d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c63331aded47f5fd1232dfafe94c3c568f52bb263c93d6580c3ca4e0b9a7ebab149c401544e2826081c6ba3f2e9c70dba2d9e5240b01a0a9ec8d3fc7b2e1dd0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:44:58.236160) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:44:58.236508) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1463045065.238461-554698824 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004396 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:44:58.318466) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:44:58.318768) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:44:58.319035) ========= ========= Total master_lag: 0.16 =========