builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1468843733.17 results: success (0) buildid: 20160718021354 builduid: a63e499af40648b1a871e9f1fa903410 revision: e29236a2cb1019532749ae35f37266cbbba0c04c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.173519) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.174294) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.174593) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.203583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.203835) ========= 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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 05:08:53-- 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% 229M=0s 2016-07-18 05:08:53 (229 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.360899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.361442) ========= 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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014124 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.508491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:53.509024) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e29236a2cb1019532749ae35f37266cbbba0c04c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e29236a2cb1019532749ae35f37266cbbba0c04c --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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 05:08:53,630 truncating revision to first 12 chars 2016-07-18 05:08:53,630 Setting DEBUG logging. 2016-07-18 05:08:53,630 attempt 1/10 2016-07-18 05:08:53,630 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e29236a2cb10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 05:08:53,949 unpacking tar archive at: mozilla-beta-e29236a2cb10/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685606 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:54.214194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:54.214509) ========= script_repo_revision: e29236a2cb1019532749ae35f37266cbbba0c04c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:54.214894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:54.215158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:08:54.226943) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-18 05:08:54.227220) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:54 INFO - MultiFileLogger online at 20160718 05:08:54 in /builds/slave/test 05:08:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:08:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:54 INFO - {'append_to_log': False, 05:08:54 INFO - 'base_work_dir': '/builds/slave/test', 05:08:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:08:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:54 INFO - 'branch': 'Mozilla-Beta', 05:08:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:54 INFO - 'config_files': ('talos/linux_config.py',), 05:08:54 INFO - 'default_actions': ('clobber', 05:08:54 INFO - 'read-buildbot-config', 05:08:54 INFO - 'download-and-extract', 05:08:54 INFO - 'populate-webroot', 05:08:54 INFO - 'create-virtualenv', 05:08:54 INFO - 'install', 05:08:54 INFO - 'run-tests'), 05:08:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:54 INFO - 'download_minidump_stackwalk': True, 05:08:54 INFO - 'download_symbols': 'ondemand', 05:08:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:54 INFO - 'installer_path': 'installer.exe', 05:08:54 INFO - 'log_level': 'info', 05:08:54 INFO - 'log_name': 'talos', 05:08:54 INFO - 'log_to_console': True, 05:08:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:54 INFO - 'opt_config_files': (), 05:08:54 INFO - 'pip_index': False, 05:08:54 INFO - 'sps_profile': False, 05:08:54 INFO - 'sps_profile_interval': 0, 05:08:54 INFO - 'suite': 'dromaeojs', 05:08:54 INFO - 'system_bits': '32', 05:08:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:54 INFO - 'title': 'talos-linux64-ix-053', 05:08:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:54 INFO - 'use_talos_json': True, 05:08:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:54 INFO - 'work_dir': 'build'} 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running clobber step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: clobber 05:08:54 INFO - rmtree: /builds/slave/test/build 05:08:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running read-buildbot-config step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: read_buildbot_config 05:08:54 INFO - Using buildbot properties: 05:08:54 INFO - { 05:08:54 INFO - "project": "", 05:08:54 INFO - "product": "firefox", 05:08:54 INFO - "script_repo_revision": "production", 05:08:54 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:08:54 INFO - "repository": "", 05:08:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:08:54 INFO - "buildid": "20160718021354", 05:08:54 INFO - "pgo_build": "True", 05:08:54 INFO - "basedir": "/builds/slave/test", 05:08:54 INFO - "buildnumber": 307, 05:08:54 INFO - "slavename": "talos-linux64-ix-053", 05:08:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:08:54 INFO - "platform": "ubuntu64_hw", 05:08:54 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:08:54 INFO - "branch": "mozilla-beta", 05:08:54 INFO - "repo_path": "releases/mozilla-beta", 05:08:54 INFO - "revision": "e29236a2cb1019532749ae35f37266cbbba0c04c", 05:08:54 INFO - "stage_platform": "linux64-pgo", 05:08:54 INFO - "builduid": "a63e499af40648b1a871e9f1fa903410", 05:08:54 INFO - "slavebuilddir": "test" 05:08:54 INFO - } 05:08:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running download-and-extract step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: download_and_extract 05:08:54 INFO - mkdir: /builds/slave/test/build/tests 05:08: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')]} 05:08:54 INFO - https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:08:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 05:08:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 05:08:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 05:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:08:56 INFO - Downloaded 1327 bytes. 05:08:56 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 05:08:56 INFO - Using the following test package requirements: 05:08:56 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 05:08:56 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:08:56 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 05:08:56 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'jsshell-linux-x86_64.zip'], 05:08:56 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:08:56 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 05:08:56 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 05:08:56 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 05:08:56 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:08:56 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 05:08:56 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:08:56 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:08:56 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')]} 05:08:56 INFO - https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:57 INFO - Downloaded 24200670 bytes. 05:08:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:08:58 INFO - Return code: 0 05:08:58 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:08: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')]} 05:08:58 INFO - https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 05:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:08:59 INFO - Downloaded 24200670 bytes. 05:08:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:09:01 INFO - Return code: 0 05:09: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')]} 05:09:01 INFO - https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:09:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 05:09:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 05:09:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 05:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:09:02 INFO - Downloaded 10840145 bytes. 05:09:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:09:02 INFO - Return code: 0 05:09:02 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')]} 05:09:02 INFO - https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:09:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 05:09:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 05:09:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 05:09:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:09:09 INFO - Downloaded 56029897 bytes. 05:09:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 05:09:09 INFO - mkdir: /builds/slave/test/properties 05:09:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:09 INFO - Contents: 05:09:09 INFO - build_url:https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 05:09:10 INFO - Running post-action listener: _resource_record_post_action 05:09:10 INFO - Running post-action listener: set_extra_try_arguments 05:09:10 INFO - ##### 05:09:10 INFO - ##### Running populate-webroot step. 05:09:10 INFO - ##### 05:09:10 INFO - Running pre-action listener: _resource_record_pre_action 05:09:10 INFO - Running main action method: populate_webroot 05:09:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:10 INFO - '--tppagecycles', 05:09:10 INFO - '7'], 05:09:10 INFO - 'tests': ['tsvgm']}}, 05:09:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['damp', 'tps']}, 05:09:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['damp', 'tps']}, 05:09:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['dromaeo_dom']}, 05:09:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['a11yr', 05:09:10 INFO - 'ts_paint', 05:09:10 INFO - 'tpaint', 05:09:10 INFO - 'sessionrestore', 05:09:10 INFO - 'sessionrestore_no_auto_restore', 05:09:10 INFO - 'tabpaint']}, 05:09:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tsvgx', 05:09:10 INFO - 'tsvgr_opacity', 05:09:10 INFO - 'tart', 05:09:10 INFO - 'tscrollx', 05:09:10 INFO - 'cart']}, 05:09:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:10 INFO - 'tsvgr_opacity', 05:09:10 INFO - 'tart', 05:09:10 INFO - 'tscrollx', 05:09:10 INFO - 'cart']}, 05:09:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s'], 05:09:10 INFO - 'tests': ['tp5o']}, 05:09:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'tests': ['tp5o']}, 05:09:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--disable-e10s', 05:09:10 INFO - '--xperf_path', 05:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:10 INFO - 'tests': ['tp5n']}, 05:09:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:10 INFO - 'talos_options': ['--xperf_path', 05:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:10 INFO - 'tests': ['tp5n']}}, 05:09:10 INFO - 'talos.zip': {'path': '', 05:09:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:10 INFO - Running post-action listener: _resource_record_post_action 05:09:10 INFO - ##### 05:09:10 INFO - ##### Running create-virtualenv step. 05:09:10 INFO - ##### 05:09:10 INFO - Running pre-action listener: _resource_record_pre_action 05:09:10 INFO - Running main action method: create_virtualenv 05:09:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:10 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 05:09:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:10 INFO - Using real prefix '/usr' 05:09:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:10 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:12 INFO - Installing pip.................done. 05:09:12 INFO - Return code: 0 05:09:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:12 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')]} 05:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 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')]} 05:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:12 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:12 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 05:09:12 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 05:09:12 INFO - Using env: {'DISPLAY': ':0', 05:09:12 INFO - 'HOME': '/home/cltbld', 05:09:12 INFO - 'LANG': 'en_US.UTF-8', 05:09:12 INFO - 'LANGUAGE': 'en_US:en', 05:09:12 INFO - 'LOGNAME': 'cltbld', 05:09:12 INFO - 'MAIL': '/var/mail/cltbld', 05:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:12 INFO - 'MOZ_NO_REMOTE': '1', 05:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:12 INFO - 'NO_EM_RESTART': '1', 05:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:12 INFO - 'PWD': '/builds/slave/test', 05:09:12 INFO - 'SHELL': '/bin/bash', 05:09:12 INFO - 'SHLVL': '1', 05:09:12 INFO - 'TERM': 'linux', 05:09:12 INFO - 'TMOUT': '86400', 05:09:12 INFO - 'USER': 'cltbld', 05:09:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:12 INFO - '_': '/tools/buildbot/bin/python'} 05:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:13 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 05:09:13 INFO - Cleaning up... 05:09:13 INFO - Return code: 0 05:09:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:09:13 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')]} 05:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:13 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')]} 05:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:13 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:13 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 05:09:13 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 05:09:13 INFO - Using env: {'DISPLAY': ':0', 05:09:13 INFO - 'HOME': '/home/cltbld', 05:09:13 INFO - 'LANG': 'en_US.UTF-8', 05:09:13 INFO - 'LANGUAGE': 'en_US:en', 05:09:13 INFO - 'LOGNAME': 'cltbld', 05:09:13 INFO - 'MAIL': '/var/mail/cltbld', 05:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:13 INFO - 'MOZ_NO_REMOTE': '1', 05:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:13 INFO - 'NO_EM_RESTART': '1', 05:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:13 INFO - 'PWD': '/builds/slave/test', 05:09:13 INFO - 'SHELL': '/bin/bash', 05:09:13 INFO - 'SHLVL': '1', 05:09:13 INFO - 'TERM': 'linux', 05:09:13 INFO - 'TMOUT': '86400', 05:09:13 INFO - 'USER': 'cltbld', 05:09:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:13 INFO - '_': '/tools/buildbot/bin/python'} 05:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:13 INFO - Downloading/unpacking psutil>=0.7.1 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:13 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 05:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:15 INFO - Installing collected packages: psutil 05:09:15 INFO - Running setup.py install for psutil 05:09:15 INFO - building 'psutil._psutil_linux' extension 05:09:15 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 05:09:15 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 05:09:15 INFO - building 'psutil._psutil_posix' extension 05:09:15 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 05:09:15 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 05:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:15 INFO - Successfully installed psutil 05:09:15 INFO - Cleaning up... 05:09:15 INFO - Return code: 0 05:09:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:09:15 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')]} 05:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:15 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')]} 05:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:15 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:15 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 05:09:15 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 05:09:15 INFO - Using env: {'DISPLAY': ':0', 05:09:15 INFO - 'HOME': '/home/cltbld', 05:09:15 INFO - 'LANG': 'en_US.UTF-8', 05:09:15 INFO - 'LANGUAGE': 'en_US:en', 05:09:15 INFO - 'LOGNAME': 'cltbld', 05:09:15 INFO - 'MAIL': '/var/mail/cltbld', 05:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:15 INFO - 'MOZ_NO_REMOTE': '1', 05:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:15 INFO - 'NO_EM_RESTART': '1', 05:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:15 INFO - 'PWD': '/builds/slave/test', 05:09:15 INFO - 'SHELL': '/bin/bash', 05:09:15 INFO - 'SHLVL': '1', 05:09:15 INFO - 'TERM': 'linux', 05:09:15 INFO - 'TMOUT': '86400', 05:09:15 INFO - 'USER': 'cltbld', 05:09:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:15 INFO - '_': '/tools/buildbot/bin/python'} 05:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:16 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 05:09:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:18 INFO - Installing collected packages: mozsystemmonitor 05:09:18 INFO - Running setup.py install for mozsystemmonitor 05:09:18 INFO - Successfully installed mozsystemmonitor 05:09:18 INFO - Cleaning up... 05:09:18 INFO - Return code: 0 05:09:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:18 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')]} 05:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:18 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')]} 05:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:18 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:18 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 05:09:18 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 05:09:18 INFO - Using env: {'DISPLAY': ':0', 05:09:18 INFO - 'HOME': '/home/cltbld', 05:09:18 INFO - 'LANG': 'en_US.UTF-8', 05:09:18 INFO - 'LANGUAGE': 'en_US:en', 05:09:18 INFO - 'LOGNAME': 'cltbld', 05:09:18 INFO - 'MAIL': '/var/mail/cltbld', 05:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:18 INFO - 'MOZ_NO_REMOTE': '1', 05:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:18 INFO - 'NO_EM_RESTART': '1', 05:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:18 INFO - 'PWD': '/builds/slave/test', 05:09:18 INFO - 'SHELL': '/bin/bash', 05:09:18 INFO - 'SHLVL': '1', 05:09:18 INFO - 'TERM': 'linux', 05:09:18 INFO - 'TMOUT': '86400', 05:09:18 INFO - 'USER': 'cltbld', 05:09:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:18 INFO - '_': '/tools/buildbot/bin/python'} 05:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:18 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 05:09:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:20 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 05:09:21 INFO - Downloading docopt-0.6.1.tar.gz 05:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:21 INFO - Running setup.py install for blobuploader 05:09:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:21 INFO - Running setup.py install for requests 05:09:21 INFO - Running setup.py install for docopt 05:09:21 INFO - Successfully installed blobuploader requests docopt 05:09:21 INFO - Cleaning up... 05:09:21 INFO - Return code: 0 05:09:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:21 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:21 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 05:09:21 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 05:09:21 INFO - Using env: {'DISPLAY': ':0', 05:09:21 INFO - 'HOME': '/home/cltbld', 05:09:21 INFO - 'LANG': 'en_US.UTF-8', 05:09:21 INFO - 'LANGUAGE': 'en_US:en', 05:09:21 INFO - 'LOGNAME': 'cltbld', 05:09:21 INFO - 'MAIL': '/var/mail/cltbld', 05:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:21 INFO - 'MOZ_NO_REMOTE': '1', 05:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:21 INFO - 'NO_EM_RESTART': '1', 05:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:21 INFO - 'PWD': '/builds/slave/test', 05:09:21 INFO - 'SHELL': '/bin/bash', 05:09:21 INFO - 'SHLVL': '1', 05:09:21 INFO - 'TERM': 'linux', 05:09:21 INFO - 'TMOUT': '86400', 05:09:21 INFO - 'USER': 'cltbld', 05:09:21 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:21 INFO - '_': '/tools/buildbot/bin/python'} 05:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:21 INFO - Running setup.py (path:/tmp/pip-rh0FcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:22 INFO - Running setup.py (path:/tmp/pip-ibbksC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:22 INFO - Running setup.py (path:/tmp/pip-0lo8Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:22 INFO - Running setup.py (path:/tmp/pip-D6n12X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:22 INFO - Running setup.py (path:/tmp/pip-7O24Vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:22 INFO - Running setup.py (path:/tmp/pip-6vtp_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:22 INFO - Running setup.py (path:/tmp/pip-bUrs7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:22 INFO - Running setup.py (path:/tmp/pip-Mc5Qef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:22 INFO - Running setup.py (path:/tmp/pip-TRi2BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:22 INFO - Running setup.py (path:/tmp/pip-iPT1RP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:22 INFO - Running setup.py (path:/tmp/pip-zq2n77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:22 INFO - Running setup.py (path:/tmp/pip-4An_T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:22 INFO - Running setup.py (path:/tmp/pip-HTl9uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:22 INFO - Running setup.py (path:/tmp/pip-KzhhPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:22 INFO - Running setup.py (path:/tmp/pip-mnfe4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:23 INFO - Running setup.py (path:/tmp/pip-i4by7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:23 INFO - Running setup.py (path:/tmp/pip-Xrqffs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:23 INFO - Running setup.py install for manifestparser 05:09:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozcrash 05:09:23 INFO - Running setup.py install for mozdebug 05:09:23 INFO - Running setup.py install for mozdevice 05:09:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozfile 05:09:23 INFO - Running setup.py install for mozhttpd 05:09:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozinfo 05:09:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozInstall 05:09:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:23 INFO - Running setup.py install for mozleak 05:09:24 INFO - Running setup.py install for mozlog 05:09:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Running setup.py install for moznetwork 05:09:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Running setup.py install for mozprocess 05:09:24 INFO - Running setup.py install for mozprofile 05:09:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Running setup.py install for mozrunner 05:09:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Running setup.py install for mozscreenshot 05:09:24 INFO - Running setup.py install for moztest 05:09:24 INFO - Running setup.py install for mozversion 05:09:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:24 INFO - Cleaning up... 05:09:24 INFO - Return code: 0 05:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:24 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:24 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 05:09:24 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 05:09:24 INFO - Using env: {'DISPLAY': ':0', 05:09:24 INFO - 'HOME': '/home/cltbld', 05:09:24 INFO - 'LANG': 'en_US.UTF-8', 05:09:24 INFO - 'LANGUAGE': 'en_US:en', 05:09:24 INFO - 'LOGNAME': 'cltbld', 05:09:24 INFO - 'MAIL': '/var/mail/cltbld', 05:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:24 INFO - 'MOZ_NO_REMOTE': '1', 05:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:24 INFO - 'NO_EM_RESTART': '1', 05:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:24 INFO - 'PWD': '/builds/slave/test', 05:09:24 INFO - 'SHELL': '/bin/bash', 05:09:24 INFO - 'SHLVL': '1', 05:09:24 INFO - 'TERM': 'linux', 05:09:24 INFO - 'TMOUT': '86400', 05:09:24 INFO - 'USER': 'cltbld', 05:09:24 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:24 INFO - '_': '/tools/buildbot/bin/python'} 05:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:25 INFO - Running setup.py (path:/tmp/pip-FYc2r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:25 INFO - Running setup.py (path:/tmp/pip-jXw9sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:25 INFO - Running setup.py (path:/tmp/pip-EhBgXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:25 INFO - Running setup.py (path:/tmp/pip-xs0DVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:25 INFO - Running setup.py (path:/tmp/pip-r1MD6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:25 INFO - Running setup.py (path:/tmp/pip-_UxlDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:25 INFO - Running setup.py (path:/tmp/pip-ntouZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:25 INFO - Running setup.py (path:/tmp/pip-ZbVDPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:25 INFO - Running setup.py (path:/tmp/pip-3U7i4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:25 INFO - Running setup.py (path:/tmp/pip-C45Kec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 INFO - Running setup.py (path:/tmp/pip-eM3f5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:25 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)) 05:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:25 INFO - Running setup.py (path:/tmp/pip-DbMDkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:26 INFO - Running setup.py (path:/tmp/pip-q_lwxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:26 INFO - Running setup.py (path:/tmp/pip-fPM3vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:26 INFO - Running setup.py (path:/tmp/pip-0rWHkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:26 INFO - Running setup.py (path:/tmp/pip-WISvvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:26 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)) 05:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:26 INFO - Running setup.py (path:/tmp/pip-CWxvzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 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)) 05:09:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:28 INFO - Downloading blessings-1.6.tar.gz 05:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:28 INFO - Installing collected packages: blessings 05:09:28 INFO - Running setup.py install for blessings 05:09:28 INFO - Successfully installed blessings 05:09:28 INFO - Cleaning up... 05:09:28 INFO - Return code: 0 05:09:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:28 INFO - Reading from file tmpfile_stdout 05:09:28 INFO - Current package versions: 05:09:28 INFO - argparse == 1.2.1 05:09:28 INFO - blessings == 1.6 05:09:28 INFO - blobuploader == 1.2.4 05:09:28 INFO - docopt == 0.6.1 05:09:28 INFO - manifestparser == 1.1 05:09:28 INFO - mozInstall == 1.12 05:09:28 INFO - mozcrash == 0.17 05:09:28 INFO - mozdebug == 0.1 05:09:28 INFO - mozdevice == 0.48 05:09:28 INFO - mozfile == 1.2 05:09:28 INFO - mozhttpd == 0.7 05:09:28 INFO - mozinfo == 0.9 05:09:28 INFO - mozleak == 0.1 05:09:28 INFO - mozlog == 3.1 05:09:28 INFO - moznetwork == 0.27 05:09:28 INFO - mozprocess == 0.22 05:09:28 INFO - mozprofile == 0.28 05:09:28 INFO - mozrunner == 6.11 05:09:28 INFO - mozscreenshot == 0.1 05:09:28 INFO - mozsystemmonitor == 0.0 05:09:28 INFO - moztest == 0.7 05:09:28 INFO - mozversion == 1.4 05:09:28 INFO - psutil == 3.1.1 05:09:28 INFO - requests == 1.2.3 05:09:28 INFO - wsgiref == 0.1.2 05:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:28 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:28 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 05:09:28 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 05:09:28 INFO - Using env: {'DISPLAY': ':0', 05:09:28 INFO - 'HOME': '/home/cltbld', 05:09:28 INFO - 'LANG': 'en_US.UTF-8', 05:09:28 INFO - 'LANGUAGE': 'en_US:en', 05:09:28 INFO - 'LOGNAME': 'cltbld', 05:09:28 INFO - 'MAIL': '/var/mail/cltbld', 05:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:28 INFO - 'MOZ_NO_REMOTE': '1', 05:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:28 INFO - 'NO_EM_RESTART': '1', 05:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:28 INFO - 'PWD': '/builds/slave/test', 05:09:28 INFO - 'SHELL': '/bin/bash', 05:09:28 INFO - 'SHLVL': '1', 05:09:28 INFO - 'TERM': 'linux', 05:09:28 INFO - 'TMOUT': '86400', 05:09:28 INFO - 'USER': 'cltbld', 05:09:28 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:28 INFO - '_': '/tools/buildbot/bin/python'} 05:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 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)) 05:09:28 INFO - Cleaning up... 05:09:28 INFO - Return code: 0 05:09:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:29 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:29 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 05:09:29 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 05:09:29 INFO - Using env: {'DISPLAY': ':0', 05:09:29 INFO - 'HOME': '/home/cltbld', 05:09:29 INFO - 'LANG': 'en_US.UTF-8', 05:09:29 INFO - 'LANGUAGE': 'en_US:en', 05:09:29 INFO - 'LOGNAME': 'cltbld', 05:09:29 INFO - 'MAIL': '/var/mail/cltbld', 05:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:29 INFO - 'MOZ_NO_REMOTE': '1', 05:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:29 INFO - 'NO_EM_RESTART': '1', 05:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:29 INFO - 'PWD': '/builds/slave/test', 05:09:29 INFO - 'SHELL': '/bin/bash', 05:09:29 INFO - 'SHLVL': '1', 05:09:29 INFO - 'TERM': 'linux', 05:09:29 INFO - 'TMOUT': '86400', 05:09:29 INFO - 'USER': 'cltbld', 05:09:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:29 INFO - '_': '/tools/buildbot/bin/python'} 05:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:29 INFO - Downloading/unpacking jsonschema 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:29 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 05:09:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:31 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:31 INFO - warning: no files found matching '*.txt' 05:09:31 INFO - no previously-included directories found matching 'build' 05:09:31 INFO - no previously-included directories found matching 'dist' 05:09:31 INFO - no previously-included directories found matching '.git*' 05:09:31 INFO - Installing collected packages: jsonschema, functools32 05:09:31 INFO - Running setup.py install for functools32 05:09:31 INFO - warning: no files found matching '*.txt' 05:09:31 INFO - no previously-included directories found matching 'build' 05:09:31 INFO - no previously-included directories found matching 'dist' 05:09:31 INFO - no previously-included directories found matching '.git*' 05:09:31 INFO - Successfully installed jsonschema functools32 05:09:31 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x7f06e0f5cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b77d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, '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': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 INFO - Downloading/unpacking flake8 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:34 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 05:09:34 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 05:09:34 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 05:09:34 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 05:09:34 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 05:09:34 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 05:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:34 INFO - no previously-included directories found matching 'docs/_build' 05:09:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:34 INFO - Running setup.py install for pep8 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:34 INFO - no previously-included directories found matching 'docs/_build' 05:09:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:34 INFO - Cleaning up... 05:09:35 INFO - Return code: 0 05:09:35 INFO - Running post-action listener: _resource_record_post_action 05:09:35 INFO - Running post-action listener: _start_resource_monitoring 05:09:35 INFO - Starting resource monitoring. 05:09:35 INFO - ##### 05:09:35 INFO - ##### Running install step. 05:09:35 INFO - ##### 05:09:35 INFO - Running pre-action listener: _resource_record_pre_action 05:09:35 INFO - Running main action method: install 05:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:35 INFO - Reading from file tmpfile_stdout 05:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:35 INFO - Reading from file tmpfile_stdout 05:09:35 INFO - Output received: 05:09:35 INFO - Usage: mozinstall [options] installer 05:09:35 INFO - Options: 05:09:35 INFO - -h, --help show this help message and exit 05:09:35 INFO - -d DEST, --destination=DEST 05:09:35 INFO - Directory to install application into. [default: 05:09:35 INFO - "/builds/slave/test"] 05:09:35 INFO - --app=APP Application being installed. [default: firefox] 05:09:35 INFO - mkdir: /builds/slave/test/build/application 05:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:09:48 INFO - Reading from file tmpfile_stdout 05:09:48 INFO - Output received: 05:09:48 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:48 INFO - Running post-action listener: _resource_record_post_action 05:09:48 INFO - ##### 05:09:48 INFO - ##### Running run-tests step. 05:09:48 INFO - ##### 05:09:48 INFO - Running pre-action listener: _resource_record_pre_action 05:09:48 INFO - Running main action method: run_tests 05:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:48 INFO - Python 2.7.3 05:09:48 INFO - Return code: 0 05:09:48 INFO - grabbing minidump binary from tooltool 05:09:48 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')]} 05:09:48 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1146640>, '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 0x1147540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11479d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:48 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 05:09:48 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 05:09:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:48 INFO - Return code: 0 05:09:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:49 INFO - Return code: 0 05:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:09:49 INFO - Using env: {'DISPLAY': ':0', 05:09:49 INFO - 'HOME': '/home/cltbld', 05:09:49 INFO - 'LANG': 'en_US.UTF-8', 05:09:49 INFO - 'LANGUAGE': 'en_US:en', 05:09:49 INFO - 'LOGNAME': 'cltbld', 05:09:49 INFO - 'MAIL': '/var/mail/cltbld', 05:09:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:49 INFO - 'MOZ_NO_REMOTE': '1', 05:09:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:49 INFO - 'NO_EM_RESTART': '1', 05:09:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:49 INFO - 'PWD': '/builds/slave/test', 05:09:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:49 INFO - 'SHELL': '/bin/bash', 05:09:49 INFO - 'SHLVL': '1', 05:09:49 INFO - 'TERM': 'linux', 05:09:49 INFO - 'TMOUT': '86400', 05:09:49 INFO - 'USER': 'cltbld', 05:09:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696', 05:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:49 INFO - '_': '/tools/buildbot/bin/python'} 05:09:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:09:50 INFO - mozversion application_buildid: 20160718021354 05:09:50 INFO - mozversion application_changeset: e29236a2cb1019532749ae35f37266cbbba0c04c 05:09:50 INFO - mozversion application_display_name: Firefox 05:09:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:50 INFO - mozversion application_name: Firefox 05:09:50 INFO - mozversion application_remotingname: firefox 05:09:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:09:50 INFO - mozversion application_vendor: Mozilla 05:09:50 INFO - mozversion application_version: 48.0 05:09:50 INFO - mozversion platform_buildid: 20160718021354 05:09:50 INFO - mozversion platform_changeset: e29236a2cb1019532749ae35f37266cbbba0c04c 05:09:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:09:50 INFO - mozversion platform_version: 48.0 05:09:50 INFO - using testdate: 1468843790 05:09:50 INFO - actual date: 1468843790 05:09:50 INFO - starting webserver on 'localhost:33108' 05:09:50 INFO - SUITE-START | Running 2 tests 05:09:50 INFO - TEST-START | dromaeo_css 05:09:50 INFO - operating with platform_type : linux_ 05:09:50 INFO - Initialising browser for dromaeo_css test... 05:09:50 INFO - TEST-INFO | started process 16264 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp187C_S/profile http://localhost:33108/getInfo.html) 05:09:54 INFO - PROCESS | 16264 | __metrics Screen width/height:1600/1200 05:09:54 INFO - PROCESS | 16264 | colorDepth:24 05:09:54 INFO - PROCESS | 16264 | Browser inner width/height: 1024/697 05:09:54 INFO - PROCESS | 16264 | __metrics 05:09:55 INFO - PROCESS | 16264 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:09:55 ERROR - PROCESS | 16264 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:09:55 INFO - TEST-INFO | 16264: exit 0 05:09:55 INFO - Browser initialized. 05:09:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:55 INFO - TEST-INFO | started process 16356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp187C_S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:07 INFO - PROCESS | 16356 | RSS: Main: 139698176 05:10:07 INFO - PROCESS | 16356 | 05:10:58 INFO - PROCESS | 16356 | 1468843858807 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] 05:13:06 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33108/tests/dromaeo/cssquery-ext.html) 05:13:07 INFO - PROCESS | 16356 | RSS: Main: 247136256 05:13:07 INFO - PROCESS | 16356 | 05:16:09 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-ext.html (next: http://localhost:33108/tests/dromaeo/cssquery-jquery.html) 05:16:10 INFO - PROCESS | 16356 | RSS: Main: 226787328 05:16:10 INFO - PROCESS | 16356 | 05:19:11 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33108/tests/dromaeo/cssquery-mootools.html) 05:19:12 INFO - PROCESS | 16356 | RSS: Main: 216825856 05:19:12 INFO - PROCESS | 16356 | 05:22:14 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33108/tests/dromaeo/cssquery-prototype.html) 05:22:14 INFO - PROCESS | 16356 | RSS: Main: 215109632 05:22:14 INFO - PROCESS | 16356 | 05:25:17 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33108/tests/dromaeo/cssquery-yui.html) 05:25:17 INFO - PROCESS | 16356 | RSS: Main: 210223104 05:25:17 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | Cycle 1(1): loaded http://localhost:33108/tests/dromaeo/cssquery-yui.html (next: http://localhost:33108/tests/dromaeo/cssquery-dojo.html) 05:28:20 INFO - PROCESS | 16356 | RSS: Main: 254148608 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | __start_tp_report 05:28:20 INFO - PROCESS | 16356 | _x_x_mozilla_page_load 05:28:20 INFO - PROCESS | 16356 | _x_x_mozilla_page_load_details 05:28:20 INFO - PROCESS | 16356 | |i|pagename|runs| 05:28:20 INFO - PROCESS | 16356 | |0;dojo.html;3301.9669803301967;3350.296437748073;3364.158960259935;3368.1916340078387;3397.575303087114;5513.834584962453;5533.253010843535;5550.111982082867;5551.417101204371;5555.555555555551;5460.481254280847;5530.05988981873;5533.22534845122;5538.446155384459;5561.916571251434;4880.194767863299;4883.29192267121;4953.702777833332;5053.418856831475;5088.465711100337;4927.113119638464;5020.397552293712;5025.0954828130925;5034.496550344973;5040.269160971657;4960.082384758831;5003.799848006075;5015.197568389059;5033.370828646419;5037.29477873098;4975;4975.203967365204;4997;4999.3500844890295;5043.319151914481;4874.829380971684;4887.120318342697;4914.410270767531;4940.40715114185;4944.110060189165;5248.343957005374;5308.973455132738;5314.309139811839;5318.547923426513;5322.866928326784;5814;5876.353601103875;5890.028145356011;5893.764249430018;5894.351621321651;5923.763049478016;5946.821595352147;5959.851003724898;5978.671173085479;6017.48851347636;5496.972515137439;5500.559955203574;5539.473749993744;5541.0580201365465;5544.584156188302;5886.823395298148;6026.608270462406;6037.45662890342;6038.849028774316;6047.0627052806885;5761.971190144106;5814.30228372598;5821.359650438449;5839.211706419664;5843.970780146072;5872.471477567039;5878.382769809108;5880.206172166788;5882.294124705063;5887.8233652990475;5747.48272655463;5768.971155144198;5843.503302219274;5843.649381037151;5852.209951656557;5779.537636989031;5791.449812267828;5796.246487956539;5801.3328467225965;5835.037218858925;4452.065066336041;4454.487733910577;4455.086707225011;4458.8439404620685;4485.618722408566;3966.8016599170505;4000.9199816004098;4025.953252154461;4051.6150965658217;4059.188162367538;5485.314335708036;5510.0632892408385;5513.8345849624575;5538.723063846792;5543.805966791143;5763.510101641324;5763.596548241583;5769.567282453749;5769.826905192851;5769.826905192851;5471.316085489314;5507.339119305709;5516.14499752539;5639.125935480007;5716.971415142898;5068.6451948364;5073.34046573943;5073.923891141636;5075.086484432763;5091.694498330112;6142.907856382158;6144.109104179869;6147.231596050494;6147.907781383283;6149.477294430024;5808.128780682762;5861.35525092248;5901.911471327848;5946.048632218824;5977.641341519523;6063.242094738158;6067.150598916243;6070;6070.484008859296;6076.939230607637;5664.943350566442;5688.800891968761;5691.345495268097;5695.601307908407;5696.943030569641;5742.425757424224;5915.290165180206;5928.962431574543;5947.494462970696;5956.344802071682;5600.299962504687;5606.299212598426;5608.859778505407;5620.887582248414;5623.718814059363;5502.724863756876;5592.3289205293995;5594.41258667834;5601.103823388238;5617.494425501724;5611.8877622448135;5613.971930140323;5617.241672374341;5629.296337957755;5648.068068768772;5678.716064196691;5709.571782116441;5724.771009159754;5724.971375143097;5729.28383952006;5555.166724991283;5573.470520300565;5581.5255703265675;5592.580556458201;5593.468620481048 05:28:20 INFO - PROCESS | 16356 | |1;ext.html;658.338369938212;661.2659947458324;675.6520391998121;750.0549307872081;769.8190925132593;31232.21919452012;31806.840965795167;31832.363352732933;31838;31844.36311273773;42440;42940.785296073554;42949.35575966363;43012.354814677805;43053.354199687026;8647.048824629286;8771.649134034631;8796.032436431988;8810.691625793099;8813.911860881373;8549.273311768507;8725.432846864966;8744.519051452167;8745.95627021864;8749.7812554686;8754.212120909116;8772.298216142693;8802.339824513156;8992.145746154138;9013.414128081686;8891.42205756627;8911.376203665746;8923.643054277822;8945.239654629364;8947.865782013276;8908.643654253821;8995.820083598292;9001.774955626162;9004.77488062797;9011.05383934684;44257.77871110656;44273.77863110664;44531.55468445306;44637;44682.553174468165;3932.3315036443873;3992.500937382827;4044.5550989391145;4047.251258517184;4682.976585117087;34115.31769364623;34238.48642270368;34617.82691086554;34723.82638086819;35992.46011309832;4497.685162038659;4570.702904311209;4577.153226653079;4588.01357708091;4620.953790462119;306160;306379.46810266026;306523;307164;308801;40200.195996079914;63286;63349.68325158345;63564.68217658882;63756.681216594545;39240.607593924266;39484.605153948665;39555.60444395519;39605.801970990535;39642.40536391956;17018.574535636708;17147.657046858993;17154.914225429042;17160;17161.570960725832;13581.592552223448;13588.11677240996;13596.728065438636;13624.386902589209;13639.454421823215;9405.952970235105;9507.524623768784;9517;9588.712338629852;9616.038396160327;3561.1809283864854;3607.6753092221825;3678.797666128335;3686.889393318205;3736.159364143046;1171.5313874450117;1227.4108427954632;1244.6888277930518;1304.6542666193466;1306.3794697518792;34369;35502;35615.82192089075;35813.82093089518;36060.27879442397;1247.1332423965257;1250.4873002069107;1261;1275.1520239040933;1306.5296493262417;16299.837001629832;16853.157342132843;16974.830251697324;17164.862283075832;17225.39711110105;475991;478904;483190;483233;483398;60190;61714;61931.690341548005;61998;62113.68943155255;1376.291210026837;1548.783170356151;1580.2414840876443;1621.0030831038825;1638.4511188751662;30895.84552077225;32178;32630.51054234141;33044.339113218084;34566.884605080944;53043.46956530385;53995.19007214974;54022.459775401745;54055.18917216166;54060;375927;384492;384614;384780;384870;27178.184654460398;27671.723282766914;48959;51285.48714512807;52319.738401307746;50353.74823125861;50565.241521376476;50883.2367514495;50921.49078509167;51948.740256299985;40163.598364017154;41478.942701431624;41646.58353416427;41786;43984;42676;43866.12267754691;44089;44623.33065003963;45257.547424525335;44646.13063870789;45568;45976;46709;46709.532904671876;39564.2087158261;40292.59707402888;40341;40604.79697601493;41363.379549307385;30424.84787576048;30500.237494062825;30802.229944251572;30906.381872362876;30990.535141973338 05:28:20 INFO - PROCESS | 16356 | |2;jquery.html;423.87919442958724;431.54333786371177;431.7042825664421;440.8041697691735;445.93199287705943;18385.26458941641;18794.590405719573;19146.71279930802;20163.495912602193;20649.79350206501;19880.801191988077;19938.102785374656;20157;20172.293969711063;20178;5576.609637325389;5633.267675202218;5772.018756811352;5778.50882674971;5780.5664575156825;4128.60778226068;5756.395578464264;5796.391378905218;6206.34833342497;6485.189351331083;6443.645599492026;6449.290578036413;6464.159659244292;6467.644279564622;6481.902771458432;5513.117901135819;5768.596198266123;5784.566157538159;5784.797532086378;6514.7719829805965;3811.5997820228754;5529.640573362719;5673.716314184274;5681.431856814327;5782.7397767100165;13999.090059146123;14248.287585620781;14251.714965700732;14348.85651143496;23260.185893493817;2389.187676190086;2434.42790944128;2514.4091138582417;2529.165375426105;2650.761431471177;9963.402195868272;10221.437820919846;10437.983435720118;10586.206034547362;10587.311824731445;1960.8137226963559;2012.3459875540507;2017.5258814178655;2081.646120159561;2154.3321570313096;78813.21186788117;78914.60542697307;79956;85696.57151714202;89663.10336896613;17484;17675.20461579232;17937.641247174983;18308.359207427675;18723.90638046801;33620.81464704786;33928;34326.14184645354;34359.82820085934;34360.14099647479;10319.1744660427;10507.369557826554;10707.411092389837;10878.292910960763;11119.27724697892;9350.438973661601;9381.577828997712;9401.717948461557;9459.763505912408;9498.335116541775;9357.204637605744;9390.67132650354;9409.29430292717;9523.952380238054;9570;3010.247438140465;3466.6533346665133;3526.244549554716;3530.134502719518;3791.2038471920655;1017.6997785653102;1044.7962647283707;1086.7283179205199;1114.3035602748282;1132.4337831084458;17933.641327173384;18606.44180674582;18851.340203092826;19172.520686982658;19527.121279542454;1070.1118072000222;1103.779244151157;1121.8541589593465;1128.3539949696296;1138.5901075612774;10735.83896241557;10883.455827208609;10959.50682219302;10964.506597202986;11050.115990720724;522552;536871;554756;556041;563886;86738.56630716806;92007.53996229976;93035;93696.53151734333;94471;1104.3263609198543;1119.5913491575516;1143.9084873210122;1148.9597864074717;1150.1373969522858;16555.420560280334;16585.336586536403;16739.74890376621;16882.24029918632;17198.054107024233;27127.59308610412;38111.42832857565;38980.415293771184;40832.591674082876;41157.1768564633;60393.396066038775;61593.692031539555;62007.689961549906;62889.37110629018;63156;8417.663293468438;8602.870956935776;8611.82776344482;8900.243479304332;8907.331950103899;12812.423440945366;12882.355882205664;13013.869861301644;13014;13118.344082796013;11456.3698996556;11784.351860647746;11882.702932426759;12032.639020829389;12099.81850272229;5854.824355269348;9944.751381215527;11839.881601183877;11890.643280701594;11946.626137994243;12115.212511186744;12232.571859984857;12326.691832704255;12357;12481;8698.086700896567;8881.911180888108;8905.154010369004;9039.95480022622;9406.38858474197;10780.730481738019;11429.657110286706;11550.422478875855;11607.245529040585;11627.76744465089 05:28:20 INFO - PROCESS | 16356 | |3;mootools.html;1438.1874241053797;1605.1252067623145;1617.838216178382;1841.1990784008958;1953.4725624081507;8133.471324363913;8383.748487545376;8390.412671113025;8410.453320534161;8411.49531028138;8194.549299788525;8323.91676083239;8370.037445693748;8374.078851326349;8389.95805020974;2393.9880300598475;2406.5186962607463;2417.806575473967;2418.9274321770376;2420.0319872051145;2402.699662542182;2407.5064611754547;2460.2004348586693;2465.654808326836;2470.789982851451;2417.9758202418016;2439.621858611918;2441.328634625483;2443.547943630434;2459.987700061497;2394.7964423024064;2425.987870060656;2440.3045132137254;2441.8046556275453;2442.645816356627;2380.809535237177;2407.590709579358;2418.7460316666675;2420.6248031555137;2424.139430502164;7273.7090516379285;7391.92817041526;8527.95736021322;8582.613782379807;8590.312774977987;5190.480951904817;5268.5521730652945;5297.390800057966;5303.390110137345;5308.628396012281;3345.347657206821;3368.0232732507543;3376.6116896556964;3389.034125274285;3453.6546345365514;1060.7454210989306;1077.7197928538558;1083.967480975572;1084.9728756781064;1093.3221402730278;11658.41707914601;11800.173987820855;11833.112516561207;11839.052875769918;11858.762824743542;4587.977060114678;4643.976780116078;4694.131585656596;4710.528947105262;4757.452892917289;10244.743881402876;10251.641192558225;10256.12822910061;10257.17942564593;10274.178065754722;5348.465153484621;5383.67697938125;5385.6230063895155;5386.8383948481605;5389.56883449331;4862.82980095695;4864.294677271776;4865.95134048662;4866.562009419169;4872.244802055687;3741.120836603396;3821.5032045833864;3823.1206822430995;3826.9234615308096;3866.2654095721723;1146.4382452598334;1149.3965668024186;1153.7807816514835;1161.3032180691519;1194.2237545595258;1031.3347890610514;1035.435687550287;1038.5741845843168;1057.9206559507913;1074.403705943209;5892.941070589325;5942.673152976541;5956.970215148896;6138.171346868205;6173.32093469727;956.8373376526007;1010.4846528270635;1047.7223535763028;1058.9470526473644;1072.4798472740715;3972.463717398114;4015.7791321477016;4051.6150965658217;4067.1052368478886;4070.9185816283507;10284.228682848816;10605.628802991858;10624.840627390595;10736.194785391126;10736.463176841127;10397.480125993821;10508.106810921157;10521.789564208673;10540.051395374454;10544.894551054545;7768.883466748003;7841.255080767432;7848.018997625411;7852.764417067498;7864.095629002738;5527.695976721319;5653.830385088454;5734.569907257056;5773.047447171338;5773.595848290579;8922.55387230623;8952.597133128895;8982.281417486454;9081.45511269326;9173.08269173104;8927.151920567536;9245.537723113683;9266.07339266068;9268.72193834186;9284.30367722437;7455.850882982419;7475.663595138123;7531.3974882008215;7562.773116806505;7584.355329797029;7863.449558530848;8057.113717491188;8090;8101.918980810352;8112.10766815638;7446.441516886147;7452.254774522505;7462.626868656654;7596.696132154873;7621.35218506411;7129.429645628441;7158.248383919614;7231.457640676865;7328.743493977685;7349.522281051928;6808.761693340709;6847.760328388483;6865.107536020286;6919.6886140124825;6952.756653517103;5228.058949389147;5312.787488500417;5331.866703332447;5333.466653334635;5447.891042179055;4988.476209997898;5087.160618497906;5152.072626927039;5155.819546315861;5157 05:28:20 INFO - PROCESS | 16356 | |4;prototype.html;355.57686353239643;358.7560458887958;364.74650118167875;366.3387585408338;366.8257577650616;6004.5796794224425;6013.428724271193;6071.028635418334;6079.1489191513165;6118.816435506931;5988.550858685594;5997.070454079614;5997.490213331872;5997.820065398045;6003.519718422526;4450.577195166462;4598.816047358102;4709.387779588648;4714.693544919569;4723.10261050401;4676.088162808253;4695.9530404696025;4707.12918110149;4715.528447155291;4718.90562188756;4469.396631454761;4686.390769199999;4694.155052090622;4694.29585562165;4702.952970470285;4520.344550040242;4639.188142075123;4639.373684552576;4639.489656137839;4642.419697537808;4074.6943979201383;4085.775282359469;4087.3460246360737;4088.3867419886956;4099.385092236159;5831.212786273841;5835.095560188178;5843;5844.73698683556;5845.620034697731;5393.031543486638;5402.486763757437;5423.999999999961;5426.403095659474;5434.777175135602;6334.398232167937;6364.809055728336;6367.586106903036;6369.299377068519;6370.203724534434;4386.342048692722;4488.192125417424;4488.820447182109;4493.618042466394;4500.504944456107;6630.469562435042;6724.764633237862;6730.226024007253;6730.562513436611;6735.966320168368;4598.563136502027;4601.217792975202;4605.953940460619;4606.147862645421;4606.516315786861;4655.255159174516;4670.883227919261;4671.11248862715;4672.672912896132;4672.789724462407;5384.353877534721;5389.51494365509;5392.272043274186;5394.379646340721;5402.6758394496455;6354.078658594478;6418.743250269937;6464.967675161688;6466.838329041718;6475.676216189172;1595.8882878198415;1607.9517614471586;1611.8307577704172;1612.9596760081092;1613.806343238819;1019.2814066083394;1062.2351906627216;1074.382230217628;1087.526925787285;1100.9559617615205;6127.969360153171;6138.508919286446;6173.166622505994;6188.071789231652;6192.164057852132;6538.1827271591055;6540.901886471706;6541.985992171221;6544.7382104716235;6553.115329430561;6149.907751383733;6152.230971128609;6154.78458253959;6157.7229024694;6209.875802483925;3488.755787094879;3518.489818976234;3525.259695463981;3530.064532898784;3531.876384326536;6803.863922721518;6833.58998460094;7014.859702806018;7015.088038554955;7043.2604576518725;6821.522493425413;6832.45340372769;6840.31596840322;6840.623765692934;6855.177378714586;6958.860822783516;6985.545939513915;6991;7000.404965577881;7005.789826305219;6693.966530167318;6697;6705.865882682221;6707.765228216989;6718.764843230463;6494.09082728409;6627.1716035495565;6627.834304142435;6632.270450250372;6640.269570347355;6946.270641582763;6990;6990.650467476504;6996.335348142122;6997.440204783503;4593.954060459353;4656.8137274508645;4659.301104834302;4669.929951050669;4670.346151538855;6654.600723956578;6667.966660166668;6680.565763225375;6691.197056353074;6693;6266.498680105672;6434.742610295536;6463.838404039937;6476.093346931528;6482.870342593027;6396.456301214449;6398.232212134573;6402.775902843378;6450.870982580228;6493.772717954849;6339.651319177489;6364.681765911779;6372.07604897278;6374.617522948638;6375.458086062551;6529.183852018497;6532;6537.967310163419;6545.607263564201;6548.214214294315;6037.516998640011;6055.969720151371;6084.60450070744;6381.234251889803;6396 05:28:20 INFO - PROCESS | 16356 | |5;yui.html;834.0533494483759;841.2218697704621;905.461250555919;924.6127126783414;957.0620791624208;15226.543203703879;15338.539843804676;15363.385464581403;15392.538223853287;15452.07287562747;14186.574402767934;14239.572812815632;14321.427142914272;14340.928295358535;14383.137011779274;728.50097683087;869.4348673362302;892.8928528576547;893.4192774696451;896.0233345653269;904.6878826804742;905.7056456651582;906.1301150895149;906.3927168796922;906.9682561110362;949.5157469690473;950.0309684122191;952.538019060755;954.5990683912773;954.7279025477741;763.3816608547095;943.3443756589173;957.3872721458238;957.913787759105;957.9185769209626;696.7874798186551;698.3749544158001;700.9614471204081;701.0255744515129;701.6912558474306;14777.187254700988;14884.851151488454;14923.925380373137;14935.25323733809;14983.550493485103;15184.240787960669;15408.22958852053;15534.456294029655;15717.21413929299;15734.291956861969;12019.338936358585;12264.202826816228;12318.815217771651;12414.193077449936;12677.366131693378;4070.0910428179436;4156.334986402161;4157.771322577257;4163.479565054368;4163.958360416387;54787.45212547863;54804.451955480334;55078;55188.44811551913;55459.16811247834;11492;11550.826737598944;11774.646760597196;11802.52789888412;12313.13808033429;21747;22511.549769004527;22551.887240563694;22565;22608.886955565118;8917.10828917103;9181.908180918239;9183.127602877716;9430.71707848752;9598.9040109598;4334.804933777988;4357.215701173755;4374.715643483163;4394.670399720034;4403.295472724349;3496.3881320768764;3507.4212754895666;3522.489239060322;3539.805310707936;3542.2384187399834;591.2904514582432;649.4122818848855;675.42588799521;733.3729661139648;735.1362149474346;599.3706608061518;631.3749388105742;696.4776417686735;700.5936556797045;723.0672432561936;10128.088472037552;10249.760275844197;10302.845457318146;10375.67798174743;10429.322094063862;743.7248218159316;839.9081194447194;840.6763396092548;845.045099038083;847.3052097280171;4783.115123702126;4844.539768721966;4897.706137631753;4929.185785511389;4941.45643979162;64948;66884;67527.32472675308;67642;67830;55972.44027559754;55975;56399.15401268984;56811.71594142003;57329.713351432976;52790;53440.198397024076;53441.198382024304;54180.187297190576;55058.72470637701;9494.240460762985;9497.810043798947;9643.517824108627;9676.56455459492;9751.609935602497;37143.44284835784;38003.42994855024;38738.6126138735;39593;39749.801250994715;44409.555904440545;48417;48563.75718121387;49125;49567;11411.486483108447;11906.345151016778;12041.45813438415;12158.331291779037;12445.066620003356;12682.746345072863;12792.488300468249;12801;12862.485500580246;12864.421101050288;12571.93714031424;12850.485980560672;12898.48406063747;12918.806217906551;12925.15986460877;13034.47862085506;13069.085164038426;13069.562199414793;13133.146345487512;13154.539591114648;12891.806622900851;12983.870161298266;13020.153690010118;13042.739145217232;13044.282564459047;10473.895261047597;10722.678319650424;10820.945895270474;10833.404162770808;10987.340759554452;10753.946230268799;10807.13542916549;11149.442527873736;11218.495167717636;11290.266132701348 05:28:20 INFO - PROCESS | 16356 | __end_tp_report 05:28:20 INFO - PROCESS | 16356 | __start_cc_report 05:28:20 INFO - PROCESS | 16356 | _x_x_mozilla_cycle_collect,795 05:28:20 INFO - PROCESS | 16356 | __end_cc_report 05:28:20 INFO - PROCESS | 16356 | __startTimestamp1468844900363__endTimestamp 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | ------- Summary: start ------- 05:28:20 INFO - PROCESS | 16356 | Number of tests: 6 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#0] dojo.html Cycles:165 Average:5440.32 Median:5601.10 stddev:596.55 (10.7%) stddev-sans-first:574.31 05:28:20 INFO - PROCESS | 16356 | Values: 3302.0 3350.3 3364.2 3368.2 3397.6 5513.8 5533.3 5550.1 5551.4 5555.6 5460.5 5530.1 5533.2 5538.4 5561.9 4880.2 4883.3 4953.7 5053.4 5088.5 4927.1 5020.4 5025.1 5034.5 5040.3 4960.1 5003.8 5015.2 5033.4 5037.3 4975.0 4975.2 4997.0 4999.4 5043.3 4874.8 4887.1 4914.4 4940.4 4944.1 5248.3 5309.0 5314.3 5318.5 5322.9 5814.0 5876.4 5890.0 5893.8 5894.4 5923.8 5946.8 5959.9 5978.7 6017.5 5497.0 5500.6 5539.5 5541.1 5544.6 5886.8 6026.6 6037.5 6038.8 6047.1 5762.0 5814.3 5821.4 5839.2 5844.0 5872.5 5878.4 5880.2 5882.3 5887.8 5747.5 5769.0 5843.5 5843.6 5852.2 5779.5 5791.4 5796.2 5801.3 5835.0 4452.1 4454.5 4455.1 4458.8 4485.6 3966.8 4000.9 4026.0 4051.6 4059.2 5485.3 5510.1 5513.8 5538.7 5543.8 5763.5 5763.6 5769.6 5769.8 5769.8 5471.3 5507.3 5516.1 5639.1 5717.0 5068.6 5073.3 5073.9 5075.1 5091.7 6142.9 6144.1 6147.2 6147.9 6149.5 5808.1 5861.4 5901.9 5946.0 5977.6 6063.2 6067.2 6070.0 6070.5 6076.9 5664.9 5688.8 5691.3 5695.6 5696.9 5742.4 5915.3 5929.0 5947.5 5956.3 5600.3 5606.3 5608.9 5620.9 5623.7 5502.7 5592.3 5594.4 5601.1 5617.5 5611.9 5614.0 5617.2 5629.3 5648.1 5678.7 5709.6 5724.8 5725.0 5729.3 5555.2 5573.5 5581.5 5592.6 5593.5 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#1] ext.html Cycles:180 Average:56126.37 Median:42113.00 stddev:104788.90 (248.8%) stddev-sans-first:105000.11 05:28:20 INFO - PROCESS | 16356 | Values: 658.3 661.3 675.7 750.1 769.8 31232.2 31806.8 31832.4 31838.0 31844.4 42440.0 42940.8 42949.4 43012.4 43053.4 8647.0 8771.6 8796.0 8810.7 8813.9 8549.3 8725.4 8744.5 8746.0 8749.8 8754.2 8772.3 8802.3 8992.1 9013.4 8891.4 8911.4 8923.6 8945.2 8947.9 8908.6 8995.8 9001.8 9004.8 9011.1 44257.8 44273.8 44531.6 44637.0 44682.6 3932.3 3992.5 4044.6 4047.3 4683.0 34115.3 34238.5 34617.8 34723.8 35992.5 4497.7 4570.7 4577.2 4588.0 4621.0 306160.0 306379.5 306523.0 307164.0 308801.0 40200.2 63286.0 63349.7 63564.7 63756.7 39240.6 39484.6 39555.6 39605.8 39642.4 17018.6 17147.7 17154.9 17160.0 17161.6 13581.6 13588.1 13596.7 13624.4 13639.5 9406.0 9507.5 9517.0 9588.7 9616.0 3561.2 3607.7 3678.8 3686.9 3736.2 1171.5 1227.4 1244.7 1304.7 1306.4 34369.0 35502.0 35615.8 35813.8 36060.3 1247.1 1250.5 1261.0 1275.2 1306.5 16299.8 16853.2 16974.8 17164.9 17225.4 475991.0 478904.0 483190.0 483233.0 483398.0 60190.0 61714.0 61931.7 61998.0 62113.7 1376.3 1548.8 1580.2 1621.0 1638.5 30895.8 32178.0 32630.5 33044.3 34566.9 53043.5 53995.2 54022.5 54055.2 54060.0 375927.0 384492.0 384614.0 384780.0 384870.0 27178.2 27671.7 48959.0 51285.5 52319.7 50353.7 50565.2 50883.2 50921.5 51948.7 40163.6 41478.9 41646.6 41786.0 43984.0 42676.0 43866.1 44089.0 44623.3 45257.5 44646.1 45568.0 45976.0 46709.0 46709.5 39564.2 40292.6 40341.0 40604.8 41363.4 30424.8 30500.2 30802.2 30906.4 30990.5 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#2] jquery.html Cycles:180 Average:31446.38 Median:2589.96 stddev:89841.93 (3468.8%) stddev-sans-first:90063.77 05:28:20 INFO - PROCESS | 16356 | Values: 423.9 431.5 431.7 440.8 445.9 18385.3 18794.6 19146.7 20163.5 20649.8 19880.8 19938.1 20157.0 20172.3 20178.0 5576.6 5633.3 5772.0 5778.5 5780.6 4128.6 5756.4 5796.4 6206.3 6485.2 6443.6 6449.3 6464.2 6467.6 6481.9 5513.1 5768.6 5784.6 5784.8 6514.8 3811.6 5529.6 5673.7 5681.4 5782.7 13999.1 14248.3 14251.7 14348.9 23260.2 2389.2 2434.4 2514.4 2529.2 2650.8 9963.4 10221.4 10438.0 10586.2 10587.3 1960.8 2012.3 2017.5 2081.6 2154.3 78813.2 78914.6 79956.0 85696.6 89663.1 17484.0 17675.2 17937.6 18308.4 18723.9 33620.8 33928.0 34326.1 34359.8 34360.1 10319.2 10507.4 10707.4 10878.3 11119.3 9350.4 9381.6 9401.7 9459.8 9498.3 9357.2 9390.7 9409.3 9524.0 9570.0 3010.2 3466.7 3526.2 3530.1 3791.2 1017.7 1044.8 1086.7 1114.3 1132.4 17933.6 18606.4 18851.3 19172.5 19527.1 1070.1 1103.8 1121.9 1128.4 1138.6 10735.8 10883.5 10959.5 10964.5 11050.1 522552.0 536871.0 554756.0 556041.0 563886.0 86738.6 92007.5 93035.0 93696.5 94471.0 1104.3 1119.6 1143.9 1149.0 1150.1 16555.4 16585.3 16739.7 16882.2 17198.1 27127.6 38111.4 38980.4 40832.6 41157.2 60393.4 61593.7 62007.7 62889.4 63156.0 8417.7 8602.9 8611.8 8900.2 8907.3 12812.4 12882.4 13013.9 13014.0 13118.3 11456.4 11784.4 11882.7 12032.6 12099.8 5854.8 9944.8 11839.9 11890.6 11946.6 12115.2 12232.6 12326.7 12357.0 12481.0 8698.1 8881.9 8905.2 9040.0 9406.4 10780.7 11429.7 11550.4 11607.2 11627.8 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#3] mootools.html Cycles:180 Average:5635.86 Median:4865.12 stddev:3072.70 (63.2%) stddev-sans-first:3065.13 05:28:20 INFO - PROCESS | 16356 | Values: 1438.2 1605.1 1617.8 1841.2 1953.5 8133.5 8383.7 8390.4 8410.5 8411.5 8194.5 8323.9 8370.0 8374.1 8390.0 2394.0 2406.5 2417.8 2418.9 2420.0 2402.7 2407.5 2460.2 2465.7 2470.8 2418.0 2439.6 2441.3 2443.5 2460.0 2394.8 2426.0 2440.3 2441.8 2442.6 2380.8 2407.6 2418.7 2420.6 2424.1 7273.7 7391.9 8528.0 8582.6 8590.3 5190.5 5268.6 5297.4 5303.4 5308.6 3345.3 3368.0 3376.6 3389.0 3453.7 1060.7 1077.7 1084.0 1085.0 1093.3 11658.4 11800.2 11833.1 11839.1 11858.8 4588.0 4644.0 4694.1 4710.5 4757.5 10244.7 10251.6 10256.1 10257.2 10274.2 5348.5 5383.7 5385.6 5386.8 5389.6 4862.8 4864.3 4866.0 4866.6 4872.2 3741.1 3821.5 3823.1 3826.9 3866.3 1146.4 1149.4 1153.8 1161.3 1194.2 1031.3 1035.4 1038.6 1057.9 1074.4 5892.9 5942.7 5957.0 6138.2 6173.3 956.8 1010.5 1047.7 1058.9 1072.5 3972.5 4015.8 4051.6 4067.1 4070.9 10284.2 10605.6 10624.8 10736.2 10736.5 10397.5 10508.1 10521.8 10540.1 10544.9 7768.9 7841.3 7848.0 7852.8 7864.1 5527.7 5653.8 5734.6 5773.0 5773.6 8922.6 8952.6 8982.3 9081.5 9173.1 8927.2 9245.5 9266.1 9268.7 9284.3 7455.9 7475.7 7531.4 7562.8 7584.4 7863.4 8057.1 8090.0 8101.9 8112.1 7446.4 7452.3 7462.6 7596.7 7621.4 7129.4 7158.2 7231.5 7328.7 7349.5 6808.8 6847.8 6865.1 6919.7 6952.8 5228.1 5312.8 5331.9 5333.5 5447.9 4988.5 5087.2 5152.1 5155.8 5157.0 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#4] prototype.html Cycles:180 Average:5400.50 Median:6081.88 stddev:1629.77 (26.8%) stddev-sans-first:1589.74 05:28:20 INFO - PROCESS | 16356 | Values: 355.6 358.8 364.7 366.3 366.8 6004.6 6013.4 6071.0 6079.1 6118.8 5988.6 5997.1 5997.5 5997.8 6003.5 4450.6 4598.8 4709.4 4714.7 4723.1 4676.1 4696.0 4707.1 4715.5 4718.9 4469.4 4686.4 4694.2 4694.3 4703.0 4520.3 4639.2 4639.4 4639.5 4642.4 4074.7 4085.8 4087.3 4088.4 4099.4 5831.2 5835.1 5843.0 5844.7 5845.6 5393.0 5402.5 5424.0 5426.4 5434.8 6334.4 6364.8 6367.6 6369.3 6370.2 4386.3 4488.2 4488.8 4493.6 4500.5 6630.5 6724.8 6730.2 6730.6 6736.0 4598.6 4601.2 4606.0 4606.1 4606.5 4655.3 4670.9 4671.1 4672.7 4672.8 5384.4 5389.5 5392.3 5394.4 5402.7 6354.1 6418.7 6465.0 6466.8 6475.7 1595.9 1608.0 1611.8 1613.0 1613.8 1019.3 1062.2 1074.4 1087.5 1101.0 6128.0 6138.5 6173.2 6188.1 6192.2 6538.2 6540.9 6542.0 6544.7 6553.1 6149.9 6152.2 6154.8 6157.7 6209.9 3488.8 3518.5 3525.3 3530.1 3531.9 6803.9 6833.6 7014.9 7015.1 7043.3 6821.5 6832.5 6840.3 6840.6 6855.2 6958.9 6985.5 6991.0 7000.4 7005.8 6694.0 6697.0 6705.9 6707.8 6718.8 6494.1 6627.2 6627.8 6632.3 6640.3 6946.3 6990.0 6990.7 6996.3 6997.4 4594.0 4656.8 4659.3 4669.9 4670.3 6654.6 6668.0 6680.6 6691.2 6693.0 6266.5 6434.7 6463.8 6476.1 6482.9 6396.5 6398.2 6402.8 6450.9 6493.8 6339.7 6364.7 6372.1 6374.6 6375.5 6529.2 6532.0 6538.0 6545.6 6548.2 6037.5 6056.0 6084.6 6381.2 6396.0 05:28:20 INFO - PROCESS | 16356 | 05:28:20 INFO - PROCESS | 16356 | [#5] yui.html Cycles:180 Average:15682.42 Median:4346.01 stddev:18048.99 (415.3%) stddev-sans-first:18065.18 05:28:20 INFO - PROCESS | 16356 | Values: 834.1 841.2 905.5 924.6 957.1 15226.5 15338.5 15363.4 15392.5 15452.1 14186.6 14239.6 14321.4 14340.9 14383.1 728.5 869.4 892.9 893.4 896.0 904.7 905.7 906.1 906.4 907.0 949.5 950.0 952.5 954.6 954.7 763.4 943.3 957.4 957.9 957.9 696.8 698.4 701.0 701.0 701.7 14777.2 14884.9 14923.9 14935.3 14983.6 15184.2 15408.2 15534.5 15717.2 15734.3 12019.3 12264.2 12318.8 12414.2 12677.4 4070.1 4156.3 4157.8 4163.5 4164.0 54787.5 54804.5 55078.0 55188.4 55459.2 11492.0 11550.8 11774.6 11802.5 12313.1 21747.0 22511.5 22551.9 22565.0 22608.9 8917.1 9181.9 9183.1 9430.7 9598.9 4334.8 4357.2 4374.7 4394.7 4403.3 3496.4 3507.4 3522.5 3539.8 3542.2 591.3 649.4 675.4 733.4 735.1 599.4 631.4 696.5 700.6 723.1 10128.1 10249.8 10302.8 10375.7 10429.3 743.7 839.9 840.7 845.0 847.3 4783.1 4844.5 4897.7 4929.2 4941.5 64948.0 66884.0 67527.3 67642.0 67830.0 55972.4 55975.0 56399.2 56811.7 57329.7 52790.0 53440.2 53441.2 54180.2 55058.7 9494.2 9497.8 9643.5 9676.6 9751.6 37143.4 38003.4 38738.6 39593.0 39749.8 44409.6 48417.0 48563.8 49125.0 49567.0 11411.5 11906.3 12041.5 12158.3 12445.1 12682.7 12792.5 12801.0 12862.5 12864.4 12571.9 12850.5 12898.5 12918.8 12925.2 13034.5 13069.1 13069.6 13133.1 13154.5 12891.8 12983.9 13020.2 13042.7 13044.3 10473.9 10722.7 10820.9 10833.4 10987.3 10753.9 10807.1 11149.4 11218.5 11290.3 05:28:20 INFO - PROCESS | 16356 | -------- Summary: end -------- 05:28:20 INFO - PROCESS | 16356 | 05:28:21 INFO - TEST-INFO | 16356: exit 0 05:28:21 INFO - TEST-OK | dromaeo_css | took 1111025ms 05:28:21 INFO - TEST-START | kraken 05:28:21 INFO - operating with platform_type : linux_ 05:28:21 INFO - Initialising browser for kraken test... 05:28:21 INFO - TEST-INFO | started process 16555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdNxioE/profile http://localhost:33108/getInfo.html) 05:28:25 INFO - PROCESS | 16555 | __metrics Screen width/height:1600/1200 05:28:25 INFO - PROCESS | 16555 | colorDepth:24 05:28:25 INFO - PROCESS | 16555 | Browser inner width/height: 1024/697 05:28:25 INFO - PROCESS | 16555 | __metrics 05:28:26 INFO - PROCESS | 16555 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:28:26 ERROR - PROCESS | 16555 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:28:27 INFO - TEST-INFO | 16555: exit 0 05:28:27 INFO - Browser initialized. 05:28:27 INFO - Running cycle 1/1 for kraken test... 05:28:27 INFO - TEST-INFO | started process 16646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdNxioE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:38 INFO - PROCESS | 16646 | RSS: Main: 139329536 05:28:38 INFO - PROCESS | 16646 | 05:28:41 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33108/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:42 INFO - PROCESS | 16646 | RSS: Main: 197414912 05:28:42 INFO - PROCESS | 16646 | 05:28:44 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33108/tests/kraken/driver.html?testName=audio-dft) 05:28:45 INFO - PROCESS | 16646 | RSS: Main: 184336384 05:28:45 INFO - PROCESS | 16646 | 05:28:48 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33108/tests/kraken/driver.html?testName=audio-fft) 05:28:48 INFO - PROCESS | 16646 | RSS: Main: 273375232 05:28:48 INFO - PROCESS | 16646 | 05:28:50 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33108/tests/kraken/driver.html?testName=audio-oscillator) 05:28:51 INFO - PROCESS | 16646 | RSS: Main: 179556352 05:28:51 INFO - PROCESS | 16646 | 05:28:53 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33108/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:54 INFO - PROCESS | 16646 | RSS: Main: 177823744 05:28:54 INFO - PROCESS | 16646 | 05:28:56 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33108/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:57 INFO - PROCESS | 16646 | RSS: Main: 212406272 05:28:57 INFO - PROCESS | 16646 | 05:29:00 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33108/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:01 INFO - PROCESS | 16646 | RSS: Main: 212754432 05:29:01 INFO - PROCESS | 16646 | 05:29:04 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33108/tests/kraken/driver.html?testName=json-parse-financial) 05:29:04 INFO - PROCESS | 16646 | RSS: Main: 212795392 05:29:04 INFO - PROCESS | 16646 | 05:29:06 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33108/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:06 INFO - PROCESS | 16646 | RSS: Main: 195956736 05:29:06 INFO - PROCESS | 16646 | 05:29:08 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:09 INFO - PROCESS | 16646 | RSS: Main: 199962624 05:29:09 INFO - PROCESS | 16646 | 05:29:11 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:11 INFO - PROCESS | 16646 | RSS: Main: 209371136 05:29:11 INFO - PROCESS | 16646 | 05:29:14 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:14 INFO - PROCESS | 16646 | RSS: Main: 218144768 05:29:14 INFO - PROCESS | 16646 | 05:29:17 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:18 INFO - PROCESS | 16646 | RSS: Main: 215732224 05:29:18 INFO - PROCESS | 16646 | 05:29:19 INFO - PROCESS | 16646 | Cycle 1(1): loaded http://localhost:33108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33108/tests/kraken/driver.html?testName=ai-astar) 05:29:19 INFO - PROCESS | 16646 | RSS: Main: 276242432 05:29:19 INFO - PROCESS | 16646 | 05:29:19 INFO - PROCESS | 16646 | __start_tp_report 05:29:19 INFO - PROCESS | 16646 | _x_x_mozilla_page_load 05:29:20 INFO - PROCESS | 16646 | _x_x_mozilla_page_load_details 05:29:20 INFO - PROCESS | 16646 | |i|pagename|runs| 05:29:20 INFO - PROCESS | 16646 | |0;ai-astar;108;109;110;110;110;112;104;110;109;110 05:29:20 INFO - PROCESS | 16646 | |1;audio-beat-detection;153;125;157;172;125;153;154;128;181;128 05:29:20 INFO - PROCESS | 16646 | |2;audio-dft;161;162;162;161;160;161;161;161;162;162 05:29:20 INFO - PROCESS | 16646 | |3;audio-fft;79;74;76;183;76;75;73;75;157;74 05:29:20 INFO - PROCESS | 16646 | |4;audio-oscillator;91;92;158;92;92;92;141;92;92;155 05:29:20 INFO - PROCESS | 16646 | |5;imaging-gaussian-blur;116;116;116;117;117;117;116;117;117;116 05:29:20 INFO - PROCESS | 16646 | |6;imaging-darkroom;145;144;145;145;146;145;144;145;145;145 05:29:20 INFO - PROCESS | 16646 | |7;imaging-desaturate;95;95;94;94;94;94;94;94;94;94 05:29:20 INFO - PROCESS | 16646 | |8;json-parse-financial;54;55;55;53;54;55;55;75;53;53 05:29:20 INFO - PROCESS | 16646 | |9;json-stringify-tinderbox;56;57;57;58;57;56;57;57;57;58 05:29:20 INFO - PROCESS | 16646 | |10;stanford-crypto-aes;72;73;72;72;73;73;72;73;73;80 05:29:20 INFO - PROCESS | 16646 | |11;stanford-crypto-ccm;113;111;126;125;122;109;113;111;129;128 05:29:20 INFO - PROCESS | 16646 | |12;stanford-crypto-pbkdf2;158;163;160;162;165;160;162;161;156;153 05:29:20 INFO - PROCESS | 16646 | |13;stanford-crypto-sha256-iterative;66;65;64;66;66;68;66;68;68;65 05:29:20 INFO - PROCESS | 16646 | __end_tp_report 05:29:20 INFO - PROCESS | 16646 | __start_cc_report 05:29:20 INFO - PROCESS | 16646 | _x_x_mozilla_cycle_collect,1571 05:29:20 INFO - PROCESS | 16646 | __end_cc_report 05:29:20 INFO - PROCESS | 16646 | __startTimestamp1468844959998__endTimestamp 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | ------- Summary: start ------- 05:29:20 INFO - PROCESS | 16646 | Number of tests: 14 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:2.10 (1.9%) stddev-sans-first:2.18 05:29:20 INFO - PROCESS | 16646 | Values: 108.0 109.0 110.0 110.0 110.0 112.0 104.0 110.0 109.0 110.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:153.50 stddev:20.24 (13.2%) stddev-sans-first:21.38 05:29:20 INFO - PROCESS | 16646 | Values: 153.0 125.0 157.0 172.0 125.0 153.0 154.0 128.0 181.0 128.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 05:29:20 INFO - PROCESS | 16646 | Values: 161.0 162.0 162.0 161.0 160.0 161.0 161.0 161.0 162.0 162.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:40.45 (53.9%) stddev-sans-first:42.53 05:29:20 INFO - PROCESS | 16646 | Values: 79.0 74.0 76.0 183.0 76.0 75.0 73.0 75.0 157.0 74.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#4] audio-oscillator Cycles:10 Average:109.70 Median:92.00 stddev:29.05 (31.6%) stddev-sans-first:30.01 05:29:20 INFO - PROCESS | 16646 | Values: 91.0 92.0 158.0 92.0 92.0 92.0 141.0 92.0 92.0 155.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 05:29:20 INFO - PROCESS | 16646 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#6] imaging-darkroom Cycles:10 Average:144.90 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:29:20 INFO - PROCESS | 16646 | Values: 145.0 144.0 145.0 145.0 146.0 145.0 144.0 145.0 145.0 145.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 05:29:20 INFO - PROCESS | 16646 | Values: 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.00 stddev:6.66 (12.1%) stddev-sans-first:7.02 05:29:20 INFO - PROCESS | 16646 | Values: 54.0 55.0 55.0 53.0 54.0 55.0 55.0 75.0 53.0 53.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 05:29:20 INFO - PROCESS | 16646 | Values: 56.0 57.0 57.0 58.0 57.0 56.0 57.0 57.0 57.0 58.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:2.41 (3.3%) stddev-sans-first:2.51 05:29:20 INFO - PROCESS | 16646 | Values: 72.0 73.0 72.0 72.0 73.0 73.0 72.0 73.0 73.0 80.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#11] stanford-crypto-ccm Cycles:10 Average:118.70 Median:123.50 stddev:7.99 (6.5%) stddev-sans-first:8.20 05:29:20 INFO - PROCESS | 16646 | Values: 113.0 111.0 126.0 125.0 122.0 109.0 113.0 111.0 129.0 128.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:161.50 stddev:3.53 (2.2%) stddev-sans-first:3.67 05:29:20 INFO - PROCESS | 16646 | Values: 158.0 163.0 160.0 162.0 165.0 160.0 162.0 161.0 156.0 153.0 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - PROCESS | 16646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:1.40 (2.1%) stddev-sans-first:1.48 05:29:20 INFO - PROCESS | 16646 | Values: 66.0 65.0 64.0 66.0 66.0 68.0 66.0 68.0 68.0 65.0 05:29:20 INFO - PROCESS | 16646 | -------- Summary: end -------- 05:29:20 INFO - PROCESS | 16646 | 05:29:20 INFO - TEST-INFO | 16646: exit 0 05:29:20 INFO - TEST-OK | kraken | took 59853ms 05:29:20 INFO - SUITE-END | took 1170s 05:29:21 INFO - Completed test suite (00:19:31) 05:29:21 INFO - javascript benchmark 05:29:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.9669803301967, 3350.296437748073, 3364.158960259935, 3368.1916340078387, 3397.575303087114, 5513.834584962453, 5533.253010843535, 5550.111982082867, 5551.417101204371, 5555.555555555551, 5460.481254280847, 5530.05988981873, 5533.22534845122, 5538.446155384459, 5561.916571251434, 4880.194767863299, 4883.29192267121, 4953.702777833332, 5053.418856831475, 5088.465711100337, 4927.113119638464, 5020.397552293712, 5025.0954828130925, 5034.496550344973, 5040.269160971657, 4960.082384758831, 5003.799848006075, 5015.197568389059, 5033.370828646419, 5037.29477873098, 4975.0, 4975.203967365204, 4997.0, 4999.3500844890295, 5043.319151914481, 4874.829380971684, 4887.120318342697, 4914.410270767531, 4940.40715114185, 4944.110060189165, 5248.343957005374, 5308.973455132738, 5314.309139811839, 5318.547923426513, 5322.866928326784, 5814.0, 5876.353601103875, 5890.028145356011, 5893.764249430018, 5894.351621321651, 5923.763049478016, 5946.821595352147, 5959.851003724898, 5978.671173085479, 6017.48851347636, 5496.972515137439, 5500.559955203574, 5539.473749993744, 5541.0580201365465, 5544.584156188302, 5886.823395298148, 6026.608270462406, 6037.45662890342, 6038.849028774316, 6047.0627052806885, 5761.971190144106, 5814.30228372598, 5821.359650438449, 5839.211706419664, 5843.970780146072, 5872.471477567039, 5878.382769809108, 5880.206172166788, 5882.294124705063, 5887.8233652990475, 5747.48272655463, 5768.971155144198, 5843.503302219274, 5843.649381037151, 5852.209951656557, 5779.537636989031, 5791.449812267828, 5796.246487956539, 5801.3328467225965, 5835.037218858925, 4452.065066336041, 4454.487733910577, 4455.086707225011, 4458.8439404620685, 4485.618722408566, 3966.8016599170505, 4000.9199816004098, 4025.953252154461, 4051.6150965658217, 4059.188162367538, 5485.314335708036, 5510.0632892408385, 5513.8345849624575, 5538.723063846792, 5543.805966791143, 5763.510101641324, 5763.596548241583, 5769.567282453749, 5769.826905192851, 5769.826905192851, 5471.316085489314, 5507.339119305709, 5516.14499752539, 5639.125935480007, 5716.971415142898, 5068.6451948364, 5073.34046573943, 5073.923891141636, 5075.086484432763, 5091.694498330112, 6142.907856382158, 6144.109104179869, 6147.231596050494, 6147.907781383283, 6149.477294430024, 5808.128780682762, 5861.35525092248, 5901.911471327848, 5946.048632218824, 5977.641341519523, 6063.242094738158, 6067.150598916243, 6070.0, 6070.484008859296, 6076.939230607637, 5664.943350566442, 5688.800891968761, 5691.345495268097, 5695.601307908407, 5696.943030569641, 5742.425757424224, 5915.290165180206, 5928.962431574543, 5947.494462970696, 5956.344802071682, 5600.299962504687, 5606.299212598426, 5608.859778505407, 5620.887582248414, 5623.718814059363, 5502.724863756876, 5592.3289205293995, 5594.41258667834, 5601.103823388238, 5617.494425501724, 5611.8877622448135, 5613.971930140323, 5617.241672374341, 5629.296337957755, 5648.068068768772, 5678.716064196691, 5709.571782116441, 5724.771009159754, 5724.971375143097, 5729.28383952006, 5555.166724991283, 5573.470520300565, 5581.5255703265675, 5592.580556458201, 5593.468620481048], "value": 5402.406550895042, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.338369938212, 661.2659947458324, 675.6520391998121, 750.0549307872081, 769.8190925132593, 31232.21919452012, 31806.840965795167, 31832.363352732933, 31838.0, 31844.36311273773, 42440.0, 42940.785296073554, 42949.35575966363, 43012.354814677805, 43053.354199687026, 8647.048824629286, 8771.649134034631, 8796.032436431988, 8810.691625793099, 8813.911860881373, 8549.273311768507, 8725.432846864966, 8744.519051452167, 8745.95627021864, 8749.7812554686, 8754.212120909116, 8772.298216142693, 8802.339824513156, 8992.145746154138, 9013.414128081686, 8891.42205756627, 8911.376203665746, 8923.643054277822, 8945.239654629364, 8947.865782013276, 8908.643654253821, 8995.820083598292, 9001.774955626162, 9004.77488062797, 9011.05383934684, 44257.77871110656, 44273.77863110664, 44531.55468445306, 44637.0, 44682.553174468165, 3932.3315036443873, 3992.500937382827, 4044.5550989391145, 4047.251258517184, 4682.976585117087, 34115.31769364623, 34238.48642270368, 34617.82691086554, 34723.82638086819, 35992.46011309832, 4497.685162038659, 4570.702904311209, 4577.153226653079, 4588.01357708091, 4620.953790462119, 306160.0, 306379.46810266026, 306523.0, 307164.0, 308801.0, 40200.195996079914, 63286.0, 63349.68325158345, 63564.68217658882, 63756.681216594545, 39240.607593924266, 39484.605153948665, 39555.60444395519, 39605.801970990535, 39642.40536391956, 17018.574535636708, 17147.657046858993, 17154.914225429042, 17160.0, 17161.570960725832, 13581.592552223448, 13588.11677240996, 13596.728065438636, 13624.386902589209, 13639.454421823215, 9405.952970235105, 9507.524623768784, 9517.0, 9588.712338629852, 9616.038396160327, 3561.1809283864854, 3607.6753092221825, 3678.797666128335, 3686.889393318205, 3736.159364143046, 1171.5313874450117, 1227.4108427954632, 1244.6888277930518, 1304.6542666193466, 1306.3794697518792, 34369.0, 35502.0, 35615.82192089075, 35813.82093089518, 36060.27879442397, 1247.1332423965257, 1250.4873002069107, 1261.0, 1275.1520239040933, 1306.5296493262417, 16299.837001629832, 16853.157342132843, 16974.830251697324, 17164.862283075832, 17225.39711110105, 475991.0, 478904.0, 483190.0, 483233.0, 483398.0, 60190.0, 61714.0, 61931.690341548005, 61998.0, 62113.68943155255, 1376.291210026837, 1548.783170356151, 1580.2414840876443, 1621.0030831038825, 1638.4511188751662, 30895.84552077225, 32178.0, 32630.51054234141, 33044.339113218084, 34566.884605080944, 53043.46956530385, 53995.19007214974, 54022.459775401745, 54055.18917216166, 54060.0, 375927.0, 384492.0, 384614.0, 384780.0, 384870.0, 27178.184654460398, 27671.723282766914, 48959.0, 51285.48714512807, 52319.738401307746, 50353.74823125861, 50565.241521376476, 50883.2367514495, 50921.49078509167, 51948.740256299985, 40163.598364017154, 41478.942701431624, 41646.58353416427, 41786.0, 43984.0, 42676.0, 43866.12267754691, 44089.0, 44623.33065003963, 45257.547424525335, 44646.13063870789, 45568.0, 45976.0, 46709.0, 46709.532904671876, 39564.2087158261, 40292.59707402888, 40341.0, 40604.79697601493, 41363.379549307385, 30424.84787576048, 30500.237494062825, 30802.229944251572, 30906.381872362876, 30990.535141973338], "value": 19903.111732381858, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.87919442958724, 431.54333786371177, 431.7042825664421, 440.8041697691735, 445.93199287705943, 18385.26458941641, 18794.590405719573, 19146.71279930802, 20163.495912602193, 20649.79350206501, 19880.801191988077, 19938.102785374656, 20157.0, 20172.293969711063, 20178.0, 5576.609637325389, 5633.267675202218, 5772.018756811352, 5778.50882674971, 5780.5664575156825, 4128.60778226068, 5756.395578464264, 5796.391378905218, 6206.34833342497, 6485.189351331083, 6443.645599492026, 6449.290578036413, 6464.159659244292, 6467.644279564622, 6481.902771458432, 5513.117901135819, 5768.596198266123, 5784.566157538159, 5784.797532086378, 6514.7719829805965, 3811.5997820228754, 5529.640573362719, 5673.716314184274, 5681.431856814327, 5782.7397767100165, 13999.090059146123, 14248.287585620781, 14251.714965700732, 14348.85651143496, 23260.185893493817, 2389.187676190086, 2434.42790944128, 2514.4091138582417, 2529.165375426105, 2650.761431471177, 9963.402195868272, 10221.437820919846, 10437.983435720118, 10586.206034547362, 10587.311824731445, 1960.8137226963559, 2012.3459875540507, 2017.5258814178655, 2081.646120159561, 2154.3321570313096, 78813.21186788117, 78914.60542697307, 79956.0, 85696.57151714202, 89663.10336896613, 17484.0, 17675.20461579232, 17937.641247174983, 18308.359207427675, 18723.90638046801, 33620.81464704786, 33928.0, 34326.14184645354, 34359.82820085934, 34360.14099647479, 10319.1744660427, 10507.369557826554, 10707.411092389837, 10878.292910960763, 11119.27724697892, 9350.438973661601, 9381.577828997712, 9401.717948461557, 9459.763505912408, 9498.335116541775, 9357.204637605744, 9390.67132650354, 9409.29430292717, 9523.952380238054, 9570.0, 3010.247438140465, 3466.6533346665133, 3526.244549554716, 3530.134502719518, 3791.2038471920655, 1017.6997785653102, 1044.7962647283707, 1086.7283179205199, 1114.3035602748282, 1132.4337831084458, 17933.641327173384, 18606.44180674582, 18851.340203092826, 19172.520686982658, 19527.121279542454, 1070.1118072000222, 1103.779244151157, 1121.8541589593465, 1128.3539949696296, 1138.5901075612774, 10735.83896241557, 10883.455827208609, 10959.50682219302, 10964.506597202986, 11050.115990720724, 522552.0, 536871.0, 554756.0, 556041.0, 563886.0, 86738.56630716806, 92007.53996229976, 93035.0, 93696.53151734333, 94471.0, 1104.3263609198543, 1119.5913491575516, 1143.9084873210122, 1148.9597864074717, 1150.1373969522858, 16555.420560280334, 16585.336586536403, 16739.74890376621, 16882.24029918632, 17198.054107024233, 27127.59308610412, 38111.42832857565, 38980.415293771184, 40832.591674082876, 41157.1768564633, 60393.396066038775, 61593.692031539555, 62007.689961549906, 62889.37110629018, 63156.0, 8417.663293468438, 8602.870956935776, 8611.82776344482, 8900.243479304332, 8907.331950103899, 12812.423440945366, 12882.355882205664, 13013.869861301644, 13014.0, 13118.344082796013, 11456.3698996556, 11784.351860647746, 11882.702932426759, 12032.639020829389, 12099.81850272229, 5854.824355269348, 9944.751381215527, 11839.881601183877, 11890.643280701594, 11946.626137994243, 12115.212511186744, 12232.571859984857, 12326.691832704255, 12357.0, 12481.0, 8698.086700896567, 8881.911180888108, 8905.154010369004, 9039.95480022622, 9406.38858474197, 10780.730481738019, 11429.657110286706, 11550.422478875855, 11607.245529040585, 11627.76744465089], "value": 10112.093954984262, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.1874241053797, 1605.1252067623145, 1617.838216178382, 1841.1990784008958, 1953.4725624081507, 8133.471324363913, 8383.748487545376, 8390.412671113025, 8410.453320534161, 8411.49531028138, 8194.549299788525, 8323.91676083239, 8370.037445693748, 8374.078851326349, 8389.95805020974, 2393.9880300598475, 2406.5186962607463, 2417.806575473967, 2418.9274321770376, 2420.0319872051145, 2402.699662542182, 2407.5064611754547, 2460.2004348586693, 2465.654808326836, 2470.789982851451, 2417.9758202418016, 2439.621858611918, 2441.328634625483, 2443.547943630434, 2459.987700061497, 2394.7964423024064, 2425.987870060656, 2440.3045132137254, 2441.8046556275453, 2442.645816356627, 2380.809535237177, 2407.590709579358, 2418.7460316666675, 2420.6248031555137, 2424.139430502164, 7273.7090516379285, 7391.92817041526, 8527.95736021322, 8582.613782379807, 8590.312774977987, 5190.480951904817, 5268.5521730652945, 5297.390800057966, 5303.390110137345, 5308.628396012281, 3345.347657206821, 3368.0232732507543, 3376.6116896556964, 3389.034125274285, 3453.6546345365514, 1060.7454210989306, 1077.7197928538558, 1083.967480975572, 1084.9728756781064, 1093.3221402730278, 11658.41707914601, 11800.173987820855, 11833.112516561207, 11839.052875769918, 11858.762824743542, 4587.977060114678, 4643.976780116078, 4694.131585656596, 4710.528947105262, 4757.452892917289, 10244.743881402876, 10251.641192558225, 10256.12822910061, 10257.17942564593, 10274.178065754722, 5348.465153484621, 5383.67697938125, 5385.6230063895155, 5386.8383948481605, 5389.56883449331, 4862.82980095695, 4864.294677271776, 4865.95134048662, 4866.562009419169, 4872.244802055687, 3741.120836603396, 3821.5032045833864, 3823.1206822430995, 3826.9234615308096, 3866.2654095721723, 1146.4382452598334, 1149.3965668024186, 1153.7807816514835, 1161.3032180691519, 1194.2237545595258, 1031.3347890610514, 1035.435687550287, 1038.5741845843168, 1057.9206559507913, 1074.403705943209, 5892.941070589325, 5942.673152976541, 5956.970215148896, 6138.171346868205, 6173.32093469727, 956.8373376526007, 1010.4846528270635, 1047.7223535763028, 1058.9470526473644, 1072.4798472740715, 3972.463717398114, 4015.7791321477016, 4051.6150965658217, 4067.1052368478886, 4070.9185816283507, 10284.228682848816, 10605.628802991858, 10624.840627390595, 10736.194785391126, 10736.463176841127, 10397.480125993821, 10508.106810921157, 10521.789564208673, 10540.051395374454, 10544.894551054545, 7768.883466748003, 7841.255080767432, 7848.018997625411, 7852.764417067498, 7864.095629002738, 5527.695976721319, 5653.830385088454, 5734.569907257056, 5773.047447171338, 5773.595848290579, 8922.55387230623, 8952.597133128895, 8982.281417486454, 9081.45511269326, 9173.08269173104, 8927.151920567536, 9245.537723113683, 9266.07339266068, 9268.72193834186, 9284.30367722437, 7455.850882982419, 7475.663595138123, 7531.3974882008215, 7562.773116806505, 7584.355329797029, 7863.449558530848, 8057.113717491188, 8090.0, 8101.918980810352, 8112.10766815638, 7446.441516886147, 7452.254774522505, 7462.626868656654, 7596.696132154873, 7621.35218506411, 7129.429645628441, 7158.248383919614, 7231.457640676865, 7328.743493977685, 7349.522281051928, 6808.761693340709, 6847.760328388483, 6865.107536020286, 6919.6886140124825, 6952.756653517103, 5228.058949389147, 5312.787488500417, 5331.866703332447, 5333.466653334635, 5447.891042179055, 4988.476209997898, 5087.160618497906, 5152.072626927039, 5155.819546315861, 5157.0], "value": 4591.190165383755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.57686353239643, 358.7560458887958, 364.74650118167875, 366.3387585408338, 366.8257577650616, 6004.5796794224425, 6013.428724271193, 6071.028635418334, 6079.1489191513165, 6118.816435506931, 5988.550858685594, 5997.070454079614, 5997.490213331872, 5997.820065398045, 6003.519718422526, 4450.577195166462, 4598.816047358102, 4709.387779588648, 4714.693544919569, 4723.10261050401, 4676.088162808253, 4695.9530404696025, 4707.12918110149, 4715.528447155291, 4718.90562188756, 4469.396631454761, 4686.390769199999, 4694.155052090622, 4694.29585562165, 4702.952970470285, 4520.344550040242, 4639.188142075123, 4639.373684552576, 4639.489656137839, 4642.419697537808, 4074.6943979201383, 4085.775282359469, 4087.3460246360737, 4088.3867419886956, 4099.385092236159, 5831.212786273841, 5835.095560188178, 5843.0, 5844.73698683556, 5845.620034697731, 5393.031543486638, 5402.486763757437, 5423.999999999961, 5426.403095659474, 5434.777175135602, 6334.398232167937, 6364.809055728336, 6367.586106903036, 6369.299377068519, 6370.203724534434, 4386.342048692722, 4488.192125417424, 4488.820447182109, 4493.618042466394, 4500.504944456107, 6630.469562435042, 6724.764633237862, 6730.226024007253, 6730.562513436611, 6735.966320168368, 4598.563136502027, 4601.217792975202, 4605.953940460619, 4606.147862645421, 4606.516315786861, 4655.255159174516, 4670.883227919261, 4671.11248862715, 4672.672912896132, 4672.789724462407, 5384.353877534721, 5389.51494365509, 5392.272043274186, 5394.379646340721, 5402.6758394496455, 6354.078658594478, 6418.743250269937, 6464.967675161688, 6466.838329041718, 6475.676216189172, 1595.8882878198415, 1607.9517614471586, 1611.8307577704172, 1612.9596760081092, 1613.806343238819, 1019.2814066083394, 1062.2351906627216, 1074.382230217628, 1087.526925787285, 1100.9559617615205, 6127.969360153171, 6138.508919286446, 6173.166622505994, 6188.071789231652, 6192.164057852132, 6538.1827271591055, 6540.901886471706, 6541.985992171221, 6544.7382104716235, 6553.115329430561, 6149.907751383733, 6152.230971128609, 6154.78458253959, 6157.7229024694, 6209.875802483925, 3488.755787094879, 3518.489818976234, 3525.259695463981, 3530.064532898784, 3531.876384326536, 6803.863922721518, 6833.58998460094, 7014.859702806018, 7015.088038554955, 7043.2604576518725, 6821.522493425413, 6832.45340372769, 6840.31596840322, 6840.623765692934, 6855.177378714586, 6958.860822783516, 6985.545939513915, 6991.0, 7000.404965577881, 7005.789826305219, 6693.966530167318, 6697.0, 6705.865882682221, 6707.765228216989, 6718.764843230463, 6494.09082728409, 6627.1716035495565, 6627.834304142435, 6632.270450250372, 6640.269570347355, 6946.270641582763, 6990.0, 6990.650467476504, 6996.335348142122, 6997.440204783503, 4593.954060459353, 4656.8137274508645, 4659.301104834302, 4669.929951050669, 4670.346151538855, 6654.600723956578, 6667.966660166668, 6680.565763225375, 6691.197056353074, 6693.0, 6266.498680105672, 6434.742610295536, 6463.838404039937, 6476.093346931528, 6482.870342593027, 6396.456301214449, 6398.232212134573, 6402.775902843378, 6450.870982580228, 6493.772717954849, 6339.651319177489, 6364.681765911779, 6372.07604897278, 6374.617522948638, 6375.458086062551, 6529.183852018497, 6532.0, 6537.967310163419, 6545.607263564201, 6548.214214294315, 6037.516998640011, 6055.969720151371, 6084.60450070744, 6381.234251889803, 6396.0], "value": 4873.94911950345, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.0533494483759, 841.2218697704621, 905.461250555919, 924.6127126783414, 957.0620791624208, 15226.543203703879, 15338.539843804676, 15363.385464581403, 15392.538223853287, 15452.07287562747, 14186.574402767934, 14239.572812815632, 14321.427142914272, 14340.928295358535, 14383.137011779274, 728.50097683087, 869.4348673362302, 892.8928528576547, 893.4192774696451, 896.0233345653269, 904.6878826804742, 905.7056456651582, 906.1301150895149, 906.3927168796922, 906.9682561110362, 949.5157469690473, 950.0309684122191, 952.538019060755, 954.5990683912773, 954.7279025477741, 763.3816608547095, 943.3443756589173, 957.3872721458238, 957.913787759105, 957.9185769209626, 696.7874798186551, 698.3749544158001, 700.9614471204081, 701.0255744515129, 701.6912558474306, 14777.187254700988, 14884.851151488454, 14923.925380373137, 14935.25323733809, 14983.550493485103, 15184.240787960669, 15408.22958852053, 15534.456294029655, 15717.21413929299, 15734.291956861969, 12019.338936358585, 12264.202826816228, 12318.815217771651, 12414.193077449936, 12677.366131693378, 4070.0910428179436, 4156.334986402161, 4157.771322577257, 4163.479565054368, 4163.958360416387, 54787.45212547863, 54804.451955480334, 55078.0, 55188.44811551913, 55459.16811247834, 11492.0, 11550.826737598944, 11774.646760597196, 11802.52789888412, 12313.13808033429, 21747.0, 22511.549769004527, 22551.887240563694, 22565.0, 22608.886955565118, 8917.10828917103, 9181.908180918239, 9183.127602877716, 9430.71707848752, 9598.9040109598, 4334.804933777988, 4357.215701173755, 4374.715643483163, 4394.670399720034, 4403.295472724349, 3496.3881320768764, 3507.4212754895666, 3522.489239060322, 3539.805310707936, 3542.2384187399834, 591.2904514582432, 649.4122818848855, 675.42588799521, 733.3729661139648, 735.1362149474346, 599.3706608061518, 631.3749388105742, 696.4776417686735, 700.5936556797045, 723.0672432561936, 10128.088472037552, 10249.760275844197, 10302.845457318146, 10375.67798174743, 10429.322094063862, 743.7248218159316, 839.9081194447194, 840.6763396092548, 845.045099038083, 847.3052097280171, 4783.115123702126, 4844.539768721966, 4897.706137631753, 4929.185785511389, 4941.45643979162, 64948.0, 66884.0, 67527.32472675308, 67642.0, 67830.0, 55972.44027559754, 55975.0, 56399.15401268984, 56811.71594142003, 57329.713351432976, 52790.0, 53440.198397024076, 53441.198382024304, 54180.187297190576, 55058.72470637701, 9494.240460762985, 9497.810043798947, 9643.517824108627, 9676.56455459492, 9751.609935602497, 37143.44284835784, 38003.42994855024, 38738.6126138735, 39593.0, 39749.801250994715, 44409.555904440545, 48417.0, 48563.75718121387, 49125.0, 49567.0, 11411.486483108447, 11906.345151016778, 12041.45813438415, 12158.331291779037, 12445.066620003356, 12682.746345072863, 12792.488300468249, 12801.0, 12862.485500580246, 12864.421101050288, 12571.93714031424, 12850.485980560672, 12898.48406063747, 12918.806217906551, 12925.15986460877, 13034.47862085506, 13069.085164038426, 13069.562199414793, 13133.146345487512, 13154.539591114648, 12891.806622900851, 12983.870161298266, 13020.153690010118, 13042.739145217232, 13044.282564459047, 10473.895261047597, 10722.678319650424, 10820.945895270474, 10833.404162770808, 10987.340759554452, 10753.946230268799, 10807.13542916549, 11149.442527873736, 11218.495167717636, 11290.266132701348], "value": 7167.812001145422, "unit": "score"}], "name": "dromaeo_css", "value": 7474.758646080133, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 110.0, 110.0, 110.0, 112.0, 104.0, 110.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 157.0, 172.0, 125.0, 153.0, 154.0, 128.0, 181.0, 128.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 76.0, 183.0, 76.0, 75.0, 73.0, 75.0, 157.0, 74.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 158.0, 92.0, 92.0, 92.0, 141.0, 92.0, 92.0, 155.0], "value": 109.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 145.0, 145.0, 146.0, 145.0, 144.0, 145.0, 145.0, 145.0], "value": 144.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 53.0, 54.0, 55.0, 55.0, 75.0, 53.0, 53.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 58.0, 57.0, 56.0, 57.0, 57.0, 57.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 73.0, 73.0, 72.0, 73.0, 73.0, 80.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 126.0, 125.0, 122.0, 109.0, 113.0, 111.0, 129.0, 128.0], "value": 118.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 163.0, 160.0, 162.0, 165.0, 160.0, 162.0, 161.0, 156.0, 153.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 66.0, 66.0, 68.0, 66.0, 68.0, 68.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1509.0000000000002, "alertThreshold": 2.0}]} 05:29:21 INFO - Return code: 0 05:29:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:21 ERROR - # TBPL SUCCESS # 05:29:21 INFO - Running post-action listener: _resource_record_post_action 05:29:21 INFO - Running post-run listener: _resource_record_post_run 05:29:22 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 217088; Write bytes: 238186496; Read time: 44; Write time: 122248 05:29:22 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 17920000; Read time: 0; Write time: 5100 05:29:22 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 217088; Write bytes: 220266496; Read time: 44; Write time: 117148 05:29:22 INFO - Running post-run listener: _upload_blobber_files 05:29:22 INFO - Blob upload gear active. 05:29:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:22 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 05:29:22 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:22 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:23 INFO - (blobuploader) - INFO - Done attempting. 05:29:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:24 INFO - (blobuploader) - INFO - Done attempting. 05:29:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:24 INFO - Return code: 0 05:29:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddb8eaa48200771f474e33297c7c326221daf9b8e04f21d0f0eb1094126302ccce39104b46715c972c4a7e0b96fc1e3e3f1d49da93b6556dfcca1ccd883f2218", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b27ee0f8b1f574f9620751bbf3a2f98133a91e77ff7d63a892c1baf6acc7a3f9e7a97c68cacb02b3fcda548abeeea33069cb93db6459d09317209e2aa7384a3"} 05:29:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:24 INFO - Contents: 05:29:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddb8eaa48200771f474e33297c7c326221daf9b8e04f21d0f0eb1094126302ccce39104b46715c972c4a7e0b96fc1e3e3f1d49da93b6556dfcca1ccd883f2218", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b27ee0f8b1f574f9620751bbf3a2f98133a91e77ff7d63a892c1baf6acc7a3f9e7a97c68cacb02b3fcda548abeeea33069cb93db6459d09317209e2aa7384a3"} 05:29:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:24 INFO - Copying logs to upload dir... 05:29:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.007375 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-18 05:29:24.257381) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 05:29:24.261800) ========= 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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddb8eaa48200771f474e33297c7c326221daf9b8e04f21d0f0eb1094126302ccce39104b46715c972c4a7e0b96fc1e3e3f1d49da93b6556dfcca1ccd883f2218", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b27ee0f8b1f574f9620751bbf3a2f98133a91e77ff7d63a892c1baf6acc7a3f9e7a97c68cacb02b3fcda548abeeea33069cb93db6459d09317209e2aa7384a3"} build_url:https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/JcPe8gFpQsGX4dyKBHjUYA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddb8eaa48200771f474e33297c7c326221daf9b8e04f21d0f0eb1094126302ccce39104b46715c972c4a7e0b96fc1e3e3f1d49da93b6556dfcca1ccd883f2218", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b27ee0f8b1f574f9620751bbf3a2f98133a91e77ff7d63a892c1baf6acc7a3f9e7a97c68cacb02b3fcda548abeeea33069cb93db6459d09317209e2aa7384a3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 05:29:24.286750) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:29:24.287076) ========= 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=920470bf432669ad7e3a5387000001ca-1468843701.25797-487235696 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:29:24.370221) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 05:29:24.370569) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 05:29:24.370897) ========= ========= Total master_lag: 0.28 =========