builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1468753754.29 results: success (0) buildid: 20160717013154 builduid: b29bf0c67b4642cdbf4ed5e6aa124749 revision: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.296113) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.296605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.296926) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.326147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.326456) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-17 04:09:14-- 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% 99.8M=0s 2016-07-17 04:09:14 (99.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.488089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.488400) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013604 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.537788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:14.538134) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa --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 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa --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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-17 04:09:14,639 truncating revision to first 12 chars 2016-07-17 04:09:14,639 Setting DEBUG logging. 2016-07-17 04:09:14,639 attempt 1/10 2016-07-17 04:09:14,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/92ec0fc8583e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 04:09:14,968 unpacking tar archive at: mozilla-beta-92ec0fc8583e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.677596 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:15.229722) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:15.230091) ========= script_repo_revision: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:15.230544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:15.230848) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 04:09:15.241383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-17 04:09:15.241695) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:09:15 INFO - MultiFileLogger online at 20160717 04:09:15 in /builds/slave/test 04:09:15 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 04:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:09:15 INFO - {'append_to_log': False, 04:09:15 INFO - 'base_work_dir': '/builds/slave/test', 04:09:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:09:15 INFO - 'branch': 'Mozilla-Beta', 04:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:15 INFO - 'config_files': ('talos/linux_config.py',), 04:09:15 INFO - 'default_actions': ('clobber', 04:09:15 INFO - 'read-buildbot-config', 04:09:15 INFO - 'download-and-extract', 04:09:15 INFO - 'populate-webroot', 04:09:15 INFO - 'create-virtualenv', 04:09:15 INFO - 'install', 04:09:15 INFO - 'run-tests'), 04:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:15 INFO - 'download_minidump_stackwalk': True, 04:09:15 INFO - 'download_symbols': 'ondemand', 04:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:15 INFO - 'installer_path': 'installer.exe', 04:09:15 INFO - 'log_level': 'info', 04:09:15 INFO - 'log_name': 'talos', 04:09:15 INFO - 'log_to_console': True, 04:09:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:09:15 INFO - 'opt_config_files': (), 04:09:15 INFO - 'pip_index': False, 04:09:15 INFO - 'sps_profile': False, 04:09:15 INFO - 'sps_profile_interval': 0, 04:09:15 INFO - 'suite': 'dromaeojs', 04:09:15 INFO - 'system_bits': '32', 04:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:15 INFO - 'title': 'talos-linux64-ix-033', 04:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:15 INFO - 'use_talos_json': True, 04:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:15 INFO - 'work_dir': 'build'} 04:09:15 INFO - ##### 04:09:15 INFO - ##### Running clobber step. 04:09:15 INFO - ##### 04:09:15 INFO - Running pre-action listener: _resource_record_pre_action 04:09:15 INFO - Running main action method: clobber 04:09:15 INFO - rmtree: /builds/slave/test/build 04:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:09:15 INFO - Running post-action listener: _resource_record_post_action 04:09:15 INFO - ##### 04:09:15 INFO - ##### Running read-buildbot-config step. 04:09:15 INFO - ##### 04:09:15 INFO - Running pre-action listener: _resource_record_pre_action 04:09:15 INFO - Running main action method: read_buildbot_config 04:09:15 INFO - Using buildbot properties: 04:09:15 INFO - { 04:09:15 INFO - "project": "", 04:09:15 INFO - "product": "firefox", 04:09:15 INFO - "script_repo_revision": "production", 04:09:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 04:09:15 INFO - "repository": "", 04:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 04:09:15 INFO - "buildid": "20160717013154", 04:09:15 INFO - "pgo_build": "True", 04:09:15 INFO - "basedir": "/builds/slave/test", 04:09:15 INFO - "buildnumber": 175, 04:09:15 INFO - "slavename": "talos-linux64-ix-033", 04:09:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:09:15 INFO - "platform": "ubuntu64_hw", 04:09:15 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 04:09:15 INFO - "branch": "mozilla-beta", 04:09:15 INFO - "repo_path": "releases/mozilla-beta", 04:09:15 INFO - "revision": "92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa", 04:09:15 INFO - "stage_platform": "linux64-pgo", 04:09:15 INFO - "builduid": "b29bf0c67b4642cdbf4ed5e6aa124749", 04:09:15 INFO - "slavebuilddir": "test" 04:09:15 INFO - } 04:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2. 04:09:15 INFO - Running post-action listener: _resource_record_post_action 04:09:15 INFO - ##### 04:09:15 INFO - ##### Running download-and-extract step. 04:09:15 INFO - ##### 04:09:15 INFO - Running pre-action listener: _resource_record_pre_action 04:09:15 INFO - Running main action method: download_and_extract 04:09:15 INFO - mkdir: /builds/slave/test/build/tests 04: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')]} 04:09:15 INFO - https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:16 INFO - Downloaded 1327 bytes. 04:09:16 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.linux-x86_64.test_packages.json 04:09:16 INFO - Using the following test package requirements: 04:09:16 INFO - {u'common': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 04:09:16 INFO - u'cppunittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.cppunittest.tests.zip'], 04:09:16 INFO - u'gtest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.gtest.tests.zip'], 04:09:16 INFO - u'jittest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'jsshell-linux-x86_64.zip'], 04:09:16 INFO - u'mochitest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.mochitest.tests.zip'], 04:09:16 INFO - u'mozbase': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'], 04:09:16 INFO - u'reftest': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.reftest.tests.zip'], 04:09:16 INFO - u'talos': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.talos.tests.zip'], 04:09:16 INFO - u'web-platform': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.web-platform.tests.zip'], 04:09:16 INFO - u'xpcshell': [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip', 04:09:16 INFO - u'firefox-48.0.en-US.linux-x86_64.xpcshell.tests.zip']} 04:09:16 INFO - Downloading packages: [u'firefox-48.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:09:16 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')]} 04:09:16 INFO - https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:18 INFO - Downloaded 24200755 bytes. 04:09:18 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'] 04:09:18 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 04:09:19 INFO - Return code: 0 04:09:19 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 04:09:19 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')]} 04:09:19 INFO - https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.common.tests.zip 04:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:31 INFO - Downloaded 24200755 bytes. 04:09:31 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'] 04:09:31 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 04:09:32 INFO - Return code: 0 04:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:09:32 INFO - https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:09:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 04:09:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.talos.tests.zip 04:09:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:33 INFO - Downloaded 10840145 bytes. 04:09:33 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'] 04:09:33 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 04:09:34 INFO - Return code: 0 04:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:34 INFO - https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:09:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:09:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:09:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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 04:09:35 INFO - Downloaded 56052136 bytes. 04:09:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:09:35 INFO - mkdir: /builds/slave/test/properties 04:09:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:35 INFO - Contents: 04:09:35 INFO - build_url:https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 04:09:36 INFO - Running post-action listener: _resource_record_post_action 04:09:36 INFO - Running post-action listener: set_extra_try_arguments 04:09:36 INFO - ##### 04:09:36 INFO - ##### Running populate-webroot step. 04:09:36 INFO - ##### 04:09:36 INFO - Running pre-action listener: _resource_record_pre_action 04:09:36 INFO - Running main action method: populate_webroot 04:09:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:09:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:09:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:09:36 INFO - '--tppagecycles', 04:09:36 INFO - '7'], 04:09:36 INFO - 'tests': ['tsvgm']}}, 04:09:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:09:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:09:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['damp', 'tps']}, 04:09:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'tests': ['damp', 'tps']}, 04:09:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['dromaeo_dom']}, 04:09:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['a11yr', 04:09:36 INFO - 'ts_paint', 04:09:36 INFO - 'tpaint', 04:09:36 INFO - 'sessionrestore', 04:09:36 INFO - 'sessionrestore_no_auto_restore', 04:09:36 INFO - 'tabpaint']}, 04:09:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['tsvgx', 04:09:36 INFO - 'tsvgr_opacity', 04:09:36 INFO - 'tart', 04:09:36 INFO - 'tscrollx', 04:09:36 INFO - 'cart']}, 04:09:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:36 INFO - 'tsvgr_opacity', 04:09:36 INFO - 'tart', 04:09:36 INFO - 'tscrollx', 04:09:36 INFO - 'cart']}, 04:09:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'talos_options': ['--disable-e10s'], 04:09:36 INFO - 'tests': ['tp5o']}, 04:09:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'tests': ['tp5o']}, 04:09:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'talos_options': ['--disable-e10s', 04:09:36 INFO - '--xperf_path', 04:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:36 INFO - 'tests': ['tp5n']}, 04:09:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:09:36 INFO - 'talos_options': ['--xperf_path', 04:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:36 INFO - 'tests': ['tp5n']}}, 04:09:36 INFO - 'talos.zip': {'path': '', 04:09:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:09:36 INFO - Running post-action listener: _resource_record_post_action 04:09:36 INFO - ##### 04:09:36 INFO - ##### Running create-virtualenv step. 04:09:36 INFO - ##### 04:09:36 INFO - Running pre-action listener: _resource_record_pre_action 04:09:36 INFO - Running main action method: create_virtualenv 04:09:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:36 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 04:09:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:36 INFO - Using real prefix '/usr' 04:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:36 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:38 INFO - Installing pip.................done. 04:09:38 INFO - Return code: 0 04:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:38 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')]} 04:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:38 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')]} 04:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:38 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:38 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 04:09:38 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 04:09:38 INFO - Using env: {'DISPLAY': ':0', 04:09:38 INFO - 'HOME': '/home/cltbld', 04:09:38 INFO - 'LANG': 'en_US.UTF-8', 04:09:38 INFO - 'LANGUAGE': 'en_US:en', 04:09:38 INFO - 'LOGNAME': 'cltbld', 04:09:38 INFO - 'MAIL': '/var/mail/cltbld', 04:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:38 INFO - 'MOZ_NO_REMOTE': '1', 04:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:38 INFO - 'NO_EM_RESTART': '1', 04:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:38 INFO - 'PWD': '/builds/slave/test', 04:09:38 INFO - 'SHELL': '/bin/bash', 04:09:38 INFO - 'SHLVL': '1', 04:09:38 INFO - 'TERM': 'linux', 04:09:38 INFO - 'TMOUT': '86400', 04:09:38 INFO - 'USER': 'cltbld', 04:09:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:38 INFO - '_': '/tools/buildbot/bin/python'} 04:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:39 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 04:09:39 INFO - Cleaning up... 04:09:39 INFO - Return code: 0 04:09:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:09:39 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')]} 04:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:39 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')]} 04:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:39 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:39 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 04:09:39 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 04:09:39 INFO - Using env: {'DISPLAY': ':0', 04:09:39 INFO - 'HOME': '/home/cltbld', 04:09:39 INFO - 'LANG': 'en_US.UTF-8', 04:09:39 INFO - 'LANGUAGE': 'en_US:en', 04:09:39 INFO - 'LOGNAME': 'cltbld', 04:09:39 INFO - 'MAIL': '/var/mail/cltbld', 04:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:39 INFO - 'MOZ_NO_REMOTE': '1', 04:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:39 INFO - 'NO_EM_RESTART': '1', 04:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:39 INFO - 'PWD': '/builds/slave/test', 04:09:39 INFO - 'SHELL': '/bin/bash', 04:09:39 INFO - 'SHLVL': '1', 04:09:39 INFO - 'TERM': 'linux', 04:09:39 INFO - 'TMOUT': '86400', 04:09:39 INFO - 'USER': 'cltbld', 04:09:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:39 INFO - '_': '/tools/buildbot/bin/python'} 04:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:39 INFO - Downloading/unpacking psutil>=0.7.1 04:09:39 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 04:09:39 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 04:09:39 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 04:09:39 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 04:09:39 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 04:09:39 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 04:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:41 INFO - Installing collected packages: psutil 04:09:41 INFO - Running setup.py install for psutil 04:09:41 INFO - building 'psutil._psutil_linux' extension 04:09:41 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 04:09:41 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 04:09:41 INFO - building 'psutil._psutil_posix' extension 04:09:41 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 04:09:41 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 04:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:41 INFO - Successfully installed psutil 04:09:41 INFO - Cleaning up... 04:09:42 INFO - Return code: 0 04:09:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:09:42 INFO - Using env: {'DISPLAY': ':0', 04:09:42 INFO - 'HOME': '/home/cltbld', 04:09:42 INFO - 'LANG': 'en_US.UTF-8', 04:09:42 INFO - 'LANGUAGE': 'en_US:en', 04:09:42 INFO - 'LOGNAME': 'cltbld', 04:09:42 INFO - 'MAIL': '/var/mail/cltbld', 04:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:42 INFO - 'MOZ_NO_REMOTE': '1', 04:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:42 INFO - 'NO_EM_RESTART': '1', 04:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:42 INFO - 'PWD': '/builds/slave/test', 04:09:42 INFO - 'SHELL': '/bin/bash', 04:09:42 INFO - 'SHLVL': '1', 04:09:42 INFO - 'TERM': 'linux', 04:09:42 INFO - 'TMOUT': '86400', 04:09:42 INFO - 'USER': 'cltbld', 04:09:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:42 INFO - '_': '/tools/buildbot/bin/python'} 04:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:09:44 INFO - Installing collected packages: mozsystemmonitor 04:09:44 INFO - Running setup.py install for mozsystemmonitor 04:09:44 INFO - Successfully installed mozsystemmonitor 04:09:44 INFO - Cleaning up... 04:09:44 INFO - Return code: 0 04:09:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:09:44 INFO - Using env: {'DISPLAY': ':0', 04:09:44 INFO - 'HOME': '/home/cltbld', 04:09:44 INFO - 'LANG': 'en_US.UTF-8', 04:09:44 INFO - 'LANGUAGE': 'en_US:en', 04:09:44 INFO - 'LOGNAME': 'cltbld', 04:09:44 INFO - 'MAIL': '/var/mail/cltbld', 04:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:44 INFO - 'MOZ_NO_REMOTE': '1', 04:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:44 INFO - 'NO_EM_RESTART': '1', 04:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:44 INFO - 'PWD': '/builds/slave/test', 04:09:44 INFO - 'SHELL': '/bin/bash', 04:09:44 INFO - 'SHLVL': '1', 04:09:44 INFO - 'TERM': 'linux', 04:09:44 INFO - 'TMOUT': '86400', 04:09:44 INFO - 'USER': 'cltbld', 04:09:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:44 INFO - '_': '/tools/buildbot/bin/python'} 04:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:09:44 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 04:09:44 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 04:09:44 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 04:09:44 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 04:09:44 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 04:09:44 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 04:09:46 INFO - Downloading blobuploader-1.2.4.tar.gz 04:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:09:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:09:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:46 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 04:09:47 INFO - Downloading docopt-0.6.1.tar.gz 04:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:09:47 INFO - Installing collected packages: blobuploader, requests, docopt 04:09:47 INFO - Running setup.py install for blobuploader 04:09:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:09:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:09:47 INFO - Running setup.py install for requests 04:09:47 INFO - Running setup.py install for docopt 04:09:47 INFO - Successfully installed blobuploader requests docopt 04:09:47 INFO - Cleaning up... 04:09:47 INFO - Return code: 0 04:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:47 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:47 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 04:09:47 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 04:09:47 INFO - Using env: {'DISPLAY': ':0', 04:09:47 INFO - 'HOME': '/home/cltbld', 04:09:47 INFO - 'LANG': 'en_US.UTF-8', 04:09:47 INFO - 'LANGUAGE': 'en_US:en', 04:09:47 INFO - 'LOGNAME': 'cltbld', 04:09:47 INFO - 'MAIL': '/var/mail/cltbld', 04:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:47 INFO - 'MOZ_NO_REMOTE': '1', 04:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:47 INFO - 'NO_EM_RESTART': '1', 04:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:47 INFO - 'PWD': '/builds/slave/test', 04:09:47 INFO - 'SHELL': '/bin/bash', 04:09:47 INFO - 'SHLVL': '1', 04:09:47 INFO - 'TERM': 'linux', 04:09:47 INFO - 'TMOUT': '86400', 04:09:47 INFO - 'USER': 'cltbld', 04:09:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:47 INFO - '_': '/tools/buildbot/bin/python'} 04:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:48 INFO - Running setup.py (path:/tmp/pip-SgG0K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:48 INFO - Running setup.py (path:/tmp/pip-bSfu34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:48 INFO - Running setup.py (path:/tmp/pip-csbWfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:48 INFO - Running setup.py (path:/tmp/pip-UDuzPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:48 INFO - Running setup.py (path:/tmp/pip-yRydji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:48 INFO - Running setup.py (path:/tmp/pip-bBQdp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:48 INFO - Running setup.py (path:/tmp/pip-iVaCTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:48 INFO - Running setup.py (path:/tmp/pip-lNqlNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:48 INFO - Running setup.py (path:/tmp/pip-VP1Vxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:48 INFO - Running setup.py (path:/tmp/pip-DE2XJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:48 INFO - Running setup.py (path:/tmp/pip-pA6fod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:48 INFO - Running setup.py (path:/tmp/pip-RqCTW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:48 INFO - Running setup.py (path:/tmp/pip-P_T8zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:48 INFO - Running setup.py (path:/tmp/pip-4443BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:49 INFO - Running setup.py (path:/tmp/pip-YcK7QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:49 INFO - Running setup.py (path:/tmp/pip-MOD0P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:49 INFO - Running setup.py (path:/tmp/pip-1CpLlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:09:49 INFO - Running setup.py install for manifestparser 04:09:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Running setup.py install for mozcrash 04:09:49 INFO - Running setup.py install for mozdebug 04:09:49 INFO - Running setup.py install for mozdevice 04:09:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Running setup.py install for mozfile 04:09:49 INFO - Running setup.py install for mozhttpd 04:09:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Running setup.py install for mozinfo 04:09:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:09:49 INFO - Running setup.py install for mozInstall 04:09:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Running setup.py install for mozleak 04:09:50 INFO - Running setup.py install for mozlog 04:09:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Running setup.py install for moznetwork 04:09:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Running setup.py install for mozprocess 04:09:50 INFO - Running setup.py install for mozprofile 04:09:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Running setup.py install for mozrunner 04:09:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:09:50 INFO - Running setup.py install for mozscreenshot 04:09:50 INFO - Running setup.py install for moztest 04:09:50 INFO - Running setup.py install for mozversion 04:09:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:09:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:09:51 INFO - Cleaning up... 04:09:51 INFO - Return code: 0 04:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:51 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:51 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 04:09:51 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 04:09:51 INFO - Using env: {'DISPLAY': ':0', 04:09:51 INFO - 'HOME': '/home/cltbld', 04:09:51 INFO - 'LANG': 'en_US.UTF-8', 04:09:51 INFO - 'LANGUAGE': 'en_US:en', 04:09:51 INFO - 'LOGNAME': 'cltbld', 04:09:51 INFO - 'MAIL': '/var/mail/cltbld', 04:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:51 INFO - 'MOZ_NO_REMOTE': '1', 04:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:51 INFO - 'NO_EM_RESTART': '1', 04:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:51 INFO - 'PWD': '/builds/slave/test', 04:09:51 INFO - 'SHELL': '/bin/bash', 04:09:51 INFO - 'SHLVL': '1', 04:09:51 INFO - 'TERM': 'linux', 04:09:51 INFO - 'TMOUT': '86400', 04:09:51 INFO - 'USER': 'cltbld', 04:09:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:51 INFO - '_': '/tools/buildbot/bin/python'} 04:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:51 INFO - Running setup.py (path:/tmp/pip-n40ef7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:51 INFO - Running setup.py (path:/tmp/pip-dKs_S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:51 INFO - Running setup.py (path:/tmp/pip-el8tMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:51 INFO - Running setup.py (path:/tmp/pip-9PZEpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:51 INFO - Running setup.py (path:/tmp/pip-9IREnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:51 INFO - Running setup.py (path:/tmp/pip-2udQmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:51 INFO - Running setup.py (path:/tmp/pip-0LNPut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:51 INFO - Running setup.py (path:/tmp/pip-SANSlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:51 INFO - Running setup.py (path:/tmp/pip-Bx333J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:51 INFO - Running setup.py (path:/tmp/pip-9gI9Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:51 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)) 04:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:51 INFO - Running setup.py (path:/tmp/pip-v1rCVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:52 INFO - Running setup.py (path:/tmp/pip-e8lbWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:52 INFO - Running setup.py (path:/tmp/pip-sprYlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:52 INFO - Running setup.py (path:/tmp/pip-vQpYaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:52 INFO - Running setup.py (path:/tmp/pip-Boriwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:52 INFO - Running setup.py (path:/tmp/pip-QiVsiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:52 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)) 04:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:52 INFO - Running setup.py (path:/tmp/pip-QWCdY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:52 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)) 04:09:52 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)) 04:09:52 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)) 04:09:52 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)) 04:09:52 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)) 04:09:52 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)) 04:09:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:52 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 04:09:52 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 04:09:52 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 04:09:52 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 04:09:52 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 04:09:52 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 04:09:54 INFO - Downloading blessings-1.6.tar.gz 04:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:09:54 INFO - Installing collected packages: blessings 04:09:54 INFO - Running setup.py install for blessings 04:09:54 INFO - Successfully installed blessings 04:09:54 INFO - Cleaning up... 04:09:54 INFO - Return code: 0 04:09:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:54 INFO - Reading from file tmpfile_stdout 04:09:54 INFO - Current package versions: 04:09:54 INFO - argparse == 1.2.1 04:09:54 INFO - blessings == 1.6 04:09:54 INFO - blobuploader == 1.2.4 04:09:54 INFO - docopt == 0.6.1 04:09:54 INFO - manifestparser == 1.1 04:09:54 INFO - mozInstall == 1.12 04:09:54 INFO - mozcrash == 0.17 04:09:54 INFO - mozdebug == 0.1 04:09:54 INFO - mozdevice == 0.48 04:09:54 INFO - mozfile == 1.2 04:09:54 INFO - mozhttpd == 0.7 04:09:54 INFO - mozinfo == 0.9 04:09:54 INFO - mozleak == 0.1 04:09:54 INFO - mozlog == 3.1 04:09:54 INFO - moznetwork == 0.27 04:09:54 INFO - mozprocess == 0.22 04:09:54 INFO - mozprofile == 0.28 04:09:54 INFO - mozrunner == 6.11 04:09:54 INFO - mozscreenshot == 0.1 04:09:54 INFO - mozsystemmonitor == 0.0 04:09:54 INFO - moztest == 0.7 04:09:54 INFO - mozversion == 1.4 04:09:54 INFO - psutil == 3.1.1 04:09:54 INFO - requests == 1.2.3 04:09:54 INFO - wsgiref == 0.1.2 04:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09: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')]} 04:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:54 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:54 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 04:09:54 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 04:09:54 INFO - Using env: {'DISPLAY': ':0', 04:09:54 INFO - 'HOME': '/home/cltbld', 04:09:54 INFO - 'LANG': 'en_US.UTF-8', 04:09:54 INFO - 'LANGUAGE': 'en_US:en', 04:09:54 INFO - 'LOGNAME': 'cltbld', 04:09:54 INFO - 'MAIL': '/var/mail/cltbld', 04:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:54 INFO - 'MOZ_NO_REMOTE': '1', 04:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:54 INFO - 'NO_EM_RESTART': '1', 04:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:54 INFO - 'PWD': '/builds/slave/test', 04:09:54 INFO - 'SHELL': '/bin/bash', 04:09:54 INFO - 'SHLVL': '1', 04:09:54 INFO - 'TERM': 'linux', 04:09:54 INFO - 'TMOUT': '86400', 04:09:54 INFO - 'USER': 'cltbld', 04:09:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:54 INFO - '_': '/tools/buildbot/bin/python'} 04:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 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)) 04:09:55 INFO - Cleaning up... 04:09:55 INFO - Return code: 0 04:09:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:09:55 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')]} 04:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:55 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')]} 04:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:55 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:55 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 04:09:55 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 04:09:55 INFO - Using env: {'DISPLAY': ':0', 04:09:55 INFO - 'HOME': '/home/cltbld', 04:09:55 INFO - 'LANG': 'en_US.UTF-8', 04:09:55 INFO - 'LANGUAGE': 'en_US:en', 04:09:55 INFO - 'LOGNAME': 'cltbld', 04:09:55 INFO - 'MAIL': '/var/mail/cltbld', 04:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:55 INFO - 'MOZ_NO_REMOTE': '1', 04:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:55 INFO - 'NO_EM_RESTART': '1', 04:09:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:55 INFO - 'PWD': '/builds/slave/test', 04:09:55 INFO - 'SHELL': '/bin/bash', 04:09:55 INFO - 'SHLVL': '1', 04:09:55 INFO - 'TERM': 'linux', 04:09:55 INFO - 'TMOUT': '86400', 04:09:55 INFO - 'USER': 'cltbld', 04:09:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:55 INFO - '_': '/tools/buildbot/bin/python'} 04:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:55 INFO - Downloading/unpacking jsonschema 04:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:57 INFO - Downloading/unpacking functools32 (from jsonschema) 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:57 INFO - warning: no files found matching '*.txt' 04:09:57 INFO - no previously-included directories found matching 'build' 04:09:57 INFO - no previously-included directories found matching 'dist' 04:09:57 INFO - no previously-included directories found matching '.git*' 04:09:57 INFO - Installing collected packages: jsonschema, functools32 04:09:57 INFO - Running setup.py install for functools32 04:09:57 INFO - warning: no files found matching '*.txt' 04:09:57 INFO - no previously-included directories found matching 'build' 04:09:57 INFO - no previously-included directories found matching 'dist' 04:09:57 INFO - no previously-included directories found matching '.git*' 04:09:57 INFO - Successfully installed jsonschema functools32 04:09:57 INFO - Cleaning up... 04:09:57 INFO - Return code: 0 04:09:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:09:57 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')]} 04:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:57 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')]} 04:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:57 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 0x7fabc9407f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b75a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', '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 04:09:57 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 04:09:57 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 04:09:57 INFO - Using env: {'DISPLAY': ':0', 04:09:57 INFO - 'HOME': '/home/cltbld', 04:09:57 INFO - 'LANG': 'en_US.UTF-8', 04:09:57 INFO - 'LANGUAGE': 'en_US:en', 04:09:57 INFO - 'LOGNAME': 'cltbld', 04:09:57 INFO - 'MAIL': '/var/mail/cltbld', 04:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:57 INFO - 'MOZ_NO_REMOTE': '1', 04:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:57 INFO - 'NO_EM_RESTART': '1', 04:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:57 INFO - 'PWD': '/builds/slave/test', 04:09:57 INFO - 'SHELL': '/bin/bash', 04:09:57 INFO - 'SHLVL': '1', 04:09:57 INFO - 'TERM': 'linux', 04:09:57 INFO - 'TMOUT': '86400', 04:09:57 INFO - 'USER': 'cltbld', 04:09:57 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:57 INFO - '_': '/tools/buildbot/bin/python'} 04:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:57 INFO - Downloading/unpacking flake8 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:57 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 04:09:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:09:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:09:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:09:59 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 04:10:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:00 INFO - no previously-included directories found matching 'docs/_build' 04:10:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:10:00 INFO - Running setup.py install for pep8 04:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:00 INFO - no previously-included directories found matching 'docs/_build' 04:10:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:10:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:10:00 INFO - Cleaning up... 04:10:00 INFO - Return code: 0 04:10:00 INFO - Running post-action listener: _resource_record_post_action 04:10:00 INFO - Running post-action listener: _start_resource_monitoring 04:10:00 INFO - Starting resource monitoring. 04:10:00 INFO - ##### 04:10:00 INFO - ##### Running install step. 04:10:00 INFO - ##### 04:10:00 INFO - Running pre-action listener: _resource_record_pre_action 04:10:00 INFO - Running main action method: install 04:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:00 INFO - Reading from file tmpfile_stdout 04:10:00 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:10:01 INFO - Reading from file tmpfile_stdout 04:10:01 INFO - Output received: 04:10:01 INFO - Usage: mozinstall [options] installer 04:10:01 INFO - Options: 04:10:01 INFO - -h, --help show this help message and exit 04:10:01 INFO - -d DEST, --destination=DEST 04:10:01 INFO - Directory to install application into. [default: 04:10:01 INFO - "/builds/slave/test"] 04:10:01 INFO - --app=APP Application being installed. [default: firefox] 04:10:01 INFO - mkdir: /builds/slave/test/build/application 04:10:01 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'] 04:10:01 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 04:10:14 INFO - Reading from file tmpfile_stdout 04:10:14 INFO - Output received: 04:10:14 INFO - /builds/slave/test/build/application/firefox/firefox 04:10:14 INFO - Running post-action listener: _resource_record_post_action 04:10:14 INFO - ##### 04:10:14 INFO - ##### Running run-tests step. 04:10:14 INFO - ##### 04:10:14 INFO - Running pre-action listener: _resource_record_pre_action 04:10:14 INFO - Running main action method: run_tests 04:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:10:14 INFO - Python 2.7.3 04:10:14 INFO - Return code: 0 04:10:14 INFO - grabbing minidump binary from tooltool 04:10:14 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')]} 04:10:14 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 0x2846410>, '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 0x2847310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28477a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:10:14 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 04:10:14 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 04:10:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:10:14 INFO - Return code: 0 04:10:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:10:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:10:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:10:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:10:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:10:15 INFO - Return code: 0 04:10:15 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/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:10:15 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/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 04:10:15 INFO - Using env: {'DISPLAY': ':0', 04:10:15 INFO - 'HOME': '/home/cltbld', 04:10:15 INFO - 'LANG': 'en_US.UTF-8', 04:10:15 INFO - 'LANGUAGE': 'en_US:en', 04:10:15 INFO - 'LOGNAME': 'cltbld', 04:10:15 INFO - 'MAIL': '/var/mail/cltbld', 04:10:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:10:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:15 INFO - 'MOZ_NO_REMOTE': '1', 04:10:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:10:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:15 INFO - 'NO_EM_RESTART': '1', 04:10:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:15 INFO - 'PWD': '/builds/slave/test', 04:10:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:10:15 INFO - 'SHELL': '/bin/bash', 04:10:15 INFO - 'SHLVL': '1', 04:10:15 INFO - 'TERM': 'linux', 04:10:15 INFO - 'TMOUT': '86400', 04:10:15 INFO - 'USER': 'cltbld', 04:10:15 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933', 04:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:15 INFO - '_': '/tools/buildbot/bin/python'} 04:10:15 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/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:10:15 INFO - mozversion application_buildid: 20160717013154 04:10:15 INFO - mozversion application_changeset: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa 04:10:15 INFO - mozversion application_display_name: Firefox 04:10:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:10:15 INFO - mozversion application_name: Firefox 04:10:15 INFO - mozversion application_remotingname: firefox 04:10:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:15 INFO - mozversion application_vendor: Mozilla 04:10:15 INFO - mozversion application_version: 48.0 04:10:15 INFO - mozversion platform_buildid: 20160717013154 04:10:15 INFO - mozversion platform_changeset: 92ec0fc8583e2a8bf38f0d8e98a3b78cda4eebaa 04:10:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:10:15 INFO - mozversion platform_version: 48.0 04:10:15 INFO - using testdate: 1468753815 04:10:15 INFO - actual date: 1468753815 04:10:15 INFO - starting webserver on 'localhost:54034' 04:10:15 INFO - SUITE-START | Running 2 tests 04:10:15 INFO - TEST-START | dromaeo_css 04:10:15 INFO - operating with platform_type : linux_ 04:10:15 INFO - Initialising browser for dromaeo_css test... 04:10:15 INFO - TEST-INFO | started process 4570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSbq5DP/profile http://localhost:54034/getInfo.html) 04:10:19 INFO - PROCESS | 4570 | __metrics Screen width/height:1600/1200 04:10:19 INFO - PROCESS | 4570 | colorDepth:24 04:10:19 INFO - PROCESS | 4570 | Browser inner width/height: 1024/697 04:10:19 INFO - PROCESS | 4570 | __metrics 04:10:21 INFO - PROCESS | 4570 | 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" 04:10:21 ERROR - PROCESS | 4570 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:10:22 INFO - TEST-INFO | 4570: exit 0 04:10:22 INFO - Browser initialized. 04:10:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:10:22 INFO - TEST-INFO | started process 4660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSbq5DP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:33 INFO - PROCESS | 4660 | RSS: Main: 140218368 04:10:33 INFO - PROCESS | 4660 | 04:11:24 INFO - PROCESS | 4660 | 1468753884802 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] 04:13:32 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54034/tests/dromaeo/cssquery-ext.html) 04:13:33 INFO - PROCESS | 4660 | RSS: Main: 247230464 04:13:33 INFO - PROCESS | 4660 | 04:16:35 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-ext.html (next: http://localhost:54034/tests/dromaeo/cssquery-jquery.html) 04:16:36 INFO - PROCESS | 4660 | RSS: Main: 224149504 04:16:36 INFO - PROCESS | 4660 | 04:19:38 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54034/tests/dromaeo/cssquery-mootools.html) 04:19:38 INFO - PROCESS | 4660 | RSS: Main: 225943552 04:19:38 INFO - PROCESS | 4660 | 04:22:40 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54034/tests/dromaeo/cssquery-prototype.html) 04:22:40 INFO - PROCESS | 4660 | RSS: Main: 215994368 04:22:40 INFO - PROCESS | 4660 | 04:25:43 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54034/tests/dromaeo/cssquery-yui.html) 04:25:44 INFO - PROCESS | 4660 | RSS: Main: 181743616 04:25:44 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | Cycle 1(1): loaded http://localhost:54034/tests/dromaeo/cssquery-yui.html (next: http://localhost:54034/tests/dromaeo/cssquery-dojo.html) 04:28:46 INFO - PROCESS | 4660 | RSS: Main: 257892352 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | __start_tp_report 04:28:46 INFO - PROCESS | 4660 | _x_x_mozilla_page_load 04:28:46 INFO - PROCESS | 4660 | _x_x_mozilla_page_load_details 04:28:46 INFO - PROCESS | 4660 | |i|pagename|runs| 04:28:46 INFO - PROCESS | 4660 | |0;dojo.html;3298.9340213195733;3348.945082299077;3371.6796904294083;3377.6284608693054;3392.25370418508;5478.890422191554;5479.4794494523;5499.862503437406;5514.6139770216105;5520.006398848211;5468.28912241409;5498.285222921015;5505.889882202354;5509.3664228613725;5550.028744969634;4870.391201099863;4980.402351717799;5058.2159765236265;5102.744862756847;5131.179011358184;4818.34952281441;4935.555799978001;4956.306117143603;4963.925541116868;4967.205247160455;4887.682300650446;4930.309756634074;4961.603071754251;4963.776630051656;5009.474005229435;4990.500949905017;5004.699718016913;5006.6245031622775;5006.849794506171;5026.120428924942;4856.39295088114;4909.435414927293;4929.112759703252;4961.206207006898;4992.550670439678;5244.239585260154;5260.710660913648;5264.499872512143;5302.73486325686;5313.38896026958;5773.797917072924;5808.97095514524;5812.273465816773;5813.767449302023;5814.854628634275;5774.769009239625;5805.709714514257;5817.0983497557945;5830.067189249742;5858.209141765849;5486.259354987065;5491.945080549183;5534.723263836792;5535.861603459945;5536.529395001429;5663.603547751618;5979.073243647242;6016.548758843104;6017.127516510169;6018.21763170785;5656.2929633795775;5691.971540142273;5705.62913410627;5709.029464990961;5740.024195886709;5755.712214389264;5759.827205183851;5772.04761214395;5777.075667893201;5783.624064435799;5622.32532096151;5654.15187721845;5693.316801983706;5703;5726.083826587809;5623.325200975909;5630.40880707528;5640.943590564042;5644.689542075143;5672.319321681424;4364.519902810688;4366.694331396835;4369.71596846204;4369.912601748011;4396.670249731283;3920.725549211585;3949.19041596472;3964.960350396517;3973.920521589552;3985.661218796377;5597.972010140004;5598.664080155204;5609.27079479665;5610.410906854803;5624.859378515488;5878.088896221093;5898.14476900847;5901.144334071621;5902.940970590239;5904.61619994699;5491.28613280271;5573.080441727151;5577.609567330329;5672.801451949162;5673.602847800615;5041.6722913010535;5066.417362003417;5096.872578185501;5096.9490305097215;5102.030614183293;6092.208012958287;6169.537284703575;6177.69111544421;6177.84555386119;6179.0422484514975;5747.453991870766;5793.217915581343;5862.062070068769;5862.794802181903;5877.823665290048;6064.818055458343;6108.847278817887;6110.602810817282;6116.480099191618;6119.8776024478375;5737.655740655574;5769.91345129815;5771.047777116729;5773.191753154478;5776;5690.459406356389;5768.7404066817935;5777.62445441045;5787.710614469344;5817.883642327214;5590.636608620427;5607.663540187602;5620.213170156099;5621.550275977994;5625.718714064198;5567.4432556744005;5571.9442805571425;5619;5631.408702086222;5641.915371269352;5503.174523821299;5556.527695145796;5557.5553955682635;5569.415211402745;5587.385387607441;5705.087186050212;5707.571932105192;5708.400617935057;5709.20071190042;5723.7424315905055;5427.538659214011;5549.223108764857;5607.579431542732;5613.073842815889;5618.04493236159 04:28:46 INFO - PROCESS | 4660 | |1;ext.html;669.2905520148643;684.6713577482808;687.6218080055968;737.0234439367836;748.985020299594;33745.66254337456;34259.31481370374;34263.657363426355;34269.828650856776;34278.65721342789;34360.312793744175;41551.37672934901;42045.159096818046;42179.68011759352;42192;8744.78138046549;8923.866142007875;8924.286057115416;8927.732168034936;8930.330225233101;8347.666093356289;8799.516026618534;8913.910860891405;10326.638567650134;10500.424936259584;10714.571417143345;10748.516316765725;10813.297135686194;10813.405262710548;10819.621313254038;10584.206184536151;10695.786084278348;10698.197635177316;10699.732506687356;10703.785924281548;10545.683629491128;10657.094146997515;10668.30656007357;10670.413127277996;10676.252662313642;41165.58834411648;41580;44332.556674433166;44378.77810610926;44466.555334446566;2817.1970988268245;3102.255458689921;3837.175007373428;3839.827207775657;3960.980195099035;32006;32338.35323293521;32856.50715239273;32941.50587741186;33189.83405082959;4455.6212721918355;4508.50406455293;4511.300748384006;4519.502854685982;4532.252178390561;312106;313342;314248;315064;315706.42146789347;40600.59399405968;61682.69158654268;62605.68697156576;62609.68695156586;62626.37373626205;38374.424383634265;38387.42418863719;38408.42387364192;38503.422448662735;38527.6147238524;16995.575110622332;17236.931568211643;17248.310067597406;17252.827471725122;17259;14153.221572813594;14159.50441734555;14244.216568088646;14288.499902503363;14294.571162865132;9132.634694612274;9321.906780932104;9554.140127388568;9751.951240243754;9813.852792208123;3670.22925185714;3791.0711875590105;3810.714196435223;3823.9808800955816;3834.827432765532;1135.5401062569672;1148.2192109365712;1194.9962031893251;1219.1587804414926;1221.2489319068886;33161.83419082889;33537.66462335394;33625;33819.323613527595;33968.98093057212;1198.6404078776332;1279.9680007999689;1283;1301.0632344711792;1401.5374926274305;16801.15994200285;18032.729509057375;18399.96427384086;19027.619447611247;19324.32364867223;455549;459081;459603;459676;460536;62946;63761.36238637647;63834.36165638284;63846.36153638497;63874.361256386845;1437.9928100359432;1488.970220595582;1507.7210716017353;1575.2911189964561;1617.943371981975;31259.53110703296;32783.34433311368;33330.83334583312;33603.99188024363;33839.83080084584;51627;51882.481175187764;52379.73810130925;52451.73774131257;52458;353972;356085;357813;358268;358671;27044.45911081728;28888.711112888603;29574.190451907034;29733.10800675983;29798.553021705124;42825.571744282155;43377;43743.34384984164;43777.781111095515;43841.23453357477;35566.288674225856;37173.256534869695;37203.441948371335;37380.43929341117;37497.4375384364;36302.8184859074;36509.817450912575;36543;37158.25683486369;38528.2294354117;40607.3908891361;41026.38460423036;41096.178076438904;41434.171316572894;41650;33752.32495350128;35569.64430355663;35625.287494250486;36440.81779591085;36647.08382290357;27941.72058279391;28138.85930570334;28148.01481948122;28366.14901552957;28369.858150709115 04:28:46 INFO - PROCESS | 4660 | |2;jquery.html;425.50216246991016;427.11587014879194;437.35490152025767;438.60297560086144;451.066292773958;19262;19520.90239548802;20301.695474567874;20394.490137746565;20435.693464598044;15335.84664153361;17092.914535427335;19498.610027799434;19652.312169074085;19719.704204436945;5491.697956612385;5724.055530837417;5747.195392645033;5983.311919129313;6012.248468941382;5733.340665823421;5734.713264336788;5745.339285982102;5766.596338256324;5773.826785196451;3976.602339766029;5705.315362156547;5955.255593050869;5955.88088238235;5957.434043765836;5731.226284451587;5879.617824841393;5894.793682221145;5895.557833162487;5914.704264786765;5746.425357464262;5752.654840709571;5780.104083866965;5782.624129431573;5786;13417.530386436429;13638.454461821515;14829.480968166063;22690.886545567333;23903.760962390326;2488.5271798358253;2616.175904590067;2653.283613424384;2790.4000639862325;3082.6300843898653;10099.596016159345;10181.592736290539;10490.108340791043;12319.199252048587;14769.483068092673;1932.3623204342673;2057.5781964697203;2163.3401812447355;2176.749673787503;2273.624851899435;74308.62845685791;79441;80231.19768802296;86131.13868861231;87827.12172878253;11281.492332845042;11417.657470275904;11726.765464690658;11789;11988.100892432927;16726.16369181536;16951.49145525636;16962.830371696124;16970.745438818427;16985.830141698672;8807.735767926972;8966.68616598416;9085.13691199335;9133.269338452908;9170.266378689688;8865.650602590318;8932.687355942511;8933.865992010125;8984.55077246135;9021.50381728998;5075.111855425286;5110.948890511122;5142.151544995034;5185.634578853665;5195.376554813447;2012.577358754678;2015.8790472571693;2035.9389218323474;2052.5176583502866;2064.4426004978573;941.6986564299358;972.941623502578;983.9704808855746;1027.0551092994463;1055.8680164979378;19033.80966190348;19148.61702765937;19169.61660766805;19172.13725382361;19498.220071197276;970.4613939263635;984.5963155106372;1022.6676330192718;1028.3829702178632;1063.0591926145416;10516.211284153718;10855.185861060452;10903.890961090447;10905.83641245382;11046.889531104585;467340;482531;497357;500010;500465;85515;88759;88846.11153888507;91500.54249728842;92338.53830730803;1136.9488373023398;1137.2835113878368;1150.4707834396272;1166.988330116705;1182.0366401382846;18226.36207732723;18265.269389224282;18268.543286417946;18300;18628.254869805056;40326.798366009156;41102.79448602738;41203.38194927019;41796.3730544036;42140;56088.43911560832;59950;60131.39868601258;60270.08244464096;61006.69496652488;8484.915150848412;8677.435966662148;8686.087960764029;8851.822963540822;8900.154485323885;9474.95262523683;9530.903946046294;9549;9708.951455242679;9735.659251926148;8507.361947853811;8668.869966950628;8726.69456569017;8740.868886966573;8965;8445.746627601182;8469.280111190617;8602.18279263469;8669.60986755585;8673.30613550927;8756.168164024408;8804.691835785718;8810.295176386002;8962.193402593797;9360;12803;13260.270685112411;13260.53588124411;13292.335383230606;13592.592222233348;12950.93524532409;13022.413991370222;13087.345632718516;13102.410391532214;13206.207627541993 04:28:46 INFO - PROCESS | 4660 | |3;mootools.html;1409.5489443378124;1526.770984352347;1530.8698760605348;1756.780402449693;1898.5063883390324;8216.58917054148;8447.155284471557;8452.830943381145;8466.322694184466;8476.957615211932;8336.666533338659;8529.189726975943;8540.786480337994;8545.743627691163;8560.48637081774;2363.090210269046;2364.8226383021342;2366.4912043910554;2389.7490763469846;2395.568797616428;2255.165588732163;2302.9981957848304;2311.2141871763597;2311.4221444638843;2317.965230521535;2395.568797616428;2409.650600662903;2414.14297924237;2439.0853429963763;2455.5579995600783;2345.5074434368803;2353.5175289065787;2366.6095094309503;2367.83425160238;2400.4238982644215;2352.564775516518;2444.7310795812446;2454.521368333176;2455.4475243070347;2459.4712136890653;7275.090613673291;8138.226868447488;8657.696980605711;8691.739247822576;8700.956495217484;5120.231965205212;5195.766190521435;5219.791208351699;5229.686218826844;5231.398389185254;3396.8811091611674;3404.2000129969438;3443.5695538057744;3493.545839040934;3500.859965601373;1131.4173200801595;1150.8676502202186;1152.9538818447336;1164.1210885781265;1173.9236949598248;10776.084032857216;10913.563457461692;10948.726281842937;11235.494402751896;11264.042556382636;4428.889277768081;4432.268675668479;4459.598636122764;4509.977450112729;4510.436195475566;9832;9839.852402213972;9846.507674616383;9857.408555486694;9869.605215791287;5130.102232109441;5145.871353216199;5147.97426012875;5149.639525233272;5150.1502252127975;4682.578567928903;4722.929156062662;4725.5983241424865;4729.266963620645;4729.385179926588;3897.376419772823;4030.879073627796;4047.1096358801606;4059.7361171523758;4064.6545043671617;1255.610760664197;1261.703499677575;1292.0567985370733;1304.6542666193466;1309.1817613991254;896.4038914122166;1029.7219750667427;1035.1822060572063;1039.2361614213546;1047.9371237725761;5906.852328691817;5944.494717949023;5952.613080149777;6013.609115407485;6112.174856394418;465.7903943225562;467.14512442230637;477.61313336197793;480.6563307235343;480.71637733737265;3966.3852102924093;4000.739951903175;4053.4527838741983;4063.9390409143884;4075.1849630074103;10504.317219380715;10646.520906559068;10759.677209683721;10767.6231331903;10961.397123158304;10652.414117223472;10805.351678899291;10859.40273284961;10865.076468500109;10868.347899126078;8058.476199047043;8155.755327340189;8192.713255036046;8238;8348.66605335792;5678.0915053591225;5745.023346031268;5778.94221057784;5866.384029676822;6005.669688167192;8679.17547832955;8849.91150088491;8915.821683566423;8937.508437036025;9476.857847132436;8929.955350223207;9089;9103.635854565744;9112.316576256675;9124.087591241088;6965.094935606954;7429.777106686808;7571.734989275349;7573.166951635206;7581.355584775356;7555.584442855476;7582.696692132253;7595.126560445619;7596;7601.277878601524;6969.895451568129;7114.21743608213;7123.252058533986;7211.531250468913;7233.782986510413;6709.161354830646;6740.730370785113;6835.839974434282;6879.6560171992205;7118.85762284741;6496.512761542808;6523.282438931808;6536.444402225864;6539.869202616016;6555.049517819984;4083.959160408358;4906.190478570996;4939;4948.802047918043;4974.253861920741;4733.928991065206;4808.182608956556;4830.589399900907;4842.080004799077;4881.145799485147 04:28:46 INFO - PROCESS | 4660 | |4;prototype.html;329.8713501734324;338.59537852266544;339.3823241700106;344.9879254226101;347.3348537550591;6102.908456373147;6143.969280153598;6150.046742754869;6151.846203844906;6160.876782464348;5879.500242479384;5997.100434934762;6029.969850150754;6084.574079814403;6165.599236049654;4711.246200607903;4731.952680473203;4735.50277220892;4736.218523943545;4745.8101675933;4741.336212930192;4754.857354279377;4757.809687612491;4758.595519380857;4765.166095933215;4571.862844114682;4586.151561961031;4719.103370359638;4723.456802467708;4725.740084295362;4676.883077923079;4694.671373003891;4705.576498115169;4706.811727530895;4710.481846996845;4151.97924010378;4152.1695660867945;4152.6055024772595;4153.231652144363;4158.355454904495;5905.114232865061;5921.348651648315;5924.555658325643;5937.317208521033;5955.464008239236;5460.481254280877;5464.672119672792;5465.098258787335;5466.1527463243265;5474.945250547484;6280.434760871498;6387.105805187278;6425.742970281183;6426.036094585803;6440.194975628046;4588.105319462706;4598.839040633595;4600.010997635524;4609.009063051427;4615.353850460938;6688.163979502562;6697.062411262427;6705.396514313687;6708.262091169968;6710.228323742735;4708.293755936637;4714.622830173578;4715.174844402216;4715.834945776881;4719.882002949885;4769.976150119227;4771.212749896228;4772.522747725269;4775.5463230993;4775.737334446639;5187.299714538564;5333.120035194227;5445.727713614304;5458.153986132076;5460.6996615186545;6412.6793660317735;6415.647139407284;6431.614103153825;6448.355164483514;6459.160309159779;1562.0393458023234;1570.2541292885787;1593.211360376621;1623.1478473801349;1625.4310991153002;1048.9370637761758;1063.3672964586058;1063.441693111123;1071.132382769959;1071.2394200118003;6201.7519299228425;6217.253929528486;6221.8133455995485;6227.221597300338;6230.252369715663;6607.273199948002;6627.436667883284;6639.535232533773;6642.169728783902;6642.535022548472;6252.843678907972;6267.310595834455;6284.183056202756;6293.622382657055;6296.905546416808;3539.415996360623;3575.66031227033;3579.1231148368297;3581.3374525712825;3585.372559802024;6738.898916516257;6890.138732658418;6912.896306555405;6917.515773895779;6959.269276725972;6738.898916516257;6770.796876093725;6775.525713200127;6777.695003724844;6779.593224406552;6922.5154239202775;6929.3070692930305;6935.653217339112;6940.444764418834;6971.65141742921;6615.6030638161865;6616.569922954993;6616.735330586723;6627.966860165669;6633.1376920999965;6339.175907132043;6510.153680021567;6518.57629254097;6529.151210342625;6541.051547525677;6917.204521480095;6930.237673855771;6934.895976560457;6941.826454338682;6943.826404339932;4583.518730533246;4586.357909892685;4591.77041147948;4595.126925884071;4595.1958407277925;6437.903431448625;6613.305602911625;6636.070950066898;6637.402633762985;6639.933600663931;6360.363963603602;6544.181977252843;6558.967205163944;6568.540202185801;6568.540202185801;6500.447461965786;6521.67391630426;6534.183227096613;6549.836254093686;6551.082848401323;6425.132607097982;6431.903521447276;6434.581752186093;6441.033844923299;6441.903371449338;6453.967730161319;6577.50668699859;6585.012248162814;6591.703373348307;6606.975918732604;6425.839354016187;6449.193850768654;6458.870822583616;6469.547131700736;6469.5794773339585 04:28:46 INFO - PROCESS | 4660 | |5;yui.html;803.9316658084063;852.6589364254302;859.6131740716675;907.6017835315309;921.847895097309;15003;15086.3211155498;15239.466618668348;15287.006344587622;15291.847081529182;13067.281299528522;13091.214527128379;13093.214407135554;13102.868971310285;13108.147970381919;743.4238465189468;881.9338549608773;900.0594378874092;907.519014922089;908.5457271364318;893.7854914820429;903.0337538833451;904.2449554621899;904.7647611620993;904.8552231642939;934.9018353072897;937.985930211044;938.2353381993653;938.3853575907757;938.6526985015554;791.9089304729944;933.6452148183714;936.5223735894675;943.0192599696308;944.4852555357347;698.451715403411;698.5773606967762;698.8357735932052;700.0619170311807;701.4738945790657;13661.453541858315;13696.65758356049;13699.178049316974;13710.177389356571;13713.794293085612;13377.398017089254;13876.653083672887;13914.860851391457;13949;14309.212993285366;11913.761724765543;12007.699807504881;12068.275903445821;12113.636590902199;12181.756364872741;4608.101420223058;4686.2267725825195;4754.263089221176;4864.440589332201;4864.805407783684;57415.712921435545;57644.71177644085;57697.711511442176;58060.70969645167;58062.41937580612;10479.580816767244;10797.352158870493;10862.837057444145;11870.169088163919;11962.940185299018;19901.502462438264;19992.50018749543;20029.799702002794;20055.899720501304;20082;8109.499742547529;8136.349092072738;8313.45962512447;8331.291840193651;8416.705415310435;4878.560929516289;5020.648554601216;5075.289459475676;5084.059449001947;5194.844154675366;3553.591336996227;3636.781793092423;3646.3801153803915;3649.033006253345;3650.0327413235514;580.5471732049009;588.9546055750991;627.8932581461163;634.4448607468465;670.0485310858593;719.6833393306928;724.4059870905807;774.5120574038432;823.185046803672;845.5518575155178;9947.850782238273;10147.797044059078;10203.94898025505;10208.744781380376;10236.846447303296;708.8759467093238;782.5343920367372;793.476305638276;812.3217113710118;836.6946064686346;4494.348319493655;4550.317452382169;4556.407667003268;4710.517978774018;4809.350737650442;60653;64154.35845641484;64268.35731642717;64371.356286437476;64467.355326447076;56453.43546564564;56866;57046.42953570494;57469;57503.71248143816;49277;49613.25580116301;49695.751521242164;49899.25151122736;50070.499295007314;9592.9040709592;9720.416774993522;9727.234661934503;9751.3661611995;9966.551505182138;37389.25221495609;38083;39292.41061384024;39885.2022959545;40179;45976.08047839091;46270.53729462797;47398.28902566395;51744.741276293375;51805.22292165545;12173.391330433265;12261;12476.43856026499;13323;13512.121712088683;14044.297785110908;14254.287285635884;14566.126032438087;14896.62758431048;15260.313285901939;12685.619431417072;13497.190168589916;13629.795553066513;13853.86146138566;14068.366923488264;13566.457341706617;13591.116577422436;13744.244066576433;13756.65608359799;14071.155730656194;13478.86521134776;13649.931750341184;13724.794128087888;13795.724085518033;14319;11220.130557163831;11330.60342887995;11369.602063927723;11495.425228738362;11568.235018142623;10643.840342394715;10659.786804263716;10786.676399708022;10816.945915270373;10973.341599504056 04:28:46 INFO - PROCESS | 4660 | __end_tp_report 04:28:46 INFO - PROCESS | 4660 | __start_cc_report 04:28:46 INFO - PROCESS | 4660 | _x_x_mozilla_cycle_collect,787 04:28:46 INFO - PROCESS | 4660 | __end_cc_report 04:28:46 INFO - PROCESS | 4660 | __startTimestamp1468754926906__endTimestamp 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | ------- Summary: start ------- 04:28:46 INFO - PROCESS | 4660 | Number of tests: 6 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#0] dojo.html Cycles:165 Average:5410.45 Median:5609.27 stddev:592.91 (10.6%) stddev-sans-first:571.12 04:28:46 INFO - PROCESS | 4660 | Values: 3298.9 3348.9 3371.7 3377.6 3392.3 5478.9 5479.5 5499.9 5514.6 5520.0 5468.3 5498.3 5505.9 5509.4 5550.0 4870.4 4980.4 5058.2 5102.7 5131.2 4818.3 4935.6 4956.3 4963.9 4967.2 4887.7 4930.3 4961.6 4963.8 5009.5 4990.5 5004.7 5006.6 5006.8 5026.1 4856.4 4909.4 4929.1 4961.2 4992.6 5244.2 5260.7 5264.5 5302.7 5313.4 5773.8 5809.0 5812.3 5813.8 5814.9 5774.8 5805.7 5817.1 5830.1 5858.2 5486.3 5491.9 5534.7 5535.9 5536.5 5663.6 5979.1 6016.5 6017.1 6018.2 5656.3 5692.0 5705.6 5709.0 5740.0 5755.7 5759.8 5772.0 5777.1 5783.6 5622.3 5654.2 5693.3 5703.0 5726.1 5623.3 5630.4 5640.9 5644.7 5672.3 4364.5 4366.7 4369.7 4369.9 4396.7 3920.7 3949.2 3965.0 3973.9 3985.7 5598.0 5598.7 5609.3 5610.4 5624.9 5878.1 5898.1 5901.1 5902.9 5904.6 5491.3 5573.1 5577.6 5672.8 5673.6 5041.7 5066.4 5096.9 5096.9 5102.0 6092.2 6169.5 6177.7 6177.8 6179.0 5747.5 5793.2 5862.1 5862.8 5877.8 6064.8 6108.8 6110.6 6116.5 6119.9 5737.7 5769.9 5771.0 5773.2 5776.0 5690.5 5768.7 5777.6 5787.7 5817.9 5590.6 5607.7 5620.2 5621.6 5625.7 5567.4 5571.9 5619.0 5631.4 5641.9 5503.2 5556.5 5557.6 5569.4 5587.4 5705.1 5707.6 5708.4 5709.2 5723.7 5427.5 5549.2 5607.6 5613.1 5618.0 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#1] ext.html Cycles:180 Average:53802.31 Median:196719.14 stddev:100672.98 (51.2%) stddev-sans-first:100876.36 04:28:46 INFO - PROCESS | 4660 | Values: 669.3 684.7 687.6 737.0 749.0 33745.7 34259.3 34263.7 34269.8 34278.7 34360.3 41551.4 42045.2 42179.7 42192.0 8744.8 8923.9 8924.3 8927.7 8930.3 8347.7 8799.5 8913.9 10326.6 10500.4 10714.6 10748.5 10813.3 10813.4 10819.6 10584.2 10695.8 10698.2 10699.7 10703.8 10545.7 10657.1 10668.3 10670.4 10676.3 41165.6 41580.0 44332.6 44378.8 44466.6 2817.2 3102.3 3837.2 3839.8 3961.0 32006.0 32338.4 32856.5 32941.5 33189.8 4455.6 4508.5 4511.3 4519.5 4532.3 312106.0 313342.0 314248.0 315064.0 315706.4 40600.6 61682.7 62605.7 62609.7 62626.4 38374.4 38387.4 38408.4 38503.4 38527.6 16995.6 17236.9 17248.3 17252.8 17259.0 14153.2 14159.5 14244.2 14288.5 14294.6 9132.6 9321.9 9554.1 9752.0 9813.9 3670.2 3791.1 3810.7 3824.0 3834.8 1135.5 1148.2 1195.0 1219.2 1221.2 33161.8 33537.7 33625.0 33819.3 33969.0 1198.6 1280.0 1283.0 1301.1 1401.5 16801.2 18032.7 18400.0 19027.6 19324.3 455549.0 459081.0 459603.0 459676.0 460536.0 62946.0 63761.4 63834.4 63846.4 63874.4 1438.0 1489.0 1507.7 1575.3 1617.9 31259.5 32783.3 33330.8 33604.0 33839.8 51627.0 51882.5 52379.7 52451.7 52458.0 353972.0 356085.0 357813.0 358268.0 358671.0 27044.5 28888.7 29574.2 29733.1 29798.6 42825.6 43377.0 43743.3 43777.8 43841.2 35566.3 37173.3 37203.4 37380.4 37497.4 36302.8 36509.8 36543.0 37158.3 38528.2 40607.4 41026.4 41096.2 41434.2 41650.0 33752.3 35569.6 35625.3 36440.8 36647.1 27941.7 28138.9 28148.0 28366.1 28369.9 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#2] jquery.html Cycles:180 Average:28859.49 Median:233895.53 stddev:80731.43 (34.5%) stddev-sans-first:80929.68 04:28:46 INFO - PROCESS | 4660 | Values: 425.5 427.1 437.4 438.6 451.1 19262.0 19520.9 20301.7 20394.5 20435.7 15335.8 17092.9 19498.6 19652.3 19719.7 5491.7 5724.1 5747.2 5983.3 6012.2 5733.3 5734.7 5745.3 5766.6 5773.8 3976.6 5705.3 5955.3 5955.9 5957.4 5731.2 5879.6 5894.8 5895.6 5914.7 5746.4 5752.7 5780.1 5782.6 5786.0 13417.5 13638.5 14829.5 22690.9 23903.8 2488.5 2616.2 2653.3 2790.4 3082.6 10099.6 10181.6 10490.1 12319.2 14769.5 1932.4 2057.6 2163.3 2176.7 2273.6 74308.6 79441.0 80231.2 86131.1 87827.1 11281.5 11417.7 11726.8 11789.0 11988.1 16726.2 16951.5 16962.8 16970.7 16985.8 8807.7 8966.7 9085.1 9133.3 9170.3 8865.7 8932.7 8933.9 8984.6 9021.5 5075.1 5110.9 5142.2 5185.6 5195.4 2012.6 2015.9 2035.9 2052.5 2064.4 941.7 972.9 984.0 1027.1 1055.9 19033.8 19148.6 19169.6 19172.1 19498.2 970.5 984.6 1022.7 1028.4 1063.1 10516.2 10855.2 10903.9 10905.8 11046.9 467340.0 482531.0 497357.0 500010.0 500465.0 85515.0 88759.0 88846.1 91500.5 92338.5 1136.9 1137.3 1150.5 1167.0 1182.0 18226.4 18265.3 18268.5 18300.0 18628.3 40326.8 41102.8 41203.4 41796.4 42140.0 56088.4 59950.0 60131.4 60270.1 61006.7 8484.9 8677.4 8686.1 8851.8 8900.2 9475.0 9530.9 9549.0 9709.0 9735.7 8507.4 8668.9 8726.7 8740.9 8965.0 8445.7 8469.3 8602.2 8669.6 8673.3 8756.2 8804.7 8810.3 8962.2 9360.0 12803.0 13260.3 13260.5 13292.3 13592.6 12950.9 13022.4 13087.3 13102.4 13206.2 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#3] mootools.html Cycles:180 Average:5534.98 Median:2644.45 stddev:3055.67 (115.6%) stddev-sans-first:3048.51 04:28:46 INFO - PROCESS | 4660 | Values: 1409.5 1526.8 1530.9 1756.8 1898.5 8216.6 8447.2 8452.8 8466.3 8477.0 8336.7 8529.2 8540.8 8545.7 8560.5 2363.1 2364.8 2366.5 2389.7 2395.6 2255.2 2303.0 2311.2 2311.4 2318.0 2395.6 2409.7 2414.1 2439.1 2455.6 2345.5 2353.5 2366.6 2367.8 2400.4 2352.6 2444.7 2454.5 2455.4 2459.5 7275.1 8138.2 8657.7 8691.7 8701.0 5120.2 5195.8 5219.8 5229.7 5231.4 3396.9 3404.2 3443.6 3493.5 3500.9 1131.4 1150.9 1153.0 1164.1 1173.9 10776.1 10913.6 10948.7 11235.5 11264.0 4428.9 4432.3 4459.6 4510.0 4510.4 9832.0 9839.9 9846.5 9857.4 9869.6 5130.1 5145.9 5148.0 5149.6 5150.2 4682.6 4722.9 4725.6 4729.3 4729.4 3897.4 4030.9 4047.1 4059.7 4064.7 1255.6 1261.7 1292.1 1304.7 1309.2 896.4 1029.7 1035.2 1039.2 1047.9 5906.9 5944.5 5952.6 6013.6 6112.2 465.8 467.1 477.6 480.7 480.7 3966.4 4000.7 4053.5 4063.9 4075.2 10504.3 10646.5 10759.7 10767.6 10961.4 10652.4 10805.4 10859.4 10865.1 10868.3 8058.5 8155.8 8192.7 8238.0 8348.7 5678.1 5745.0 5778.9 5866.4 6005.7 8679.2 8849.9 8915.8 8937.5 9476.9 8930.0 9089.0 9103.6 9112.3 9124.1 6965.1 7429.8 7571.7 7573.2 7581.4 7555.6 7582.7 7595.1 7596.0 7601.3 6969.9 7114.2 7123.3 7211.5 7233.8 6709.2 6740.7 6835.8 6879.7 7118.9 6496.5 6523.3 6536.4 6539.9 6555.0 4084.0 4906.2 4939.0 4948.8 4974.3 4733.9 4808.2 4830.6 4842.1 4881.1 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#4] prototype.html Cycles:180 Average:5433.11 Median:6209.50 stddev:1626.93 (26.2%) stddev-sans-first:1585.76 04:28:46 INFO - PROCESS | 4660 | Values: 329.9 338.6 339.4 345.0 347.3 6102.9 6144.0 6150.0 6151.8 6160.9 5879.5 5997.1 6030.0 6084.6 6165.6 4711.2 4732.0 4735.5 4736.2 4745.8 4741.3 4754.9 4757.8 4758.6 4765.2 4571.9 4586.2 4719.1 4723.5 4725.7 4676.9 4694.7 4705.6 4706.8 4710.5 4152.0 4152.2 4152.6 4153.2 4158.4 5905.1 5921.3 5924.6 5937.3 5955.5 5460.5 5464.7 5465.1 5466.2 5474.9 6280.4 6387.1 6425.7 6426.0 6440.2 4588.1 4598.8 4600.0 4609.0 4615.4 6688.2 6697.1 6705.4 6708.3 6710.2 4708.3 4714.6 4715.2 4715.8 4719.9 4770.0 4771.2 4772.5 4775.5 4775.7 5187.3 5333.1 5445.7 5458.2 5460.7 6412.7 6415.6 6431.6 6448.4 6459.2 1562.0 1570.3 1593.2 1623.1 1625.4 1048.9 1063.4 1063.4 1071.1 1071.2 6201.8 6217.3 6221.8 6227.2 6230.3 6607.3 6627.4 6639.5 6642.2 6642.5 6252.8 6267.3 6284.2 6293.6 6296.9 3539.4 3575.7 3579.1 3581.3 3585.4 6738.9 6890.1 6912.9 6917.5 6959.3 6738.9 6770.8 6775.5 6777.7 6779.6 6922.5 6929.3 6935.7 6940.4 6971.7 6615.6 6616.6 6616.7 6628.0 6633.1 6339.2 6510.2 6518.6 6529.2 6541.1 6917.2 6930.2 6934.9 6941.8 6943.8 4583.5 4586.4 4591.8 4595.1 4595.2 6437.9 6613.3 6636.1 6637.4 6639.9 6360.4 6544.2 6559.0 6568.5 6568.5 6500.4 6521.7 6534.2 6549.8 6551.1 6425.1 6431.9 6434.6 6441.0 6441.9 6454.0 6577.5 6585.0 6591.7 6607.0 6425.8 6449.2 6458.9 6469.5 6469.6 04:28:46 INFO - PROCESS | 4660 | 04:28:46 INFO - PROCESS | 4660 | [#5] yui.html Cycles:180 Average:15529.62 Median:4698.37 stddev:17778.73 (378.4%) stddev-sans-first:17794.21 04:28:46 INFO - PROCESS | 4660 | Values: 803.9 852.7 859.6 907.6 921.8 15003.0 15086.3 15239.5 15287.0 15291.8 13067.3 13091.2 13093.2 13102.9 13108.1 743.4 881.9 900.1 907.5 908.5 893.8 903.0 904.2 904.8 904.9 934.9 938.0 938.2 938.4 938.7 791.9 933.6 936.5 943.0 944.5 698.5 698.6 698.8 700.1 701.5 13661.5 13696.7 13699.2 13710.2 13713.8 13377.4 13876.7 13914.9 13949.0 14309.2 11913.8 12007.7 12068.3 12113.6 12181.8 4608.1 4686.2 4754.3 4864.4 4864.8 57415.7 57644.7 57697.7 58060.7 58062.4 10479.6 10797.4 10862.8 11870.2 11962.9 19901.5 19992.5 20029.8 20055.9 20082.0 8109.5 8136.3 8313.5 8331.3 8416.7 4878.6 5020.6 5075.3 5084.1 5194.8 3553.6 3636.8 3646.4 3649.0 3650.0 580.5 589.0 627.9 634.4 670.0 719.7 724.4 774.5 823.2 845.6 9947.9 10147.8 10203.9 10208.7 10236.8 708.9 782.5 793.5 812.3 836.7 4494.3 4550.3 4556.4 4710.5 4809.4 60653.0 64154.4 64268.4 64371.4 64467.4 56453.4 56866.0 57046.4 57469.0 57503.7 49277.0 49613.3 49695.8 49899.3 50070.5 9592.9 9720.4 9727.2 9751.4 9966.6 37389.3 38083.0 39292.4 39885.2 40179.0 45976.1 46270.5 47398.3 51744.7 51805.2 12173.4 12261.0 12476.4 13323.0 13512.1 14044.3 14254.3 14566.1 14896.6 15260.3 12685.6 13497.2 13629.8 13853.9 14068.4 13566.5 13591.1 13744.2 13756.7 14071.2 13478.9 13649.9 13724.8 13795.7 14319.0 11220.1 11330.6 11369.6 11495.4 11568.2 10643.8 10659.8 10786.7 10816.9 10973.3 04:28:46 INFO - PROCESS | 4660 | -------- Summary: end -------- 04:28:46 INFO - PROCESS | 4660 | 04:28:47 INFO - TEST-INFO | 4660: exit 0 04:28:47 INFO - TEST-OK | dromaeo_css | took 1111903ms 04:28:47 INFO - TEST-START | kraken 04:28:47 INFO - operating with platform_type : linux_ 04:28:47 INFO - Initialising browser for kraken test... 04:28:47 INFO - TEST-INFO | started process 4863 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpts_oNX/profile http://localhost:54034/getInfo.html) 04:28:52 INFO - PROCESS | 4863 | __metrics Screen width/height:1600/1200 04:28:52 INFO - PROCESS | 4863 | colorDepth:24 04:28:52 INFO - PROCESS | 4863 | Browser inner width/height: 1024/697 04:28:52 INFO - PROCESS | 4863 | __metrics 04:28:53 INFO - PROCESS | 4863 | 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" 04:28:53 ERROR - PROCESS | 4863 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:28:54 INFO - TEST-INFO | 4863: exit 0 04:28:54 INFO - Browser initialized. 04:28:54 INFO - Running cycle 1/1 for kraken test... 04:28:54 INFO - TEST-INFO | started process 4950 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpts_oNX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:29:06 INFO - PROCESS | 4950 | RSS: Main: 139382784 04:29:06 INFO - PROCESS | 4950 | 04:29:08 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54034/tests/kraken/driver.html?testName=audio-beat-detection) 04:29:09 INFO - PROCESS | 4950 | RSS: Main: 198004736 04:29:09 INFO - PROCESS | 4950 | 04:29:12 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54034/tests/kraken/driver.html?testName=audio-dft) 04:29:12 INFO - PROCESS | 4950 | RSS: Main: 184279040 04:29:12 INFO - PROCESS | 4950 | 04:29:15 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54034/tests/kraken/driver.html?testName=audio-fft) 04:29:16 INFO - PROCESS | 4950 | RSS: Main: 273690624 04:29:16 INFO - PROCESS | 4950 | 04:29:18 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54034/tests/kraken/driver.html?testName=audio-oscillator) 04:29:19 INFO - PROCESS | 4950 | RSS: Main: 180248576 04:29:19 INFO - PROCESS | 4950 | 04:29:21 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54034/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:29:21 INFO - PROCESS | 4950 | RSS: Main: 178593792 04:29:21 INFO - PROCESS | 4950 | 04:29:24 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54034/tests/kraken/driver.html?testName=imaging-darkroom) 04:29:25 INFO - PROCESS | 4950 | RSS: Main: 213159936 04:29:25 INFO - PROCESS | 4950 | 04:29:28 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54034/tests/kraken/driver.html?testName=imaging-desaturate) 04:29:29 INFO - PROCESS | 4950 | RSS: Main: 212901888 04:29:29 INFO - PROCESS | 4950 | 04:29:32 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54034/tests/kraken/driver.html?testName=json-parse-financial) 04:29:32 INFO - PROCESS | 4950 | RSS: Main: 212246528 04:29:32 INFO - PROCESS | 4950 | 04:29:34 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54034/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:34 INFO - PROCESS | 4950 | RSS: Main: 196317184 04:29:34 INFO - PROCESS | 4950 | 04:29:36 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:37 INFO - PROCESS | 4950 | RSS: Main: 199348224 04:29:37 INFO - PROCESS | 4950 | 04:29:38 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:39 INFO - PROCESS | 4950 | RSS: Main: 210354176 04:29:39 INFO - PROCESS | 4950 | 04:29:42 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:42 INFO - PROCESS | 4950 | RSS: Main: 217391104 04:29:42 INFO - PROCESS | 4950 | 04:29:45 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:45 INFO - PROCESS | 4950 | RSS: Main: 214929408 04:29:45 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | Cycle 1(1): loaded http://localhost:54034/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54034/tests/kraken/driver.html?testName=ai-astar) 04:29:47 INFO - PROCESS | 4950 | RSS: Main: 274489344 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | __start_tp_report 04:29:47 INFO - PROCESS | 4950 | _x_x_mozilla_page_load 04:29:47 INFO - PROCESS | 4950 | _x_x_mozilla_page_load_details 04:29:47 INFO - PROCESS | 4950 | |i|pagename|runs| 04:29:47 INFO - PROCESS | 4950 | |0;ai-astar;111;109;110;111;111;111;106;110;109;110 04:29:47 INFO - PROCESS | 4950 | |1;audio-beat-detection;154;126;155;170;125;155;160;128;196;131 04:29:47 INFO - PROCESS | 4950 | |2;audio-dft;159;163;160;161;160;160;162;161;159;159 04:29:47 INFO - PROCESS | 4950 | |3;audio-fft;73;76;73;176;77;73;76;77;166;76 04:29:47 INFO - PROCESS | 4950 | |4;audio-oscillator;93;94;156;91;91;91;144;92;92;154 04:29:47 INFO - PROCESS | 4950 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;116;116 04:29:47 INFO - PROCESS | 4950 | |6;imaging-darkroom;145;145;145;146;145;145;146;145;145;145 04:29:47 INFO - PROCESS | 4950 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;95;95 04:29:47 INFO - PROCESS | 4950 | |8;json-parse-financial;54;55;54;54;54;56;60;77;53;54 04:29:47 INFO - PROCESS | 4950 | |9;json-stringify-tinderbox;57;59;57;58;58;57;57;57;58;57 04:29:47 INFO - PROCESS | 4950 | |10;stanford-crypto-aes;73;72;71;79;73;73;74;73;73;81 04:29:47 INFO - PROCESS | 4950 | |11;stanford-crypto-ccm;114;113;113;113;113;120;116;113;128;128 04:29:47 INFO - PROCESS | 4950 | |12;stanford-crypto-pbkdf2;163;164;160;163;160;158;158;169;157;160 04:29:47 INFO - PROCESS | 4950 | |13;stanford-crypto-sha256-iterative;68;69;67;66;67;78;65;67;67;66 04:29:47 INFO - PROCESS | 4950 | __end_tp_report 04:29:47 INFO - PROCESS | 4950 | __start_cc_report 04:29:47 INFO - PROCESS | 4950 | _x_x_mozilla_cycle_collect,1589 04:29:47 INFO - PROCESS | 4950 | __end_cc_report 04:29:47 INFO - PROCESS | 4950 | __startTimestamp1468754987893__endTimestamp 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | ------- Summary: start ------- 04:29:47 INFO - PROCESS | 4950 | Number of tests: 14 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.50 stddev:1.55 (1.4%) stddev-sans-first:1.58 04:29:47 INFO - PROCESS | 4950 | Values: 111.0 109.0 110.0 111.0 111.0 111.0 106.0 110.0 109.0 110.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#1] audio-beat-detection Cycles:10 Average:150.00 Median:155.00 stddev:22.92 (14.8%) stddev-sans-first:24.26 04:29:47 INFO - PROCESS | 4950 | Values: 154.0 126.0 155.0 170.0 125.0 155.0 160.0 128.0 196.0 131.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.35 (0.8%) stddev-sans-first:1.33 04:29:47 INFO - PROCESS | 4950 | Values: 159.0 163.0 160.0 161.0 160.0 160.0 162.0 161.0 159.0 159.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#3] audio-fft Cycles:10 Average:94.30 Median:76.00 stddev:40.52 (53.3%) stddev-sans-first:42.24 04:29:47 INFO - PROCESS | 4950 | Values: 73.0 76.0 73.0 176.0 77.0 73.0 76.0 77.0 166.0 76.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:91.50 stddev:28.84 (31.5%) stddev-sans-first:29.94 04:29:47 INFO - PROCESS | 4950 | Values: 93.0 94.0 156.0 91.0 91.0 91.0 144.0 92.0 92.0 154.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:29:47 INFO - PROCESS | 4950 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 04:29:47 INFO - PROCESS | 4950 | Values: 145.0 145.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:29:47 INFO - PROCESS | 4950 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:54.50 stddev:7.26 (13.3%) stddev-sans-first:7.62 04:29:47 INFO - PROCESS | 4950 | Values: 54.0 55.0 54.0 54.0 54.0 56.0 60.0 77.0 53.0 54.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#9] json-stringify-tinderbox Cycles:10 Average:57.50 Median:57.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 04:29:47 INFO - PROCESS | 4950 | Values: 57.0 59.0 57.0 58.0 58.0 57.0 57.0 57.0 58.0 57.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:73.00 stddev:3.19 (4.4%) stddev-sans-first:3.35 04:29:47 INFO - PROCESS | 4950 | Values: 73.0 72.0 71.0 79.0 73.0 73.0 74.0 73.0 73.0 81.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:115.00 stddev:6.15 (5.4%) stddev-sans-first:6.42 04:29:47 INFO - PROCESS | 4950 | Values: 114.0 113.0 113.0 113.0 113.0 120.0 116.0 113.0 128.0 128.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:161.50 stddev:3.61 (2.2%) stddev-sans-first:3.77 04:29:47 INFO - PROCESS | 4950 | Values: 163.0 164.0 160.0 163.0 160.0 158.0 158.0 169.0 157.0 160.0 04:29:47 INFO - PROCESS | 4950 | 04:29:47 INFO - PROCESS | 4950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:67.00 stddev:3.68 (5.5%) stddev-sans-first:3.91 04:29:47 INFO - PROCESS | 4950 | Values: 68.0 69.0 67.0 66.0 67.0 78.0 65.0 67.0 67.0 66.0 04:29:47 INFO - PROCESS | 4950 | -------- Summary: end -------- 04:29:47 INFO - PROCESS | 4950 | 04:29:48 INFO - TEST-INFO | 4950: exit 0 04:29:48 INFO - TEST-OK | kraken | took 61272ms 04:29:48 INFO - SUITE-END | took 1173s 04:29:49 INFO - Completed test suite (00:19:33) 04:29:49 INFO - javascript benchmark 04:29:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.9340213195733, 3348.945082299077, 3371.6796904294083, 3377.6284608693054, 3392.25370418508, 5478.890422191554, 5479.4794494523, 5499.862503437406, 5514.6139770216105, 5520.006398848211, 5468.28912241409, 5498.285222921015, 5505.889882202354, 5509.3664228613725, 5550.028744969634, 4870.391201099863, 4980.402351717799, 5058.2159765236265, 5102.744862756847, 5131.179011358184, 4818.34952281441, 4935.555799978001, 4956.306117143603, 4963.925541116868, 4967.205247160455, 4887.682300650446, 4930.309756634074, 4961.603071754251, 4963.776630051656, 5009.474005229435, 4990.500949905017, 5004.699718016913, 5006.6245031622775, 5006.849794506171, 5026.120428924942, 4856.39295088114, 4909.435414927293, 4929.112759703252, 4961.206207006898, 4992.550670439678, 5244.239585260154, 5260.710660913648, 5264.499872512143, 5302.73486325686, 5313.38896026958, 5773.797917072924, 5808.97095514524, 5812.273465816773, 5813.767449302023, 5814.854628634275, 5774.769009239625, 5805.709714514257, 5817.0983497557945, 5830.067189249742, 5858.209141765849, 5486.259354987065, 5491.945080549183, 5534.723263836792, 5535.861603459945, 5536.529395001429, 5663.603547751618, 5979.073243647242, 6016.548758843104, 6017.127516510169, 6018.21763170785, 5656.2929633795775, 5691.971540142273, 5705.62913410627, 5709.029464990961, 5740.024195886709, 5755.712214389264, 5759.827205183851, 5772.04761214395, 5777.075667893201, 5783.624064435799, 5622.32532096151, 5654.15187721845, 5693.316801983706, 5703.0, 5726.083826587809, 5623.325200975909, 5630.40880707528, 5640.943590564042, 5644.689542075143, 5672.319321681424, 4364.519902810688, 4366.694331396835, 4369.71596846204, 4369.912601748011, 4396.670249731283, 3920.725549211585, 3949.19041596472, 3964.960350396517, 3973.920521589552, 3985.661218796377, 5597.972010140004, 5598.664080155204, 5609.27079479665, 5610.410906854803, 5624.859378515488, 5878.088896221093, 5898.14476900847, 5901.144334071621, 5902.940970590239, 5904.61619994699, 5491.28613280271, 5573.080441727151, 5577.609567330329, 5672.801451949162, 5673.602847800615, 5041.6722913010535, 5066.417362003417, 5096.872578185501, 5096.9490305097215, 5102.030614183293, 6092.208012958287, 6169.537284703575, 6177.69111544421, 6177.84555386119, 6179.0422484514975, 5747.453991870766, 5793.217915581343, 5862.062070068769, 5862.794802181903, 5877.823665290048, 6064.818055458343, 6108.847278817887, 6110.602810817282, 6116.480099191618, 6119.8776024478375, 5737.655740655574, 5769.91345129815, 5771.047777116729, 5773.191753154478, 5776.0, 5690.459406356389, 5768.7404066817935, 5777.62445441045, 5787.710614469344, 5817.883642327214, 5590.636608620427, 5607.663540187602, 5620.213170156099, 5621.550275977994, 5625.718714064198, 5567.4432556744005, 5571.9442805571425, 5619.0, 5631.408702086222, 5641.915371269352, 5503.174523821299, 5556.527695145796, 5557.5553955682635, 5569.415211402745, 5587.385387607441, 5705.087186050212, 5707.571932105192, 5708.400617935057, 5709.20071190042, 5723.7424315905055, 5427.538659214011, 5549.223108764857, 5607.579431542732, 5613.073842815889, 5618.04493236159], "value": 5372.775057582117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.2905520148643, 684.6713577482808, 687.6218080055968, 737.0234439367836, 748.985020299594, 33745.66254337456, 34259.31481370374, 34263.657363426355, 34269.828650856776, 34278.65721342789, 34360.312793744175, 41551.37672934901, 42045.159096818046, 42179.68011759352, 42192.0, 8744.78138046549, 8923.866142007875, 8924.286057115416, 8927.732168034936, 8930.330225233101, 8347.666093356289, 8799.516026618534, 8913.910860891405, 10326.638567650134, 10500.424936259584, 10714.571417143345, 10748.516316765725, 10813.297135686194, 10813.405262710548, 10819.621313254038, 10584.206184536151, 10695.786084278348, 10698.197635177316, 10699.732506687356, 10703.785924281548, 10545.683629491128, 10657.094146997515, 10668.30656007357, 10670.413127277996, 10676.252662313642, 41165.58834411648, 41580.0, 44332.556674433166, 44378.77810610926, 44466.555334446566, 2817.1970988268245, 3102.255458689921, 3837.175007373428, 3839.827207775657, 3960.980195099035, 32006.0, 32338.35323293521, 32856.50715239273, 32941.50587741186, 33189.83405082959, 4455.6212721918355, 4508.50406455293, 4511.300748384006, 4519.502854685982, 4532.252178390561, 312106.0, 313342.0, 314248.0, 315064.0, 315706.42146789347, 40600.59399405968, 61682.69158654268, 62605.68697156576, 62609.68695156586, 62626.37373626205, 38374.424383634265, 38387.42418863719, 38408.42387364192, 38503.422448662735, 38527.6147238524, 16995.575110622332, 17236.931568211643, 17248.310067597406, 17252.827471725122, 17259.0, 14153.221572813594, 14159.50441734555, 14244.216568088646, 14288.499902503363, 14294.571162865132, 9132.634694612274, 9321.906780932104, 9554.140127388568, 9751.951240243754, 9813.852792208123, 3670.22925185714, 3791.0711875590105, 3810.714196435223, 3823.9808800955816, 3834.827432765532, 1135.5401062569672, 1148.2192109365712, 1194.9962031893251, 1219.1587804414926, 1221.2489319068886, 33161.83419082889, 33537.66462335394, 33625.0, 33819.323613527595, 33968.98093057212, 1198.6404078776332, 1279.9680007999689, 1283.0, 1301.0632344711792, 1401.5374926274305, 16801.15994200285, 18032.729509057375, 18399.96427384086, 19027.619447611247, 19324.32364867223, 455549.0, 459081.0, 459603.0, 459676.0, 460536.0, 62946.0, 63761.36238637647, 63834.36165638284, 63846.36153638497, 63874.361256386845, 1437.9928100359432, 1488.970220595582, 1507.7210716017353, 1575.2911189964561, 1617.943371981975, 31259.53110703296, 32783.34433311368, 33330.83334583312, 33603.99188024363, 33839.83080084584, 51627.0, 51882.481175187764, 52379.73810130925, 52451.73774131257, 52458.0, 353972.0, 356085.0, 357813.0, 358268.0, 358671.0, 27044.45911081728, 28888.711112888603, 29574.190451907034, 29733.10800675983, 29798.553021705124, 42825.571744282155, 43377.0, 43743.34384984164, 43777.781111095515, 43841.23453357477, 35566.288674225856, 37173.256534869695, 37203.441948371335, 37380.43929341117, 37497.4375384364, 36302.8184859074, 36509.817450912575, 36543.0, 37158.25683486369, 38528.2294354117, 40607.3908891361, 41026.38460423036, 41096.178076438904, 41434.171316572894, 41650.0, 33752.32495350128, 35569.64430355663, 35625.287494250486, 36440.81779591085, 36647.08382290357, 27941.72058279391, 28138.85930570334, 28148.01481948122, 28366.14901552957, 28369.858150709115], "value": 19453.684152020964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.50216246991016, 427.11587014879194, 437.35490152025767, 438.60297560086144, 451.066292773958, 19262.0, 19520.90239548802, 20301.695474567874, 20394.490137746565, 20435.693464598044, 15335.84664153361, 17092.914535427335, 19498.610027799434, 19652.312169074085, 19719.704204436945, 5491.697956612385, 5724.055530837417, 5747.195392645033, 5983.311919129313, 6012.248468941382, 5733.340665823421, 5734.713264336788, 5745.339285982102, 5766.596338256324, 5773.826785196451, 3976.602339766029, 5705.315362156547, 5955.255593050869, 5955.88088238235, 5957.434043765836, 5731.226284451587, 5879.617824841393, 5894.793682221145, 5895.557833162487, 5914.704264786765, 5746.425357464262, 5752.654840709571, 5780.104083866965, 5782.624129431573, 5786.0, 13417.530386436429, 13638.454461821515, 14829.480968166063, 22690.886545567333, 23903.760962390326, 2488.5271798358253, 2616.175904590067, 2653.283613424384, 2790.4000639862325, 3082.6300843898653, 10099.596016159345, 10181.592736290539, 10490.108340791043, 12319.199252048587, 14769.483068092673, 1932.3623204342673, 2057.5781964697203, 2163.3401812447355, 2176.749673787503, 2273.624851899435, 74308.62845685791, 79441.0, 80231.19768802296, 86131.13868861231, 87827.12172878253, 11281.492332845042, 11417.657470275904, 11726.765464690658, 11789.0, 11988.100892432927, 16726.16369181536, 16951.49145525636, 16962.830371696124, 16970.745438818427, 16985.830141698672, 8807.735767926972, 8966.68616598416, 9085.13691199335, 9133.269338452908, 9170.266378689688, 8865.650602590318, 8932.687355942511, 8933.865992010125, 8984.55077246135, 9021.50381728998, 5075.111855425286, 5110.948890511122, 5142.151544995034, 5185.634578853665, 5195.376554813447, 2012.577358754678, 2015.8790472571693, 2035.9389218323474, 2052.5176583502866, 2064.4426004978573, 941.6986564299358, 972.941623502578, 983.9704808855746, 1027.0551092994463, 1055.8680164979378, 19033.80966190348, 19148.61702765937, 19169.61660766805, 19172.13725382361, 19498.220071197276, 970.4613939263635, 984.5963155106372, 1022.6676330192718, 1028.3829702178632, 1063.0591926145416, 10516.211284153718, 10855.185861060452, 10903.890961090447, 10905.83641245382, 11046.889531104585, 467340.0, 482531.0, 497357.0, 500010.0, 500465.0, 85515.0, 88759.0, 88846.11153888507, 91500.54249728842, 92338.53830730803, 1136.9488373023398, 1137.2835113878368, 1150.4707834396272, 1166.988330116705, 1182.0366401382846, 18226.36207732723, 18265.269389224282, 18268.543286417946, 18300.0, 18628.254869805056, 40326.798366009156, 41102.79448602738, 41203.38194927019, 41796.3730544036, 42140.0, 56088.43911560832, 59950.0, 60131.39868601258, 60270.08244464096, 61006.69496652488, 8484.915150848412, 8677.435966662148, 8686.087960764029, 8851.822963540822, 8900.154485323885, 9474.95262523683, 9530.903946046294, 9549.0, 9708.951455242679, 9735.659251926148, 8507.361947853811, 8668.869966950628, 8726.69456569017, 8740.868886966573, 8965.0, 8445.746627601182, 8469.280111190617, 8602.18279263469, 8669.60986755585, 8673.30613550927, 8756.168164024408, 8804.691835785718, 8810.295176386002, 8962.193402593797, 9360.0, 12803.0, 13260.270685112411, 13260.53588124411, 13292.335383230606, 13592.592222233348, 12950.93524532409, 13022.413991370222, 13087.345632718516, 13102.410391532214, 13206.207627541993], "value": 9302.853844807345, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.5489443378124, 1526.770984352347, 1530.8698760605348, 1756.780402449693, 1898.5063883390324, 8216.58917054148, 8447.155284471557, 8452.830943381145, 8466.322694184466, 8476.957615211932, 8336.666533338659, 8529.189726975943, 8540.786480337994, 8545.743627691163, 8560.48637081774, 2363.090210269046, 2364.8226383021342, 2366.4912043910554, 2389.7490763469846, 2395.568797616428, 2255.165588732163, 2302.9981957848304, 2311.2141871763597, 2311.4221444638843, 2317.965230521535, 2395.568797616428, 2409.650600662903, 2414.14297924237, 2439.0853429963763, 2455.5579995600783, 2345.5074434368803, 2353.5175289065787, 2366.6095094309503, 2367.83425160238, 2400.4238982644215, 2352.564775516518, 2444.7310795812446, 2454.521368333176, 2455.4475243070347, 2459.4712136890653, 7275.090613673291, 8138.226868447488, 8657.696980605711, 8691.739247822576, 8700.956495217484, 5120.231965205212, 5195.766190521435, 5219.791208351699, 5229.686218826844, 5231.398389185254, 3396.8811091611674, 3404.2000129969438, 3443.5695538057744, 3493.545839040934, 3500.859965601373, 1131.4173200801595, 1150.8676502202186, 1152.9538818447336, 1164.1210885781265, 1173.9236949598248, 10776.084032857216, 10913.563457461692, 10948.726281842937, 11235.494402751896, 11264.042556382636, 4428.889277768081, 4432.268675668479, 4459.598636122764, 4509.977450112729, 4510.436195475566, 9832.0, 9839.852402213972, 9846.507674616383, 9857.408555486694, 9869.605215791287, 5130.102232109441, 5145.871353216199, 5147.97426012875, 5149.639525233272, 5150.1502252127975, 4682.578567928903, 4722.929156062662, 4725.5983241424865, 4729.266963620645, 4729.385179926588, 3897.376419772823, 4030.879073627796, 4047.1096358801606, 4059.7361171523758, 4064.6545043671617, 1255.610760664197, 1261.703499677575, 1292.0567985370733, 1304.6542666193466, 1309.1817613991254, 896.4038914122166, 1029.7219750667427, 1035.1822060572063, 1039.2361614213546, 1047.9371237725761, 5906.852328691817, 5944.494717949023, 5952.613080149777, 6013.609115407485, 6112.174856394418, 465.7903943225562, 467.14512442230637, 477.61313336197793, 480.6563307235343, 480.71637733737265, 3966.3852102924093, 4000.739951903175, 4053.4527838741983, 4063.9390409143884, 4075.1849630074103, 10504.317219380715, 10646.520906559068, 10759.677209683721, 10767.6231331903, 10961.397123158304, 10652.414117223472, 10805.351678899291, 10859.40273284961, 10865.076468500109, 10868.347899126078, 8058.476199047043, 8155.755327340189, 8192.713255036046, 8238.0, 8348.66605335792, 5678.0915053591225, 5745.023346031268, 5778.94221057784, 5866.384029676822, 6005.669688167192, 8679.17547832955, 8849.91150088491, 8915.821683566423, 8937.508437036025, 9476.857847132436, 8929.955350223207, 9089.0, 9103.635854565744, 9112.316576256675, 9124.087591241088, 6965.094935606954, 7429.777106686808, 7571.734989275349, 7573.166951635206, 7581.355584775356, 7555.584442855476, 7582.696692132253, 7595.126560445619, 7596.0, 7601.277878601524, 6969.895451568129, 7114.21743608213, 7123.252058533986, 7211.531250468913, 7233.782986510413, 6709.161354830646, 6740.730370785113, 6835.839974434282, 6879.6560171992205, 7118.85762284741, 6496.512761542808, 6523.282438931808, 6536.444402225864, 6539.869202616016, 6555.049517819984, 4083.959160408358, 4906.190478570996, 4939.0, 4948.802047918043, 4974.253861920741, 4733.928991065206, 4808.182608956556, 4830.589399900907, 4842.080004799077, 4881.145799485147], "value": 4438.416696025422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8713501734324, 338.59537852266544, 339.3823241700106, 344.9879254226101, 347.3348537550591, 6102.908456373147, 6143.969280153598, 6150.046742754869, 6151.846203844906, 6160.876782464348, 5879.500242479384, 5997.100434934762, 6029.969850150754, 6084.574079814403, 6165.599236049654, 4711.246200607903, 4731.952680473203, 4735.50277220892, 4736.218523943545, 4745.8101675933, 4741.336212930192, 4754.857354279377, 4757.809687612491, 4758.595519380857, 4765.166095933215, 4571.862844114682, 4586.151561961031, 4719.103370359638, 4723.456802467708, 4725.740084295362, 4676.883077923079, 4694.671373003891, 4705.576498115169, 4706.811727530895, 4710.481846996845, 4151.97924010378, 4152.1695660867945, 4152.6055024772595, 4153.231652144363, 4158.355454904495, 5905.114232865061, 5921.348651648315, 5924.555658325643, 5937.317208521033, 5955.464008239236, 5460.481254280877, 5464.672119672792, 5465.098258787335, 5466.1527463243265, 5474.945250547484, 6280.434760871498, 6387.105805187278, 6425.742970281183, 6426.036094585803, 6440.194975628046, 4588.105319462706, 4598.839040633595, 4600.010997635524, 4609.009063051427, 4615.353850460938, 6688.163979502562, 6697.062411262427, 6705.396514313687, 6708.262091169968, 6710.228323742735, 4708.293755936637, 4714.622830173578, 4715.174844402216, 4715.834945776881, 4719.882002949885, 4769.976150119227, 4771.212749896228, 4772.522747725269, 4775.5463230993, 4775.737334446639, 5187.299714538564, 5333.120035194227, 5445.727713614304, 5458.153986132076, 5460.6996615186545, 6412.6793660317735, 6415.647139407284, 6431.614103153825, 6448.355164483514, 6459.160309159779, 1562.0393458023234, 1570.2541292885787, 1593.211360376621, 1623.1478473801349, 1625.4310991153002, 1048.9370637761758, 1063.3672964586058, 1063.441693111123, 1071.132382769959, 1071.2394200118003, 6201.7519299228425, 6217.253929528486, 6221.8133455995485, 6227.221597300338, 6230.252369715663, 6607.273199948002, 6627.436667883284, 6639.535232533773, 6642.169728783902, 6642.535022548472, 6252.843678907972, 6267.310595834455, 6284.183056202756, 6293.622382657055, 6296.905546416808, 3539.415996360623, 3575.66031227033, 3579.1231148368297, 3581.3374525712825, 3585.372559802024, 6738.898916516257, 6890.138732658418, 6912.896306555405, 6917.515773895779, 6959.269276725972, 6738.898916516257, 6770.796876093725, 6775.525713200127, 6777.695003724844, 6779.593224406552, 6922.5154239202775, 6929.3070692930305, 6935.653217339112, 6940.444764418834, 6971.65141742921, 6615.6030638161865, 6616.569922954993, 6616.735330586723, 6627.966860165669, 6633.1376920999965, 6339.175907132043, 6510.153680021567, 6518.57629254097, 6529.151210342625, 6541.051547525677, 6917.204521480095, 6930.237673855771, 6934.895976560457, 6941.826454338682, 6943.826404339932, 4583.518730533246, 4586.357909892685, 4591.77041147948, 4595.126925884071, 4595.1958407277925, 6437.903431448625, 6613.305602911625, 6636.070950066898, 6637.402633762985, 6639.933600663931, 6360.363963603602, 6544.181977252843, 6558.967205163944, 6568.540202185801, 6568.540202185801, 6500.447461965786, 6521.67391630426, 6534.183227096613, 6549.836254093686, 6551.082848401323, 6425.132607097982, 6431.903521447276, 6434.581752186093, 6441.033844923299, 6441.903371449338, 6453.967730161319, 6577.50668699859, 6585.012248162814, 6591.703373348307, 6606.975918732604, 6425.839354016187, 6449.193850768654, 6458.870822583616, 6469.547131700736, 6469.5794773339585], "value": 4896.196871852234, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.9316658084063, 852.6589364254302, 859.6131740716675, 907.6017835315309, 921.847895097309, 15003.0, 15086.3211155498, 15239.466618668348, 15287.006344587622, 15291.847081529182, 13067.281299528522, 13091.214527128379, 13093.214407135554, 13102.868971310285, 13108.147970381919, 743.4238465189468, 881.9338549608773, 900.0594378874092, 907.519014922089, 908.5457271364318, 893.7854914820429, 903.0337538833451, 904.2449554621899, 904.7647611620993, 904.8552231642939, 934.9018353072897, 937.985930211044, 938.2353381993653, 938.3853575907757, 938.6526985015554, 791.9089304729944, 933.6452148183714, 936.5223735894675, 943.0192599696308, 944.4852555357347, 698.451715403411, 698.5773606967762, 698.8357735932052, 700.0619170311807, 701.4738945790657, 13661.453541858315, 13696.65758356049, 13699.178049316974, 13710.177389356571, 13713.794293085612, 13377.398017089254, 13876.653083672887, 13914.860851391457, 13949.0, 14309.212993285366, 11913.761724765543, 12007.699807504881, 12068.275903445821, 12113.636590902199, 12181.756364872741, 4608.101420223058, 4686.2267725825195, 4754.263089221176, 4864.440589332201, 4864.805407783684, 57415.712921435545, 57644.71177644085, 57697.711511442176, 58060.70969645167, 58062.41937580612, 10479.580816767244, 10797.352158870493, 10862.837057444145, 11870.169088163919, 11962.940185299018, 19901.502462438264, 19992.50018749543, 20029.799702002794, 20055.899720501304, 20082.0, 8109.499742547529, 8136.349092072738, 8313.45962512447, 8331.291840193651, 8416.705415310435, 4878.560929516289, 5020.648554601216, 5075.289459475676, 5084.059449001947, 5194.844154675366, 3553.591336996227, 3636.781793092423, 3646.3801153803915, 3649.033006253345, 3650.0327413235514, 580.5471732049009, 588.9546055750991, 627.8932581461163, 634.4448607468465, 670.0485310858593, 719.6833393306928, 724.4059870905807, 774.5120574038432, 823.185046803672, 845.5518575155178, 9947.850782238273, 10147.797044059078, 10203.94898025505, 10208.744781380376, 10236.846447303296, 708.8759467093238, 782.5343920367372, 793.476305638276, 812.3217113710118, 836.6946064686346, 4494.348319493655, 4550.317452382169, 4556.407667003268, 4710.517978774018, 4809.350737650442, 60653.0, 64154.35845641484, 64268.35731642717, 64371.356286437476, 64467.355326447076, 56453.43546564564, 56866.0, 57046.42953570494, 57469.0, 57503.71248143816, 49277.0, 49613.25580116301, 49695.751521242164, 49899.25151122736, 50070.499295007314, 9592.9040709592, 9720.416774993522, 9727.234661934503, 9751.3661611995, 9966.551505182138, 37389.25221495609, 38083.0, 39292.41061384024, 39885.2022959545, 40179.0, 45976.08047839091, 46270.53729462797, 47398.28902566395, 51744.741276293375, 51805.22292165545, 12173.391330433265, 12261.0, 12476.43856026499, 13323.0, 13512.121712088683, 14044.297785110908, 14254.287285635884, 14566.126032438087, 14896.62758431048, 15260.313285901939, 12685.619431417072, 13497.190168589916, 13629.795553066513, 13853.86146138566, 14068.366923488264, 13566.457341706617, 13591.116577422436, 13744.244066576433, 13756.65608359799, 14071.155730656194, 13478.86521134776, 13649.931750341184, 13724.794128087888, 13795.724085518033, 14319.0, 11220.130557163831, 11330.60342887995, 11369.602063927723, 11495.425228738362, 11568.235018142623, 10643.840342394715, 10659.786804263716, 10786.676399708022, 10816.945915270373, 10973.341599504056], "value": 7162.810446895712, "unit": "score"}], "name": "dromaeo_css", "value": 7300.252218997011, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 110.0, 111.0, 111.0, 111.0, 106.0, 110.0, 109.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 155.0, 170.0, 125.0, 155.0, 160.0, 128.0, 196.0, 131.0], "value": 150.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 160.0, 161.0, 160.0, 160.0, 162.0, 161.0, 159.0, 159.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 73.0, 176.0, 77.0, 73.0, 76.0, 77.0, 166.0, 76.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 156.0, 91.0, 91.0, 91.0, 144.0, 92.0, 92.0, 154.0], "value": 109.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 56.0, 60.0, 77.0, 53.0, 54.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 58.0, 58.0, 57.0, 57.0, 57.0, 58.0, 57.0], "value": 57.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 71.0, 79.0, 73.0, 73.0, 74.0, 73.0, 73.0, 81.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 120.0, 116.0, 113.0, 128.0, 128.0], "value": 117.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 160.0, 163.0, 160.0, 158.0, 158.0, 169.0, 157.0, 160.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 66.0, 67.0, 78.0, 65.0, 67.0, 67.0, 66.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1516.2, "alertThreshold": 2.0}]} 04:29:49 INFO - Return code: 0 04:29:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:29:49 ERROR - # TBPL SUCCESS # 04:29:49 INFO - Running post-action listener: _resource_record_post_action 04:29:49 INFO - Running post-run listener: _resource_record_post_run 04:29:50 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 28672; Write bytes: 285429760; Read time: 64; Write time: 218732 04:29:50 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 69382144; Read time: 0; Write time: 68428 04:29:50 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 216047616; Read time: 12; Write time: 150304 04:29:50 INFO - Running post-run listener: _upload_blobber_files 04:29:50 INFO - Blob upload gear active. 04:29:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:50 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 04:29:50 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'] 04:29:50 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 04:29:50 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:29:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:51 INFO - (blobuploader) - INFO - Done attempting. 04:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:52 INFO - (blobuploader) - INFO - Done attempting. 04:29:52 INFO - (blobuploader) - INFO - Iteration through files over. 04:29:52 INFO - Return code: 0 04:29:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:29:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:29:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1d0b8ab9b7b8bd5a129bbcbe22a39a3355adab86f0a18f46a50cb91be02c77e0886a60bca09aa63b6d71c0110ad7dd6b7c0eedcd4a02f86c055e8561f1ae6f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7228a347dfaee25453aa189b00a908ed14de17dc97245a6674afa84486d63785c2f20e10ec6862f53a501800763f2c6a6da6f6cbeef359f704370d72d2f30cd"} 04:29:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:29:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:29:52 INFO - Contents: 04:29:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e1d0b8ab9b7b8bd5a129bbcbe22a39a3355adab86f0a18f46a50cb91be02c77e0886a60bca09aa63b6d71c0110ad7dd6b7c0eedcd4a02f86c055e8561f1ae6f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7228a347dfaee25453aa189b00a908ed14de17dc97245a6674afa84486d63785c2f20e10ec6862f53a501800763f2c6a6da6f6cbeef359f704370d72d2f30cd"} 04:29:52 INFO - Running post-run listener: copy_logs_to_upload_dir 04:29:52 INFO - Copying logs to upload dir... 04:29:52 INFO - mkdir: /builds/slave/test/build/upload/logs 04:29:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.791106 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-17 04:29:52.070794) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:29:52.075246) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 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/e1d0b8ab9b7b8bd5a129bbcbe22a39a3355adab86f0a18f46a50cb91be02c77e0886a60bca09aa63b6d71c0110ad7dd6b7c0eedcd4a02f86c055e8561f1ae6f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7228a347dfaee25453aa189b00a908ed14de17dc97245a6674afa84486d63785c2f20e10ec6862f53a501800763f2c6a6da6f6cbeef359f704370d72d2f30cd"} build_url:https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/artifacts/public/build/firefox-48.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/GknIWnyQS2m5Hyb_g8XqrA/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/e1d0b8ab9b7b8bd5a129bbcbe22a39a3355adab86f0a18f46a50cb91be02c77e0886a60bca09aa63b6d71c0110ad7dd6b7c0eedcd4a02f86c055e8561f1ae6f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7228a347dfaee25453aa189b00a908ed14de17dc97245a6674afa84486d63785c2f20e10ec6862f53a501800763f2c6a6da6f6cbeef359f704370d72d2f30cd"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 04:29:52.368532) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:29:52.368845) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1468749620.16580-1094882933 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 04:29:52.450368) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:29:52.450654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 04:29:52.453927) ========= ========= Total master_lag: 0.47 =========