builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-053 starttime: 1472737945.93 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.928129) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.928513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.928830) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.102405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.102703) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:27-- 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% 22.5M=0.001s 2016-09-01 06:52:27 (22.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123595 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.257311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.257591) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020656 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.366847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.367164) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:27,805 truncating revision to first 12 chars 2016-09-01 06:52:27,805 Setting DEBUG logging. 2016-09-01 06:52:27,805 attempt 1/10 2016-09-01 06:52:27,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bac8c738419e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:28,130 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659532 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.080153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.080423) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.080797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.081107) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.100639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-01 06:52:27.100887) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:28 INFO - MultiFileLogger online at 20160901 06:52:28 in /builds/slave/test 06:52:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:52:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:28 INFO - {'append_to_log': False, 06:52:28 INFO - 'base_work_dir': '/builds/slave/test', 06:52:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:28 INFO - 'branch': 'Mozilla-Aurora', 06:52:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:28 INFO - 'config_files': ('talos/linux_config.py',), 06:52:28 INFO - 'default_actions': ('clobber', 06:52:28 INFO - 'read-buildbot-config', 06:52:28 INFO - 'download-and-extract', 06:52:28 INFO - 'populate-webroot', 06:52:28 INFO - 'create-virtualenv', 06:52:28 INFO - 'install', 06:52:28 INFO - 'run-tests'), 06:52:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:28 INFO - 'download_minidump_stackwalk': True, 06:52:28 INFO - 'download_symbols': 'ondemand', 06:52:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:28 INFO - 'installer_path': 'installer.exe', 06:52:28 INFO - 'log_level': 'info', 06:52:28 INFO - 'log_name': 'talos', 06:52:28 INFO - 'log_to_console': True, 06:52:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:28 INFO - 'opt_config_files': (), 06:52:28 INFO - 'pip_index': False, 06:52:28 INFO - 'sps_profile': False, 06:52:28 INFO - 'sps_profile_interval': 0, 06:52:28 INFO - 'suite': 'dromaeojs-e10s', 06:52:28 INFO - 'system_bits': '32', 06:52:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:28 INFO - 'title': 'talos-linux64-ix-053', 06:52:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:28 INFO - 'use_talos_json': True, 06:52:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:28 INFO - 'work_dir': 'build'} 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.597091Z] Running clobber step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: clobber 06:52:28 INFO - rmtree: /builds/slave/test/build 06:52:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.060117Z] Finished clobber step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.060219Z] Running read-buildbot-config step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: read_buildbot_config 06:52:29 INFO - Using buildbot properties: 06:52:29 INFO - { 06:52:29 INFO - "project": "", 06:52:29 INFO - "product": "firefox", 06:52:29 INFO - "script_repo_revision": "production", 06:52:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 06:52:29 INFO - "repository": "", 06:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 06:52:29 INFO - "buildid": "20160830104522", 06:52:29 INFO - "pgo_build": "True", 06:52:29 INFO - "basedir": "/builds/slave/test", 06:52:29 INFO - "buildnumber": 520, 06:52:29 INFO - "slavename": "talos-linux64-ix-053", 06:52:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:29 INFO - "platform": "ubuntu64_hw", 06:52:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:29 INFO - "branch": "mozilla-aurora", 06:52:29 INFO - "repo_path": "releases/mozilla-aurora", 06:52:29 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:29 INFO - "stage_platform": "linux64-pgo", 06:52:29 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:29 INFO - "slavebuilddir": "test" 06:52:29 INFO - } 06:52:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.062675Z] Finished read-buildbot-config step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.062763Z] Running download-and-extract step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: download_and_extract 06:52:29 INFO - mkdir: /builds/slave/test/build/tests 06:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:29 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:52:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:29 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 06:52:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:59 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:53:59 INFO - Downloaded 1361 bytes. 06:53:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Using the following test package requirements: 06:53:59 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:59 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:59 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'jsshell-linux-x86_64.zip'], 06:53:59 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:59 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:59 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:59 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:59 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:59 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:59 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')]} 06:53:59 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:54:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:29 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:30 INFO - Downloaded 21769513 bytes. 06:55:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:55:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:55:31 INFO - Return code: 0 06:55:31 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:31 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:31 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:56:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:01 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:57:03 INFO - Downloaded 21769513 bytes. 06:57:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:57:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:57:04 INFO - Return code: 0 06:57:04 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')]} 06:57:04 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:57:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:04 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 06:57:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:34 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:58:35 INFO - Downloaded 14413723 bytes. 06:58:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:58:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:58:35 INFO - Return code: 0 06:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:35 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:58:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:35 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 06:59:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:05 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:00:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:00:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:00:07 INFO - Downloaded 63889067 bytes. 07:00:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:07 INFO - mkdir: /builds/slave/test/properties 07:00:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:07 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:07 INFO - Contents: 07:00:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - Running post-action listener: set_extra_try_arguments 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.023577Z] Finished download-and-extract step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.023812Z] Running populate-webroot step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: populate_webroot 07:00:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:08 INFO - '--tppagecycles', 07:00:08 INFO - '7'], 07:00:08 INFO - 'tests': ['tsvgm']}}, 07:00:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s', 07:00:08 INFO - '--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}, 07:00:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}}, 07:00:08 INFO - 'talos.zip': {'path': '', 07:00:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.039845Z] Finished populate-webroot step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.039954Z] Running create-virtualenv step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: create_virtualenv 07:00:08 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:00:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:08 INFO - Using real prefix '/usr' 07:00:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:10 INFO - Installing pip.................done. 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:10 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')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:00:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:00:10 INFO - Cleaning up... 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:10 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')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:00:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:11 INFO - Downloading/unpacking psutil>=3.1.1 07:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Installing collected packages: psutil 07:00:13 INFO - Running setup.py install for psutil 07:00:13 INFO - building 'psutil._psutil_linux' extension 07:00:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:00:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:00:13 INFO - building 'psutil._psutil_posix' extension 07:00:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:00:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Successfully installed psutil 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:00:13 INFO - Using env: {'DISPLAY': ':0', 07:00:13 INFO - 'HOME': '/home/cltbld', 07:00:13 INFO - 'LANG': 'en_US.UTF-8', 07:00:13 INFO - 'LANGUAGE': 'en_US:en', 07:00:13 INFO - 'LOGNAME': 'cltbld', 07:00:13 INFO - 'MAIL': '/var/mail/cltbld', 07:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:13 INFO - 'MOZ_NO_REMOTE': '1', 07:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:13 INFO - 'NO_EM_RESTART': '1', 07:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:13 INFO - 'PWD': '/builds/slave/test', 07:00:13 INFO - 'SHELL': '/bin/bash', 07:00:13 INFO - 'SHLVL': '1', 07:00:13 INFO - 'TERM': 'linux', 07:00:13 INFO - 'TMOUT': '86400', 07:00:13 INFO - 'USER': 'cltbld', 07:00:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:13 INFO - '_': '/tools/buildbot/bin/python'} 07:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:15 INFO - Installing collected packages: mozsystemmonitor 07:00:15 INFO - Running setup.py install for mozsystemmonitor 07:00:15 INFO - Successfully installed mozsystemmonitor 07:00:15 INFO - Cleaning up... 07:00:15 INFO - Return code: 0 07:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:00:15 INFO - Using env: {'DISPLAY': ':0', 07:00:15 INFO - 'HOME': '/home/cltbld', 07:00:15 INFO - 'LANG': 'en_US.UTF-8', 07:00:15 INFO - 'LANGUAGE': 'en_US:en', 07:00:15 INFO - 'LOGNAME': 'cltbld', 07:00:15 INFO - 'MAIL': '/var/mail/cltbld', 07:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:15 INFO - 'MOZ_NO_REMOTE': '1', 07:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:15 INFO - 'NO_EM_RESTART': '1', 07:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:15 INFO - 'PWD': '/builds/slave/test', 07:00:15 INFO - 'SHELL': '/bin/bash', 07:00:15 INFO - 'SHLVL': '1', 07:00:15 INFO - 'TERM': 'linux', 07:00:15 INFO - 'TMOUT': '86400', 07:00:15 INFO - 'USER': 'cltbld', 07:00:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:15 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Installing collected packages: jsonschema, functools32 07:00:18 INFO - Running setup.py install for functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Successfully installed jsonschema functools32 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - Downloading docopt-0.6.1.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:21 INFO - Running setup.py install for blobuploader 07:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:21 INFO - Running setup.py install for requests 07:00:21 INFO - Running setup.py install for docopt 07:00:22 INFO - Successfully installed blobuploader requests docopt 07:00:22 INFO - Cleaning up... 07:00:22 INFO - Return code: 0 07:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:22 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')]} 07:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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')]} 07:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:00:22 INFO - Using env: {'DISPLAY': ':0', 07:00:22 INFO - 'HOME': '/home/cltbld', 07:00:22 INFO - 'LANG': 'en_US.UTF-8', 07:00:22 INFO - 'LANGUAGE': 'en_US:en', 07:00:22 INFO - 'LOGNAME': 'cltbld', 07:00:22 INFO - 'MAIL': '/var/mail/cltbld', 07:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:22 INFO - 'MOZ_NO_REMOTE': '1', 07:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:22 INFO - 'NO_EM_RESTART': '1', 07:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:22 INFO - 'PWD': '/builds/slave/test', 07:00:22 INFO - 'SHELL': '/bin/bash', 07:00:22 INFO - 'SHLVL': '1', 07:00:22 INFO - 'TERM': 'linux', 07:00:22 INFO - 'TMOUT': '86400', 07:00:22 INFO - 'USER': 'cltbld', 07:00:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:22 INFO - '_': '/tools/buildbot/bin/python'} 07:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Running setup.py (path:/tmp/pip-EiWqpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Running setup.py (path:/tmp/pip-9c6_ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Running setup.py (path:/tmp/pip-wYc3pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Running setup.py (path:/tmp/pip-pamh36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Running setup.py (path:/tmp/pip-euu1JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Running setup.py (path:/tmp/pip-a_nh83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Running setup.py (path:/tmp/pip-e97Jpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Running setup.py (path:/tmp/pip-jtotS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Running setup.py (path:/tmp/pip-4FbVhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:22 INFO - Running setup.py (path:/tmp/pip-Qs09ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Running setup.py (path:/tmp/pip-ttuJ5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Running setup.py (path:/tmp/pip-hbJrc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Running setup.py (path:/tmp/pip-7cel3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Running setup.py (path:/tmp/pip-eDoOCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Running setup.py (path:/tmp/pip-va2czl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Running setup.py (path:/tmp/pip-hlmj26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Running setup.py (path:/tmp/pip-nznjgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:23 INFO - Running setup.py install for manifestparser 07:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozcrash 07:00:23 INFO - Running setup.py install for mozdebug 07:00:23 INFO - Running setup.py install for mozdevice 07:00:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozfile 07:00:24 INFO - Running setup.py install for mozhttpd 07:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozinfo 07:00:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozInstall 07:00:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozleak 07:00:24 INFO - Running setup.py install for mozlog 07:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for moznetwork 07:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozprocess 07:00:24 INFO - Running setup.py install for mozprofile 07:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozrunner 07:00:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozscreenshot 07:00:25 INFO - Running setup.py install for moztest 07:00:25 INFO - Running setup.py install for mozversion 07:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:25 INFO - Cleaning up... 07:00:25 INFO - Return code: 0 07:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:25 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')]} 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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')]} 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:00:25 INFO - Using env: {'DISPLAY': ':0', 07:00:25 INFO - 'HOME': '/home/cltbld', 07:00:25 INFO - 'LANG': 'en_US.UTF-8', 07:00:25 INFO - 'LANGUAGE': 'en_US:en', 07:00:25 INFO - 'LOGNAME': 'cltbld', 07:00:25 INFO - 'MAIL': '/var/mail/cltbld', 07:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:25 INFO - 'MOZ_NO_REMOTE': '1', 07:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:25 INFO - 'NO_EM_RESTART': '1', 07:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:25 INFO - 'PWD': '/builds/slave/test', 07:00:25 INFO - 'SHELL': '/bin/bash', 07:00:25 INFO - 'SHLVL': '1', 07:00:25 INFO - 'TERM': 'linux', 07:00:25 INFO - 'TMOUT': '86400', 07:00:25 INFO - 'USER': 'cltbld', 07:00:25 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:25 INFO - '_': '/tools/buildbot/bin/python'} 07:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Running setup.py (path:/tmp/pip-xdMriW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Running setup.py (path:/tmp/pip-D9cRyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 INFO - Running setup.py (path:/tmp/pip-e8vVQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 INFO - Running setup.py (path:/tmp/pip-px18OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:25 INFO - Running setup.py (path:/tmp/pip-SUdANY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:25 INFO - Running setup.py (path:/tmp/pip-rloJKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 INFO - Running setup.py (path:/tmp/pip-KJdZZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 INFO - Running setup.py (path:/tmp/pip-pLB5qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:26 INFO - Running setup.py (path:/tmp/pip-88shaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Running setup.py (path:/tmp/pip-J2yqbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 INFO - Running setup.py (path:/tmp/pip-mQGyFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 INFO - Running setup.py (path:/tmp/pip-wTWi4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Running setup.py (path:/tmp/pip-UVEafL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Running setup.py (path:/tmp/pip-9AyOAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Running setup.py (path:/tmp/pip-oBdMDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:26 INFO - Running setup.py (path:/tmp/pip-7wN9Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:26 INFO - Running setup.py (path:/tmp/pip-a_t9ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:00:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:28 INFO - Downloading blessings-1.6.tar.gz 07:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:28 INFO - Installing collected packages: blessings 07:00:28 INFO - Running setup.py install for blessings 07:00:28 INFO - Successfully installed blessings 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Current package versions: 07:00:29 INFO - argparse == 1.2.1 07:00:29 INFO - blessings == 1.6 07:00:29 INFO - blobuploader == 1.2.4 07:00:29 INFO - docopt == 0.6.1 07:00:29 INFO - functools32 == 3.2.3-2 07:00:29 INFO - jsonschema == 2.5.1 07:00:29 INFO - manifestparser == 1.1 07:00:29 INFO - mozInstall == 1.12 07:00:29 INFO - mozcrash == 0.17 07:00:29 INFO - mozdebug == 0.1 07:00:29 INFO - mozdevice == 0.48 07:00:29 INFO - mozfile == 1.2 07:00:29 INFO - mozhttpd == 0.7 07:00:29 INFO - mozinfo == 0.9 07:00:29 INFO - mozleak == 0.1 07:00:29 INFO - mozlog == 3.3 07:00:29 INFO - moznetwork == 0.27 07:00:29 INFO - mozprocess == 0.23 07:00:29 INFO - mozprofile == 0.28 07:00:29 INFO - mozrunner == 6.12 07:00:29 INFO - mozscreenshot == 0.1 07:00:29 INFO - mozsystemmonitor == 0.3 07:00:29 INFO - moztest == 0.7 07:00:29 INFO - mozversion == 1.4 07:00:29 INFO - psutil == 3.1.1 07:00:29 INFO - requests == 1.2.3 07:00:29 INFO - wsgiref == 0.1.2 07:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a32030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c61400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:00:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Running post-action listener: _resource_record_post_action 07:00:29 INFO - Running post-action listener: _start_resource_monitoring 07:00:29 INFO - Starting resource monitoring. 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.623635Z] Finished create-virtualenv step (success) 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.624170Z] Running install step. 07:00:29 INFO - Running pre-action listener: _resource_record_pre_action 07:00:29 INFO - Running main action method: install 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Output received: 07:00:29 INFO - Usage: mozinstall [options] installer 07:00:29 INFO - Options: 07:00:29 INFO - -h, --help show this help message and exit 07:00:29 INFO - -d DEST, --destination=DEST 07:00:29 INFO - Directory to install application into. [default: 07:00:29 INFO - "/builds/slave/test"] 07:00:29 INFO - --app=APP Application being installed. [default: firefox] 07:00:29 INFO - mkdir: /builds/slave/test/build/application 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:00:45 INFO - Reading from file tmpfile_stdout 07:00:45 INFO - Output received: 07:00:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.919523Z] Finished install step (success) 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.919804Z] Running run-tests step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: run_tests 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:45 INFO - Python 2.7.3 07:00:45 INFO - Return code: 0 07:00:45 INFO - grabbing minidump binary from tooltool 07:00:45 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')]} 07:00:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf0570>, '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 0x1cf1470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf1900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:00:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:00:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:00:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:45 INFO - Return code: 0 07:00:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:00:45 INFO - Using env: {'DISPLAY': ':0', 07:00:45 INFO - 'HOME': '/home/cltbld', 07:00:45 INFO - 'LANG': 'en_US.UTF-8', 07:00:46 INFO - 'LANGUAGE': 'en_US:en', 07:00:46 INFO - 'LOGNAME': 'cltbld', 07:00:46 INFO - 'MAIL': '/var/mail/cltbld', 07:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:46 INFO - 'MOZ_NO_REMOTE': '1', 07:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:46 INFO - 'NO_EM_RESTART': '1', 07:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:46 INFO - 'PWD': '/builds/slave/test', 07:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:46 INFO - 'SHELL': '/bin/bash', 07:00:46 INFO - 'SHLVL': '1', 07:00:46 INFO - 'TERM': 'linux', 07:00:46 INFO - 'TMOUT': '86400', 07:00:46 INFO - 'USER': 'cltbld', 07:00:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039', 07:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:46 INFO - '_': '/tools/buildbot/bin/python'} 07:00:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:00:46 INFO - mozversion application_buildid: 20160830104522 07:00:46 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:46 INFO - mozversion application_name: Firefox 07:00:46 INFO - mozversion application_remotingname: firefox-dev 07:00:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion application_vendor: Mozilla 07:00:46 INFO - mozversion application_version: 50.0a2 07:00:46 INFO - mozversion platform_buildid: 20160830104522 07:00:46 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion platform_version: 50.0a2 07:00:46 INFO - using testdate: 1472738446 07:00:46 INFO - actual date: 1472738446 07:00:46 INFO - starting webserver on 'localhost:49164' 07:00:46 INFO - SUITE-START | Running 2 tests 07:00:46 INFO - TEST-START | dromaeo_css 07:00:46 INFO - operating with platform_type : linux_ 07:00:46 INFO - Initialising browser for dromaeo_css test... 07:00:46 INFO - TEST-INFO | started process 19849 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsKJu8I/profile http://localhost:49164/getInfo.html) 07:00:50 INFO - PROCESS | 19849 | __metrics Screen width/height:1600/1200 07:00:50 INFO - PROCESS | 19849 | colorDepth:24 07:00:50 INFO - PROCESS | 19849 | Browser inner width/height: 1024/697 07:00:50 INFO - PROCESS | 19849 | __metrics 07:00:51 ERROR - PROCESS | 19849 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:00:52 INFO - TEST-INFO | 19849: exit 0 07:00:52 INFO - Browser initialized. 07:00:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:52 INFO - TEST-INFO | started process 19975 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsKJu8I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:04 INFO - PROCESS | 19975 | RSS: Main: 152756224 07:01:04 INFO - PROCESS | 19975 | 07:01:53 INFO - PROCESS | 19975 | 1472738513744 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:04:02 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49164/tests/dromaeo/cssquery-ext.html) 07:04:03 INFO - PROCESS | 19975 | RSS: Main: 157999104 07:04:03 INFO - PROCESS | 19975 | 07:07:05 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-ext.html (next: http://localhost:49164/tests/dromaeo/cssquery-jquery.html) 07:07:06 INFO - PROCESS | 19975 | RSS: Main: 153899008 07:07:06 INFO - PROCESS | 19975 | 07:10:08 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49164/tests/dromaeo/cssquery-mootools.html) 07:10:08 INFO - PROCESS | 19975 | RSS: Main: 154030080 07:10:08 INFO - PROCESS | 19975 | 07:13:10 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49164/tests/dromaeo/cssquery-prototype.html) 07:13:11 INFO - PROCESS | 19975 | RSS: Main: 153239552 07:13:11 INFO - PROCESS | 19975 | 07:16:13 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49164/tests/dromaeo/cssquery-yui.html) 07:16:14 INFO - PROCESS | 19975 | RSS: Main: 155238400 07:16:14 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | Cycle 1(1): loaded http://localhost:49164/tests/dromaeo/cssquery-yui.html (next: http://localhost:49164/tests/dromaeo/cssquery-dojo.html) 07:19:16 INFO - PROCESS | 19975 | RSS: Main: 155987968 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | __start_tp_report 07:19:16 INFO - PROCESS | 19975 | _x_x_mozilla_page_load 07:19:16 INFO - PROCESS | 19975 | _x_x_mozilla_page_load_details 07:19:16 INFO - PROCESS | 19975 | |i|pagename|runs| 07:19:16 INFO - PROCESS | 19975 | |0;dojo.html;3301.851416686249;3308.3548708001936;3324.58442694663;3348.330333933211;3362.6805453481925;5885.382034886334;5909.143174239733;5935.584509084366;5964.671943043131;5976.462118409348;5813.593048486608;5861.267341582302;5863.560232982522;5873.735681894314;5879.206307148534;5315.229291752694;5337.786488540453;5383.730813459331;5392.083345831198;5441.374241962166;5249.580033597303;5256.526912577867;5284.867878303035;5395.406505284415;5428.077226871422;5354.678719276856;5380.757865896044;5395.028894798935;5455.0453670607685;5545.805896793613;5413.377461591908;5439.9936011837935;5461.317335333083;5467.20725494805;5468.64453810505;5212.322398088262;5212.50481204285;5269.552088072518;5343.198520221959;5455.372632147274;5742.655440673531;5837.941620583782;5853.882922341572;5863.442972917566;5889.204957330749;6252.093446450284;6296.527760417941;6313.273973493061;6317.6209427434505;6319.6412771253945;6090.96954515229;6200.410960958701;6329.303776584572;6351.650659213788;6373.26707428647;5828.737706803204;5909.468147866713;5914.645121292736;5943.4056594340655;5946.791862284843;6481.935180648227;6483.351664833479;6511;6521.413072823374;6557.901631475532;6051.7579296827635;6060.393960603992;6234.875302494015;6242.282137554149;6292.276388215414;6137.8060289514;6265.091561723616;6301.369863013753;6308.927482328016;6315.5263355247625;6278.748850046039;6293.30773614902;6299.5275354348605;6304.590201636879;6318.557700960981;6051.878962420815;6180.907286390708;6303.747850086037;6305.936940630627;6324.2410910691015;4754.120487709784;4779.976100119477;4795.424549054136;4831.903361932742;4841.104395686809;4255.127698821734;4278.251306021434;4294.592013758688;4324.632406245504;4326.199653064194;5896.823095307148;5945.1973983512535;6025.0661147522205;6071.544634152457;6083.695815209222;6185.628862268189;6330.430261276415;6356.968215158987;6369.331220221903;6383.329750376237;6051.727672254759;6067.878642427127;6098.878022439615;6128.17269668598;6130.816075517741;5370.0333939890415;5398.379186393537;5467.617266791286;5470.64440811346;5479.479449452296;6609.438197753245;6613.206415230107;6614.669266536654;6622.1722284714415;6626.171728533934;6354.046892966092;6364.331745166876;6366.108744775827;6380.840478988062;6386.201724784402;6292.748290068346;6340.30256671775;6421;6604.207495100619;6620.073189753342;6177.1969643945995;6202.503799696104;6211;6215.34738852414;6218.782342617987;6267.059941008885;6314.905276420949;6327;6345.365463453618;6381.9469787486405;5906.438888305604;6070.817875463743;6127.785527506516;6190.473809726043;6193.009118541192;6156.753729850756;6209.5963762357105;6214.223222097238;6216.658083805253;6225.875482490415;6154.846128846814;6161.199044124235;6203.100550420254;6213.937860621336;6215.689215539295;6016.916954948151;6047.365026672136;6078.6048906820915;6235.625862448268;6239.563230573816;5940.851478713067;5996.4902983245165;6027.698615069141;6043.36544662804;6050.152978582913 07:19:16 INFO - PROCESS | 19975 | |1;ext.html;577.4629594477137;625.8091282158941;643.0964494884689;643.8229486891105;659.6701649175412;47874.52125478744;48988.51011489884;49489;49706.25440618385;53469.1979620306;48943.51056489434;49572.25641615379;49652.75173624136;49739.25391119136;49792.50207497924;9228.169464748154;9345.485998270093;9369.718908432758;9383.906160938372;9387.859182112274;9197.356185067049;9234.6306147754;9258.814823703522;9261.583228754722;9279.886413630375;9328.207102396305;9367.625294988193;9368.437893726365;9378.109079637457;9432.386894851847;9195.310351723614;9229.07709229075;9251.259899208047;9262.444253344753;9321.067893210658;9161.954190229073;9198.954005229998;9238.122378374044;9257.814843703089;9302.30232732541;46242.76878615585;46269;46556;46638.76680616575;46662.06675866498;3940.3892396678275;4156.210320039213;4333.349997500401;4470.307102399133;4631.3516107744945;35222.471662925076;35500.822495887354;35638;35662;35728.28543429117;4596.770161491912;4631.466201205968;4655.604273636745;4736.52634736527;4761.523847615211;261785;263732;266555;266968;267456.9999999981;66811.66594167096;67233;67407;67421.66289168522;67624.66187669129;62637;69044.6547767268;70729.29270707227;71649.64175179091;71911;19287;19421.805781942283;19423.611527769648;19471.805281947283;19560.804391956182;14192.503262385768;14205.502807401692;14234.288285585679;14305.141691498544;14336.856631433553;19808.40574782759;19890;20124.698129528068;20278.898605506878;20368.28710995108;6916.308369163144;7301.452391070691;7302.3792977597495;7344.706211751577;7424.48028638001;1011.4993078426079;1075.800976819291;1079.6707004363745;1213.1447329632588;1241.6957845327772;32822.835885820896;33512.664873351445;33589.83205083959;33915.15212119717;34089.488657670154;1126.0890597804766;1138.1805100327751;1142.497301187475;1145.5303325636448;1239.2874097393853;17127.914360428367;17835.10824458772;17912.462626121236;17929;18068.277268909096;141349;141811.29094354462;142005.28997354946;142076;143034;62095.37904620896;62682.373176268564;62726.686366568785;62740;62855.37144628495;1425.964350891236;1444.4366696988184;1460.145814698392;1477.0325436838887;1488.1220080152762;30736.846315768278;32816.671833282315;32934.34131317312;33069.66930330666;33776.986690399324;55443.16835247394;55757.88484230211;55790.163147552004;55984.44015559792;56034.15948760691;366496;367148;368029;368361;368914;45794.771026144655;46412.719311370805;46620.06759864716;46866;47376.289355659006;51480.22779658383;51545;51551.2267315998;51594.48405516047;52019.47980520147;42835;43642.52773276829;44141.77929110334;44144.779276103414;44799;42132.78933605312;42221;42829.78585107055;44492.77753611211;44867.775661121486;43262;43718.78140609276;43869.78065109654;44866.327005095605;44980.32529511995;36776.26447471089;38973.41539876848;39049.14186142051;39260;39660.60339396569;29579;29649.25876853096;30180.84909575438;30395.696043039286;30529.23676908095 07:19:16 INFO - PROCESS | 19975 | |2;jquery.html;418.4476491031837;419.5594625643074;427.9893002674934;429;441.0847491455229;20253.392398228058;20437.386878393634;20989.68515472269;21034.368968930918;21238.57522849542;19980.100895459702;20827.27104551341;20947.266845660404;20995.160193592277;21242.89378553109;5742.425757424245;5926.614770039933;5935.406459354052;5939.020061689816;5962.642241465504;5812.157237200603;5858.326292476378;5884.146798714184;5885.970570147165;5898.085796701518;5654.349749778787;5807.535397168216;5832.9708351458185;5856.619319744203;5858;5698.487136157766;5764.654120752747;5802.854928626776;5835.562332825034;5872.442117998783;5218.791248350061;5286.3127793387;5301.310829592128;5307.6019298552765;5311.654742441729;24870;25398.365040874127;25417;25433.237002889757;25452.87273563639;2578.355411147213;2626.290901456596;2694.7170547092664;2704.594310853388;2709.525832979221;15268.38926442941;15403.460878869186;15550.61123471911;16174.595635109099;16270.674586508203;2005.5587770690424;2085.3952353817376;2133.4133113393787;2185.9344219673435;2266.4447210433386;86679;90044.54977725135;90771.54614226952;91015;91660.54169729176;11163.218574699855;11471.827922581168;11534.826977595343;11540.769184616262;11763.529458821551;16192.43326483591;16210.83789162117;16258.593535161715;16288.42990495327;16294;8613.440126391764;8619.870701939475;8719.215270625675;8846.823063538823;8916.465012099296;8625.69810056645;8669.783255418664;8848.380613357003;8852.690155844515;9068.866391701038;4964.255361695702;4977.0792403405485;5012.1980483122525;5017.548420642087;5020.472850350734;2048.8463365247667;2073.8651987620756;2102.8843413612094;2118.9046492907855;2123.108294516307;922.4511415707777;1045.9895401046044;1050.495762034228;1065.2223876570147;1091.748897753521;20455.4886127848;20826.47933801667;20975.79024209769;20995.16019359239;21184.607446217156;1015.2030655935056;1016.0753714120116;1026.5380578739598;1046.5186014433446;1051.2115913065202;11149.554017839358;11569.76860462786;11624.186306958432;11692.181547291608;11780.941095294469;497007;500195;503001;505155;517332;91034.54482727677;93193.53403232941;103536;103867;104781.47609261905;1073.120041565924;1104.2711810205387;1127.4982632728356;1129.6215767717906;1136.8351589019712;18458.63082738311;18874.43376698701;19182.90408548004;19212.231510739974;19341.430472421423;42434;43121.137577248905;43360;43816.123677526906;44664.77667611641;59482;59925.70037149961;60969.695151523956;62383.37616623957;62536;9068.546572671472;9165.037671044443;9200.493972831558;9322.254219662547;9403.388779729295;12366.814497782361;14050.437982480586;14172.43310267619;14533.128012319296;14619.780703289247;11524.250923689933;11526.135539834379;12227.816582751087;12379.62861114168;12441.626751197116;11672.47473863695;11752.58865939688;11876.703082423008;12367.567135150226;12736.61790146297;5782.5952183348445;9073.59168837417;11004.614838480615;12025.63923082309;12279.508819647113;9047.728568142966;9122.087791220825;9130.817383652422;9426.198773104363;9456.810863782823;11224.775504490028;11863.406829658656;11863.881361186277;12104.394780261127;12121.636350909486 07:19:16 INFO - PROCESS | 19975 | |3;mootools.html;1577.203512226326;1591.1725902530682;1791.3372052340635;1867.570458794478;1895.2703209264437;8630.093840146772;9134.451932884032;9242.30682698797;10134.442605656686;10159.492025398737;9116.726498205064;9410.670626528074;9426.622935082589;9432.52837358131;9461.006594307602;2519.3449703077194;2563.8333508321994;2569.922902312924;2573.74262573743;2575.394782226185;2498.3129639349145;2594.0661361909697;2605.7524535169127;2613.242159773673;2613.542630039745;2564.4614630927435;2579.690437147545;2611.2949503634104;2613.1376645706964;2613.8301010434357;2477.541654793869;2500.8374455660323;2513.39678477166;2535.6830396200476;2555.3611597100726;2434;2455.300239431753;2459.827812053157;2503.324102492317;2541.275736415113;9553.665621703276;9554.378965367228;9603.855942160873;9643.324967252294;9643.517824108836;5650.689212093374;5711.600187986843;5762.740676669518;5792.275965504312;5804.912926306109;3527.153483164022;3590.425531914881;3652.7990960497164;3669.4495825626104;3727.8881633550764;1109.2346281066039;1114.6656003199007;1119.6696974392573;1129.232122156933;1132.824412216108;13449.260290684106;13547.932260338635;13623.727525449436;13659.521916732967;13747.243901585409;4861.955546695889;4999;5169.276301317818;5244.501772331622;5263.736813159327;11301.26541774782;11336.6032188873;11379.260348077349;11382.772344553063;11394.37330946806;5864.794732184353;5901;5936.050231962951;5941.851453713605;5949.137375080589;4852.95147048525;5214.713190774544;5297.867553311198;5343.973280133574;5482.396936337;4388.1223755249075;4421.911561768747;4455.487618923865;4474.574915383034;4513.029698614813;1368.7946807978683;1385.9445622175201;1398.930053497321;1402.5792262321263;1406.2406300597768;1063.8563793887881;1105.729096371378;1107.9612213572648;1111.0389513071136;1125.7298248420484;6811.489138314646;6811.489138314646;6828.351306625863;6847.828804279933;6903.447724182053;494.8144445832813;510.4181233393934;512.8051340490664;512.8153864608738;517.056372120881;4759.214729569651;4761.833335833229;4787.736674482867;4802.51974802517;4872.098661747588;11277.71805704864;11766.941165294118;11811.173217874666;11822.172447928562;11834.82247766267;11087.667369978913;11213.887861121284;11255.831162532568;11376.715582110513;11542;8444;8659.090795466385;8701.129887011375;8711.825763484821;8737.257333126629;6242.875142497215;6278.466330361788;6338.366163383809;6404.231492220963;6418.422341989243;10164.745881353025;10418.374897506174;10430.26988110825;10470.790584188122;10481;10582.417967011888;10656.25406221557;10672.679819605424;10820.67537973862;10938.45307734626;7912.9208707912185;8226.588670566567;8385.287250583524;8429.072801991899;8522.190391913007;9235.353525253167;9247.815043698954;9258.166764991183;9317.767055823659;9334.486603236886;8009.959950200445;8124.837503249783;8126.878096828434;8183.3862460316905;8385.58072096405;7635.312821846061;7635.541867487968;7711.305982461606;7831.373490120663;7982.08206056311;7209.6034718091005;7276.090488688914;7280.453965952681;7293.307135822089;7346.449016323691;5472.835814925558;5483.4516548344845;5627.8874422512135;5642.125470552071;5703.458171473703;5768.1636162757;5802.709864506842;5806.158107074366;5842.298924129132;5878.147668587945 07:19:16 INFO - PROCESS | 19975 | |4;prototype.html;338.771329352687;351.7361978516113;352.54697713438236;353.5562868599907;354.9183687751817;5700.7719691212405;5786.643746626957;5837.795677151301;5918.852028699284;5949.613275137113;5784.3058832940105;5792.971035144818;5814.5057670098095;5815.38938411467;5837;4512.63898888089;4528.343390208418;4536.682432229745;4541.43232095988;4560.3843481130125;4465.330200469923;4533.0480599074235;4570.520095389986;4573.474050484204;4617.792199351037;4499.055198408339;4509.864704058883;4519.819207231707;4520.593146616804;4529.456465224178;4507.301368287905;4518.864434066983;4536.319552067183;4549.772511374451;4572.839950601713;3924.2936271471126;4020.175863947913;4048.473698419216;4050.2102090092444;4066.2883995301;5678.148277758328;5708.800191993302;5710.77156913723;5711.200431939532;5742.047703780693;5129.769160387791;5149.871253218662;5210.921836172422;5214.765335559871;5219.921701174486;5938.584299099065;6092.268927728701;6111.449969502722;6117.632942023492;6148.477379422754;4391.604755572014;4420.4803262140595;4425.867223983286;4431.246688063037;4435.889102772457;6278.968605157036;6385.936140638581;6401.935980640227;6419.743210271584;6446.613203207822;4448.421705178371;4471.597556219956;4489.416375871116;4489.865304040884;4519.7740112994215;4550.362949187116;4556.476005259437;4562;4565.863024109282;4575.931361029588;5086.862269108227;5198.584113270929;5208.895822083613;5217.530422262014;5232.973835130876;5956.702164891824;6034.9396506035255;6116.755329786848;6132.356102609251;6203.294094124152;1517.87856971442;1547.81426228851;1548.3355392952622;1556.8832337574727;1559.0567706537609;969.471637957315;971.8882328532168;994.9503274045807;996.9152622027209;1007.3049595778889;5255.78976840922;5263.683879156184;5266.236395722599;5266.262723218676;5276.551493123051;5542.63972841764;5557.416471270459;5569.162176481832;5577.74900129487;5589.720513974285;4978.991911511268;5225.399079105951;5285.894282114336;5299.655522391032;5354.973225133927;3003.57949887016;3018.3661431099713;3035.362573859514;3041.4981528048065;3062.8162310261455;5726.3700992890745;5736.856578585569;5761.164631128463;5809.651420914759;5835.387284335084;6028.5478589106;6470.611763294123;6475.672487140125;6486.83237017332;6516.413522782972;6562.212534495891;6622.2715501294815;6644.933550664528;6648.867022659617;6665.80002599913;6155.753769849335;6342.255879633138;6349.333320001518;6353.77761778354;6379.617222966637;6297.779577714758;6312.400321969406;6336.651484168231;6341.397567231105;6368.42684158428;6446.5165112615805;6613.957579513478;6635.568688035262;6669.23265334677;6681.665916704243;4422.867313980586;4475.66432517569;4487.371767952424;4504.301833215857;4510.616597589111;6136.355682653436;6144.2319710036245;6473.482121430368;6499.447546958561;6506.186726659168;6084.330723620486;6102.877942441215;6116.785912493041;6134.601250918676;6187.938120618736;6079.57130074441;6204.728030753595;6212.596181248205;6231.4391704746795;6304.5271604628915;6082.391760823883;6115.969420152871;6145.8156255312415;6153.353897840662;6157.568970172224;6236.34518375564;6239.906401404073;6259.812205633838;6275.717592708431;6303.023031430135;5810.461416069974;6071.787487437919;6079.726412311545;6108.869859076085;6113.5414843886 07:19:16 INFO - PROCESS | 19975 | |5;yui.html;819.0662644585174;831.2560258568581;839.8740188971657;906.4923642760057;928.336239588694;15811;17224.397146099887;17297.308107675693;17442.476725698216;17503.38738144165;16234.431794887181;16424.507264782074;16632.50102496927;16635.168241587933;16645.250963706632;853.6542700206427;881.5548148185158;893.2050475077186;894.4096896048609;895.0780695883251;876.7106854737953;887.3034667785789;888.1518150166576;891.2246345679268;892.959816808242;943.9622415103388;954.7422196007074;955.5843208204453;955.6464108279912;956.9617215311414;930.5626355612851;931.0503286647581;935.508857849631;936.1621348892771;937.9202767764714;692.8510370270366;694.1323345817727;696.9128858892639;698.8183072401162;699.3251512290614;16089.436869709622;16121.83878161215;16281.755773663286;16318.83681163185;16370.099644519547;14390;14503.782443263359;14612.634684132876;15222.238888055554;16261.268242929096;14353.425862965469;14612.415503379852;14675.412983480648;14873;14941.402343906231;4786.7845946932475;4825.445073816486;4825.662203645747;4827.541383568555;4919.2867034279825;93911.53044234804;94285.52857235739;95162.52418737861;95304;95501.0449895499;12506.499740010298;12624.684382890502;12639.368031598384;12842.357882105858;12876.80684789729;22687.546249075258;22696.886515567647;22714.205002824823;22812.543749124925;22841.88579057094;9158.129977652112;9234.676786312446;9441.386309889835;9556.713298601053;9670.70987870365;4904.117258893362;4936.111499929975;4958.752062396866;5019.447860735389;5143.434222235551;3700.703943684499;3775.2638235543805;3788.6021967693546;3803.7527560708463;3806.219724956433;646.1761254400583;652.9249136349285;660.5904339309659;665.0921492163259;669.8291935556402;630.4735545819201;699.1679900917892;749.4678778067524;761.5430741555023;772.2432016623741;9771.853422198672;10207.336523126121;10410.583576657;10731.83902241467;10886.782264354826;719.7013239505546;753.2053683363994;766.6013672890066;787.2835719495232;790.5454363740872;4613.8846528836375;4814.759262036884;4816.157172494799;4830.2513110467935;4837.56461918422;126067;136761;136773;138660;140941;115751.86748944597;116220.09449763654;118229.40885295518;120371;122086;62483;65650.34349656443;89658.55170724104;102358.95282094316;105755.47122264493;9072.364934454525;9100.681476148304;9176.495292758962;9237.445753254826;9260.907390926004;61404.385956141654;61987;62145.37854621396;63380;63695.36304636894;72525.6373718128;82141;89553.55223223842;90316;91294;13944.721105578035;14091.886217067522;14808;14959.850401495845;15257.237138143271;16073.919630401773;16344;16411.343546258493;16577.751333729764;16944.830551694817;13843.307834608027;14327.212003339917;14716.55850324492;15095.773563396777;15204.391824326904;15164.49793754852;15325.693486130438;15373.846261537241;15589;16044.839551604335;13807.585772426843;14411.855881441052;14712.705745885236;14930.402783888523;15358;11657.533698652298;12352.444140013542;12886.871131288568;12912.935435322763;13043.28261945602;12104.93947530292;12342.568010119961;12416.627501174979;12456.377181141088;13046 07:19:16 INFO - PROCESS | 19975 | __end_tp_report 07:19:16 INFO - PROCESS | 19975 | __start_cc_report 07:19:16 INFO - PROCESS | 19975 | _x_x_mozilla_cycle_collect,320 07:19:16 INFO - PROCESS | 19975 | __end_cc_report 07:19:16 INFO - PROCESS | 19975 | __startTimestamp1472739556696__endTimestamp 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | ------- Summary: start ------- 07:19:16 INFO - PROCESS | 19975 | Number of tests: 6 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#0] dojo.html Cycles:165 Average:5859.25 Median:6071.54 stddev:677.56 (11.2%) stddev-sans-first:649.26 07:19:16 INFO - PROCESS | 19975 | Values: 3301.9 3308.4 3324.6 3348.3 3362.7 5885.4 5909.1 5935.6 5964.7 5976.5 5813.6 5861.3 5863.6 5873.7 5879.2 5315.2 5337.8 5383.7 5392.1 5441.4 5249.6 5256.5 5284.9 5395.4 5428.1 5354.7 5380.8 5395.0 5455.0 5545.8 5413.4 5440.0 5461.3 5467.2 5468.6 5212.3 5212.5 5269.6 5343.2 5455.4 5742.7 5837.9 5853.9 5863.4 5889.2 6252.1 6296.5 6313.3 6317.6 6319.6 6091.0 6200.4 6329.3 6351.7 6373.3 5828.7 5909.5 5914.6 5943.4 5946.8 6481.9 6483.4 6511.0 6521.4 6557.9 6051.8 6060.4 6234.9 6242.3 6292.3 6137.8 6265.1 6301.4 6308.9 6315.5 6278.7 6293.3 6299.5 6304.6 6318.6 6051.9 6180.9 6303.7 6305.9 6324.2 4754.1 4780.0 4795.4 4831.9 4841.1 4255.1 4278.3 4294.6 4324.6 4326.2 5896.8 5945.2 6025.1 6071.5 6083.7 6185.6 6330.4 6357.0 6369.3 6383.3 6051.7 6067.9 6098.9 6128.2 6130.8 5370.0 5398.4 5467.6 5470.6 5479.5 6609.4 6613.2 6614.7 6622.2 6626.2 6354.0 6364.3 6366.1 6380.8 6386.2 6292.7 6340.3 6421.0 6604.2 6620.1 6177.2 6202.5 6211.0 6215.3 6218.8 6267.1 6314.9 6327.0 6345.4 6381.9 5906.4 6070.8 6127.8 6190.5 6193.0 6156.8 6209.6 6214.2 6216.7 6225.9 6154.8 6161.2 6203.1 6213.9 6215.7 6016.9 6047.4 6078.6 6235.6 6239.6 5940.9 5996.5 6027.7 6043.4 6050.2 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#1] ext.html Cycles:180 Average:47633.97 Median:44832.66 stddev:72064.19 (160.7%) stddev-sans-first:72179.73 07:19:16 INFO - PROCESS | 19975 | Values: 577.5 625.8 643.1 643.8 659.7 47874.5 48988.5 49489.0 49706.3 53469.2 48943.5 49572.3 49652.8 49739.3 49792.5 9228.2 9345.5 9369.7 9383.9 9387.9 9197.4 9234.6 9258.8 9261.6 9279.9 9328.2 9367.6 9368.4 9378.1 9432.4 9195.3 9229.1 9251.3 9262.4 9321.1 9162.0 9199.0 9238.1 9257.8 9302.3 46242.8 46269.0 46556.0 46638.8 46662.1 3940.4 4156.2 4333.3 4470.3 4631.4 35222.5 35500.8 35638.0 35662.0 35728.3 4596.8 4631.5 4655.6 4736.5 4761.5 261785.0 263732.0 266555.0 266968.0 267457.0 66811.7 67233.0 67407.0 67421.7 67624.7 62637.0 69044.7 70729.3 71649.6 71911.0 19287.0 19421.8 19423.6 19471.8 19560.8 14192.5 14205.5 14234.3 14305.1 14336.9 19808.4 19890.0 20124.7 20278.9 20368.3 6916.3 7301.5 7302.4 7344.7 7424.5 1011.5 1075.8 1079.7 1213.1 1241.7 32822.8 33512.7 33589.8 33915.2 34089.5 1126.1 1138.2 1142.5 1145.5 1239.3 17127.9 17835.1 17912.5 17929.0 18068.3 141349.0 141811.3 142005.3 142076.0 143034.0 62095.4 62682.4 62726.7 62740.0 62855.4 1426.0 1444.4 1460.1 1477.0 1488.1 30736.8 32816.7 32934.3 33069.7 33777.0 55443.2 55757.9 55790.2 55984.4 56034.2 366496.0 367148.0 368029.0 368361.0 368914.0 45794.8 46412.7 46620.1 46866.0 47376.3 51480.2 51545.0 51551.2 51594.5 52019.5 42835.0 43642.5 44141.8 44144.8 44799.0 42132.8 42221.0 42829.8 44492.8 44867.8 43262.0 43718.8 43869.8 44866.3 44980.3 36776.3 38973.4 39049.1 39260.0 39660.6 29579.0 29649.3 30180.8 30395.7 30529.2 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#2] jquery.html Cycles:180 Average:30564.74 Median:25134.18 stddev:83389.39 (331.8%) stddev-sans-first:83592.60 07:19:16 INFO - PROCESS | 19975 | Values: 418.4 419.6 428.0 429.0 441.1 20253.4 20437.4 20989.7 21034.4 21238.6 19980.1 20827.3 20947.3 20995.2 21242.9 5742.4 5926.6 5935.4 5939.0 5962.6 5812.2 5858.3 5884.1 5886.0 5898.1 5654.3 5807.5 5833.0 5856.6 5858.0 5698.5 5764.7 5802.9 5835.6 5872.4 5218.8 5286.3 5301.3 5307.6 5311.7 24870.0 25398.4 25417.0 25433.2 25452.9 2578.4 2626.3 2694.7 2704.6 2709.5 15268.4 15403.5 15550.6 16174.6 16270.7 2005.6 2085.4 2133.4 2185.9 2266.4 86679.0 90044.5 90771.5 91015.0 91660.5 11163.2 11471.8 11534.8 11540.8 11763.5 16192.4 16210.8 16258.6 16288.4 16294.0 8613.4 8619.9 8719.2 8846.8 8916.5 8625.7 8669.8 8848.4 8852.7 9068.9 4964.3 4977.1 5012.2 5017.5 5020.5 2048.8 2073.9 2102.9 2118.9 2123.1 922.5 1046.0 1050.5 1065.2 1091.7 20455.5 20826.5 20975.8 20995.2 21184.6 1015.2 1016.1 1026.5 1046.5 1051.2 11149.6 11569.8 11624.2 11692.2 11780.9 497007.0 500195.0 503001.0 505155.0 517332.0 91034.5 93193.5 103536.0 103867.0 104781.5 1073.1 1104.3 1127.5 1129.6 1136.8 18458.6 18874.4 19182.9 19212.2 19341.4 42434.0 43121.1 43360.0 43816.1 44664.8 59482.0 59925.7 60969.7 62383.4 62536.0 9068.5 9165.0 9200.5 9322.3 9403.4 12366.8 14050.4 14172.4 14533.1 14619.8 11524.3 11526.1 12227.8 12379.6 12441.6 11672.5 11752.6 11876.7 12367.6 12736.6 5782.6 9073.6 11004.6 12025.6 12279.5 9047.7 9122.1 9130.8 9426.2 9456.8 11224.8 11863.4 11863.9 12104.4 12121.6 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#3] mootools.html Cycles:180 Average:6250.83 Median:4827.74 stddev:3524.94 (73.0%) stddev-sans-first:3517.33 07:19:16 INFO - PROCESS | 19975 | Values: 1577.2 1591.2 1791.3 1867.6 1895.3 8630.1 9134.5 9242.3 10134.4 10159.5 9116.7 9410.7 9426.6 9432.5 9461.0 2519.3 2563.8 2569.9 2573.7 2575.4 2498.3 2594.1 2605.8 2613.2 2613.5 2564.5 2579.7 2611.3 2613.1 2613.8 2477.5 2500.8 2513.4 2535.7 2555.4 2434.0 2455.3 2459.8 2503.3 2541.3 9553.7 9554.4 9603.9 9643.3 9643.5 5650.7 5711.6 5762.7 5792.3 5804.9 3527.2 3590.4 3652.8 3669.4 3727.9 1109.2 1114.7 1119.7 1129.2 1132.8 13449.3 13547.9 13623.7 13659.5 13747.2 4862.0 4999.0 5169.3 5244.5 5263.7 11301.3 11336.6 11379.3 11382.8 11394.4 5864.8 5901.0 5936.1 5941.9 5949.1 4853.0 5214.7 5297.9 5344.0 5482.4 4388.1 4421.9 4455.5 4474.6 4513.0 1368.8 1385.9 1398.9 1402.6 1406.2 1063.9 1105.7 1108.0 1111.0 1125.7 6811.5 6811.5 6828.4 6847.8 6903.4 494.8 510.4 512.8 512.8 517.1 4759.2 4761.8 4787.7 4802.5 4872.1 11277.7 11766.9 11811.2 11822.2 11834.8 11087.7 11213.9 11255.8 11376.7 11542.0 8444.0 8659.1 8701.1 8711.8 8737.3 6242.9 6278.5 6338.4 6404.2 6418.4 10164.7 10418.4 10430.3 10470.8 10481.0 10582.4 10656.3 10672.7 10820.7 10938.5 7912.9 8226.6 8385.3 8429.1 8522.2 9235.4 9247.8 9258.2 9317.8 9334.5 8010.0 8124.8 8126.9 8183.4 8385.6 7635.3 7635.5 7711.3 7831.4 7982.1 7209.6 7276.1 7280.5 7293.3 7346.4 5472.8 5483.5 5627.9 5642.1 5703.5 5768.2 5802.7 5806.2 5842.3 5878.1 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#4] prototype.html Cycles:180 Average:5098.53 Median:5718.79 stddev:1519.51 (26.6%) stddev-sans-first:1481.18 07:19:16 INFO - PROCESS | 19975 | Values: 338.8 351.7 352.5 353.6 354.9 5700.8 5786.6 5837.8 5918.9 5949.6 5784.3 5793.0 5814.5 5815.4 5837.0 4512.6 4528.3 4536.7 4541.4 4560.4 4465.3 4533.0 4570.5 4573.5 4617.8 4499.1 4509.9 4519.8 4520.6 4529.5 4507.3 4518.9 4536.3 4549.8 4572.8 3924.3 4020.2 4048.5 4050.2 4066.3 5678.1 5708.8 5710.8 5711.2 5742.0 5129.8 5149.9 5210.9 5214.8 5219.9 5938.6 6092.3 6111.4 6117.6 6148.5 4391.6 4420.5 4425.9 4431.2 4435.9 6279.0 6385.9 6401.9 6419.7 6446.6 4448.4 4471.6 4489.4 4489.9 4519.8 4550.4 4556.5 4562.0 4565.9 4575.9 5086.9 5198.6 5208.9 5217.5 5233.0 5956.7 6034.9 6116.8 6132.4 6203.3 1517.9 1547.8 1548.3 1556.9 1559.1 969.5 971.9 995.0 996.9 1007.3 5255.8 5263.7 5266.2 5266.3 5276.6 5542.6 5557.4 5569.2 5577.7 5589.7 4979.0 5225.4 5285.9 5299.7 5355.0 3003.6 3018.4 3035.4 3041.5 3062.8 5726.4 5736.9 5761.2 5809.7 5835.4 6028.5 6470.6 6475.7 6486.8 6516.4 6562.2 6622.3 6644.9 6648.9 6665.8 6155.8 6342.3 6349.3 6353.8 6379.6 6297.8 6312.4 6336.7 6341.4 6368.4 6446.5 6614.0 6635.6 6669.2 6681.7 4422.9 4475.7 4487.4 4504.3 4510.6 6136.4 6144.2 6473.5 6499.4 6506.2 6084.3 6102.9 6116.8 6134.6 6187.9 6079.6 6204.7 6212.6 6231.4 6304.5 6082.4 6116.0 6145.8 6153.4 6157.6 6236.3 6239.9 6259.8 6275.7 6303.0 5810.5 6071.8 6079.7 6108.9 6113.5 07:19:16 INFO - PROCESS | 19975 | 07:19:16 INFO - PROCESS | 19975 | [#5] yui.html Cycles:180 Average:23685.05 Median:4210.05 stddev:35025.25 (831.9%) stddev-sans-first:35081.42 07:19:16 INFO - PROCESS | 19975 | Values: 819.1 831.3 839.9 906.5 928.3 15811.0 17224.4 17297.3 17442.5 17503.4 16234.4 16424.5 16632.5 16635.2 16645.3 853.7 881.6 893.2 894.4 895.1 876.7 887.3 888.2 891.2 893.0 944.0 954.7 955.6 955.6 957.0 930.6 931.1 935.5 936.2 937.9 692.9 694.1 696.9 698.8 699.3 16089.4 16121.8 16281.8 16318.8 16370.1 14390.0 14503.8 14612.6 15222.2 16261.3 14353.4 14612.4 14675.4 14873.0 14941.4 4786.8 4825.4 4825.7 4827.5 4919.3 93911.5 94285.5 95162.5 95304.0 95501.0 12506.5 12624.7 12639.4 12842.4 12876.8 22687.5 22696.9 22714.2 22812.5 22841.9 9158.1 9234.7 9441.4 9556.7 9670.7 4904.1 4936.1 4958.8 5019.4 5143.4 3700.7 3775.3 3788.6 3803.8 3806.2 646.2 652.9 660.6 665.1 669.8 630.5 699.2 749.5 761.5 772.2 9771.9 10207.3 10410.6 10731.8 10886.8 719.7 753.2 766.6 787.3 790.5 4613.9 4814.8 4816.2 4830.3 4837.6 126067.0 136761.0 136773.0 138660.0 140941.0 115751.9 116220.1 118229.4 120371.0 122086.0 62483.0 65650.3 89658.6 102359.0 105755.5 9072.4 9100.7 9176.5 9237.4 9260.9 61404.4 61987.0 62145.4 63380.0 63695.4 72525.6 82141.0 89553.6 90316.0 91294.0 13944.7 14091.9 14808.0 14959.9 15257.2 16073.9 16344.0 16411.3 16577.8 16944.8 13843.3 14327.2 14716.6 15095.8 15204.4 15164.5 15325.7 15373.8 15589.0 16044.8 13807.6 14411.9 14712.7 14930.4 15358.0 11657.5 12352.4 12886.9 12912.9 13043.3 12104.9 12342.6 12416.6 12456.4 13046.0 07:19:16 INFO - PROCESS | 19975 | -------- Summary: end -------- 07:19:16 INFO - PROCESS | 19975 | 07:19:17 INFO - TEST-INFO | 19975: exit 0 07:19:17 INFO - TEST-OK | dromaeo_css | took 1111219ms 07:19:17 INFO - TEST-START | kraken 07:19:17 INFO - operating with platform_type : linux_ 07:19:17 INFO - Initialising browser for kraken test... 07:19:17 INFO - TEST-INFO | started process 20245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Tf2bk/profile http://localhost:49164/getInfo.html) 07:19:22 INFO - PROCESS | 20245 | __metrics Screen width/height:1600/1200 07:19:22 INFO - PROCESS | 20245 | colorDepth:24 07:19:22 INFO - PROCESS | 20245 | Browser inner width/height: 1024/697 07:19:22 INFO - PROCESS | 20245 | __metrics 07:19:22 ERROR - PROCESS | 20245 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:23 INFO - TEST-INFO | 20245: exit 0 07:19:23 INFO - Browser initialized. 07:19:23 INFO - Running cycle 1/1 for kraken test... 07:19:23 INFO - TEST-INFO | started process 20372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Tf2bk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:35 INFO - PROCESS | 20372 | RSS: Main: 152678400 07:19:35 INFO - PROCESS | 20372 | 07:19:37 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49164/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:38 INFO - PROCESS | 20372 | RSS: Main: 157753344 07:19:38 INFO - PROCESS | 20372 | 07:19:41 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49164/tests/kraken/driver.html?testName=audio-dft) 07:19:42 INFO - PROCESS | 20372 | RSS: Main: 156540928 07:19:42 INFO - PROCESS | 20372 | 07:19:44 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49164/tests/kraken/driver.html?testName=audio-fft) 07:19:45 INFO - PROCESS | 20372 | RSS: Main: 156815360 07:19:45 INFO - PROCESS | 20372 | 07:19:47 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49164/tests/kraken/driver.html?testName=audio-oscillator) 07:19:48 INFO - PROCESS | 20372 | RSS: Main: 156700672 07:19:48 INFO - PROCESS | 20372 | 07:19:50 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49164/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:51 INFO - PROCESS | 20372 | RSS: Main: 156340224 07:19:51 INFO - PROCESS | 20372 | 07:19:54 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49164/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:54 INFO - PROCESS | 20372 | RSS: Main: 155578368 07:19:54 INFO - PROCESS | 20372 | 07:19:58 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49164/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:58 INFO - PROCESS | 20372 | RSS: Main: 157085696 07:19:58 INFO - PROCESS | 20372 | 07:20:01 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49164/tests/kraken/driver.html?testName=json-parse-financial) 07:20:02 INFO - PROCESS | 20372 | RSS: Main: 157319168 07:20:02 INFO - PROCESS | 20372 | 07:20:03 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49164/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:04 INFO - PROCESS | 20372 | RSS: Main: 156663808 07:20:04 INFO - PROCESS | 20372 | 07:20:06 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:06 INFO - PROCESS | 20372 | RSS: Main: 156950528 07:20:06 INFO - PROCESS | 20372 | 07:20:08 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:09 INFO - PROCESS | 20372 | RSS: Main: 157233152 07:20:09 INFO - PROCESS | 20372 | 07:20:12 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:12 INFO - PROCESS | 20372 | RSS: Main: 157896704 07:20:12 INFO - PROCESS | 20372 | 07:20:15 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:16 INFO - PROCESS | 20372 | RSS: Main: 157687808 07:20:16 INFO - PROCESS | 20372 | 07:20:17 INFO - PROCESS | 20372 | Cycle 1(1): loaded http://localhost:49164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49164/tests/kraken/driver.html?testName=ai-astar) 07:20:18 INFO - PROCESS | 20372 | RSS: Main: 158593024 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | __start_tp_report 07:20:18 INFO - PROCESS | 20372 | _x_x_mozilla_page_load 07:20:18 INFO - PROCESS | 20372 | _x_x_mozilla_page_load_details 07:20:18 INFO - PROCESS | 20372 | |i|pagename|runs| 07:20:18 INFO - PROCESS | 20372 | |0;ai-astar;100;111;106;110;111;111;111;111;110;112 07:20:18 INFO - PROCESS | 20372 | |1;audio-beat-detection;145;116;145;168;122;144;147;119;167;123 07:20:18 INFO - PROCESS | 20372 | |2;audio-dft;162;163;162;162;163;162;162;162;167;163 07:20:18 INFO - PROCESS | 20372 | |3;audio-fft;75;76;76;167;77;75;77;77;182;76 07:20:18 INFO - PROCESS | 20372 | |4;audio-oscillator;84;83;154;84;83;83;150;84;84;153 07:20:18 INFO - PROCESS | 20372 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;118;118;117 07:20:18 INFO - PROCESS | 20372 | |6;imaging-darkroom;147;149;147;147;146;147;146;147;148;147 07:20:18 INFO - PROCESS | 20372 | |7;imaging-desaturate;95;95;96;95;96;95;96;95;95;96 07:20:18 INFO - PROCESS | 20372 | |8;json-parse-financial;52;51;51;51;54;52;51;53;52;52 07:20:18 INFO - PROCESS | 20372 | |9;json-stringify-tinderbox;61;61;61;60;60;60;59;60;60;61 07:20:18 INFO - PROCESS | 20372 | |10;stanford-crypto-aes;74;74;72;75;73;72;74;82;75;78 07:20:18 INFO - PROCESS | 20372 | |11;stanford-crypto-ccm;130;123;119;125;119;139;125;122;150;118 07:20:18 INFO - PROCESS | 20372 | |12;stanford-crypto-pbkdf2;152;157;155;157;154;153;157;152;155;153 07:20:18 INFO - PROCESS | 20372 | |13;stanford-crypto-sha256-iterative;62;66;68;62;63;63;63;62;62;61 07:20:18 INFO - PROCESS | 20372 | __end_tp_report 07:20:18 INFO - PROCESS | 20372 | __start_cc_report 07:20:18 INFO - PROCESS | 20372 | _x_x_mozilla_cycle_collect,895 07:20:18 INFO - PROCESS | 20372 | __end_cc_report 07:20:18 INFO - PROCESS | 20372 | __startTimestamp1472739618053__endTimestamp 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | ------- Summary: start ------- 07:20:18 INFO - PROCESS | 20372 | Number of tests: 14 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:3.65 (3.3%) stddev-sans-first:1.73 07:20:18 INFO - PROCESS | 20372 | Values: 100.0 111.0 106.0 110.0 111.0 111.0 111.0 111.0 110.0 112.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:145.00 stddev:19.02 (13.1%) stddev-sans-first:20.07 07:20:18 INFO - PROCESS | 20372 | Values: 145.0 116.0 145.0 168.0 122.0 144.0 147.0 119.0 167.0 123.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.50 stddev:1.55 (1.0%) stddev-sans-first:1.62 07:20:18 INFO - PROCESS | 20372 | Values: 162.0 163.0 162.0 162.0 163.0 162.0 162.0 162.0 167.0 163.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#3] audio-fft Cycles:10 Average:95.80 Median:76.00 stddev:41.64 (54.8%) stddev-sans-first:43.48 07:20:18 INFO - PROCESS | 20372 | Values: 75.0 76.0 76.0 167.0 77.0 75.0 77.0 77.0 182.0 76.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:83.50 stddev:33.23 (39.8%) stddev-sans-first:34.44 07:20:18 INFO - PROCESS | 20372 | Values: 84.0 83.0 154.0 84.0 83.0 83.0 150.0 84.0 84.0 153.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:20:18 INFO - PROCESS | 20372 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 118.0 118.0 117.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 07:20:18 INFO - PROCESS | 20372 | Values: 147.0 149.0 147.0 147.0 146.0 147.0 146.0 147.0 148.0 147.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:20:18 INFO - PROCESS | 20372 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 07:20:18 INFO - PROCESS | 20372 | Values: 52.0 51.0 51.0 51.0 54.0 52.0 51.0 53.0 52.0 52.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:20:18 INFO - PROCESS | 20372 | Values: 61.0 61.0 61.0 60.0 60.0 60.0 59.0 60.0 60.0 61.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:3.03 (4.1%) stddev-sans-first:3.20 07:20:18 INFO - PROCESS | 20372 | Values: 74.0 74.0 72.0 75.0 73.0 72.0 74.0 82.0 75.0 78.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:125.00 stddev:10.22 (8.2%) stddev-sans-first:10.78 07:20:18 INFO - PROCESS | 20372 | Values: 130.0 123.0 119.0 125.0 119.0 139.0 125.0 122.0 150.0 118.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:155.00 stddev:2.01 (1.3%) stddev-sans-first:1.92 07:20:18 INFO - PROCESS | 20372 | Values: 152.0 157.0 155.0 157.0 154.0 153.0 157.0 152.0 155.0 153.0 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - PROCESS | 20372 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:2.15 (3.4%) stddev-sans-first:2.24 07:20:18 INFO - PROCESS | 20372 | Values: 62.0 66.0 68.0 62.0 63.0 63.0 63.0 62.0 62.0 61.0 07:20:18 INFO - PROCESS | 20372 | -------- Summary: end -------- 07:20:18 INFO - PROCESS | 20372 | 07:20:18 INFO - TEST-INFO | 20372: exit 0 07:20:19 INFO - TEST-OK | kraken | took 61632ms 07:20:19 INFO - SUITE-END | took 1172s 07:20:19 INFO - Completed test suite (00:19:33) 07:20:19 INFO - javascript benchmark 07:20:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7804.800258306274, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.851416686249, 3308.3548708001936, 3324.58442694663, 3348.330333933211, 3362.6805453481925, 5885.382034886334, 5909.143174239733, 5935.584509084366, 5964.671943043131, 5976.462118409348, 5813.593048486608, 5861.267341582302, 5863.560232982522, 5873.735681894314, 5879.206307148534, 5315.229291752694, 5337.786488540453, 5383.730813459331, 5392.083345831198, 5441.374241962166, 5249.580033597303, 5256.526912577867, 5284.867878303035, 5395.406505284415, 5428.077226871422, 5354.678719276856, 5380.757865896044, 5395.028894798935, 5455.0453670607685, 5545.805896793613, 5413.377461591908, 5439.9936011837935, 5461.317335333083, 5467.20725494805, 5468.64453810505, 5212.322398088262, 5212.50481204285, 5269.552088072518, 5343.198520221959, 5455.372632147274, 5742.655440673531, 5837.941620583782, 5853.882922341572, 5863.442972917566, 5889.204957330749, 6252.093446450284, 6296.527760417941, 6313.273973493061, 6317.6209427434505, 6319.6412771253945, 6090.96954515229, 6200.410960958701, 6329.303776584572, 6351.650659213788, 6373.26707428647, 5828.737706803204, 5909.468147866713, 5914.645121292736, 5943.4056594340655, 5946.791862284843, 6481.935180648227, 6483.351664833479, 6511.0, 6521.413072823374, 6557.901631475532, 6051.7579296827635, 6060.393960603992, 6234.875302494015, 6242.282137554149, 6292.276388215414, 6137.8060289514, 6265.091561723616, 6301.369863013753, 6308.927482328016, 6315.5263355247625, 6278.748850046039, 6293.30773614902, 6299.5275354348605, 6304.590201636879, 6318.557700960981, 6051.878962420815, 6180.907286390708, 6303.747850086037, 6305.936940630627, 6324.2410910691015, 4754.120487709784, 4779.976100119477, 4795.424549054136, 4831.903361932742, 4841.104395686809, 4255.127698821734, 4278.251306021434, 4294.592013758688, 4324.632406245504, 4326.199653064194, 5896.823095307148, 5945.1973983512535, 6025.0661147522205, 6071.544634152457, 6083.695815209222, 6185.628862268189, 6330.430261276415, 6356.968215158987, 6369.331220221903, 6383.329750376237, 6051.727672254759, 6067.878642427127, 6098.878022439615, 6128.17269668598, 6130.816075517741, 5370.0333939890415, 5398.379186393537, 5467.617266791286, 5470.64440811346, 5479.479449452296, 6609.438197753245, 6613.206415230107, 6614.669266536654, 6622.1722284714415, 6626.171728533934, 6354.046892966092, 6364.331745166876, 6366.108744775827, 6380.840478988062, 6386.201724784402, 6292.748290068346, 6340.30256671775, 6421.0, 6604.207495100619, 6620.073189753342, 6177.1969643945995, 6202.503799696104, 6211.0, 6215.34738852414, 6218.782342617987, 6267.059941008885, 6314.905276420949, 6327.0, 6345.365463453618, 6381.9469787486405, 5906.438888305604, 6070.817875463743, 6127.785527506516, 6190.473809726043, 6193.009118541192, 6156.753729850756, 6209.5963762357105, 6214.223222097238, 6216.658083805253, 6225.875482490415, 6154.846128846814, 6161.199044124235, 6203.100550420254, 6213.937860621336, 6215.689215539295, 6016.916954948151, 6047.365026672136, 6078.6048906820915, 6235.625862448268, 6239.563230573816, 5940.851478713067, 5996.4902983245165, 6027.698615069141, 6043.36544662804, 6050.152978582913], "value": 5812.161908614103, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.4629594477137, 625.8091282158941, 643.0964494884689, 643.8229486891105, 659.6701649175412, 47874.52125478744, 48988.51011489884, 49489.0, 49706.25440618385, 53469.1979620306, 48943.51056489434, 49572.25641615379, 49652.75173624136, 49739.25391119136, 49792.50207497924, 9228.169464748154, 9345.485998270093, 9369.718908432758, 9383.906160938372, 9387.859182112274, 9197.356185067049, 9234.6306147754, 9258.814823703522, 9261.583228754722, 9279.886413630375, 9328.207102396305, 9367.625294988193, 9368.437893726365, 9378.109079637457, 9432.386894851847, 9195.310351723614, 9229.07709229075, 9251.259899208047, 9262.444253344753, 9321.067893210658, 9161.954190229073, 9198.954005229998, 9238.122378374044, 9257.814843703089, 9302.30232732541, 46242.76878615585, 46269.0, 46556.0, 46638.76680616575, 46662.06675866498, 3940.3892396678275, 4156.210320039213, 4333.349997500401, 4470.307102399133, 4631.3516107744945, 35222.471662925076, 35500.822495887354, 35638.0, 35662.0, 35728.28543429117, 4596.770161491912, 4631.466201205968, 4655.604273636745, 4736.52634736527, 4761.523847615211, 261785.0, 263732.0, 266555.0, 266968.0, 267456.9999999981, 66811.66594167096, 67233.0, 67407.0, 67421.66289168522, 67624.66187669129, 62637.0, 69044.6547767268, 70729.29270707227, 71649.64175179091, 71911.0, 19287.0, 19421.805781942283, 19423.611527769648, 19471.805281947283, 19560.804391956182, 14192.503262385768, 14205.502807401692, 14234.288285585679, 14305.141691498544, 14336.856631433553, 19808.40574782759, 19890.0, 20124.698129528068, 20278.898605506878, 20368.28710995108, 6916.308369163144, 7301.452391070691, 7302.3792977597495, 7344.706211751577, 7424.48028638001, 1011.4993078426079, 1075.800976819291, 1079.6707004363745, 1213.1447329632588, 1241.6957845327772, 32822.835885820896, 33512.664873351445, 33589.83205083959, 33915.15212119717, 34089.488657670154, 1126.0890597804766, 1138.1805100327751, 1142.497301187475, 1145.5303325636448, 1239.2874097393853, 17127.914360428367, 17835.10824458772, 17912.462626121236, 17929.0, 18068.277268909096, 141349.0, 141811.29094354462, 142005.28997354946, 142076.0, 143034.0, 62095.37904620896, 62682.373176268564, 62726.686366568785, 62740.0, 62855.37144628495, 1425.964350891236, 1444.4366696988184, 1460.145814698392, 1477.0325436838887, 1488.1220080152762, 30736.846315768278, 32816.671833282315, 32934.34131317312, 33069.66930330666, 33776.986690399324, 55443.16835247394, 55757.88484230211, 55790.163147552004, 55984.44015559792, 56034.15948760691, 366496.0, 367148.0, 368029.0, 368361.0, 368914.0, 45794.771026144655, 46412.719311370805, 46620.06759864716, 46866.0, 47376.289355659006, 51480.22779658383, 51545.0, 51551.2267315998, 51594.48405516047, 52019.47980520147, 42835.0, 43642.52773276829, 44141.77929110334, 44144.779276103414, 44799.0, 42132.78933605312, 42221.0, 42829.78585107055, 44492.77753611211, 44867.775661121486, 43262.0, 43718.78140609276, 43869.78065109654, 44866.327005095605, 44980.32529511995, 36776.26447471089, 38973.41539876848, 39049.14186142051, 39260.0, 39660.60339396569, 29579.0, 29649.25876853096, 30180.84909575438, 30395.696043039286, 30529.23676908095], "value": 20808.245453880638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.4476491031837, 419.5594625643074, 427.9893002674934, 429.0, 441.0847491455229, 20253.392398228058, 20437.386878393634, 20989.68515472269, 21034.368968930918, 21238.57522849542, 19980.100895459702, 20827.27104551341, 20947.266845660404, 20995.160193592277, 21242.89378553109, 5742.425757424245, 5926.614770039933, 5935.406459354052, 5939.020061689816, 5962.642241465504, 5812.157237200603, 5858.326292476378, 5884.146798714184, 5885.970570147165, 5898.085796701518, 5654.349749778787, 5807.535397168216, 5832.9708351458185, 5856.619319744203, 5858.0, 5698.487136157766, 5764.654120752747, 5802.854928626776, 5835.562332825034, 5872.442117998783, 5218.791248350061, 5286.3127793387, 5301.310829592128, 5307.6019298552765, 5311.654742441729, 24870.0, 25398.365040874127, 25417.0, 25433.237002889757, 25452.87273563639, 2578.355411147213, 2626.290901456596, 2694.7170547092664, 2704.594310853388, 2709.525832979221, 15268.38926442941, 15403.460878869186, 15550.61123471911, 16174.595635109099, 16270.674586508203, 2005.5587770690424, 2085.3952353817376, 2133.4133113393787, 2185.9344219673435, 2266.4447210433386, 86679.0, 90044.54977725135, 90771.54614226952, 91015.0, 91660.54169729176, 11163.218574699855, 11471.827922581168, 11534.826977595343, 11540.769184616262, 11763.529458821551, 16192.43326483591, 16210.83789162117, 16258.593535161715, 16288.42990495327, 16294.0, 8613.440126391764, 8619.870701939475, 8719.215270625675, 8846.823063538823, 8916.465012099296, 8625.69810056645, 8669.783255418664, 8848.380613357003, 8852.690155844515, 9068.866391701038, 4964.255361695702, 4977.0792403405485, 5012.1980483122525, 5017.548420642087, 5020.472850350734, 2048.8463365247667, 2073.8651987620756, 2102.8843413612094, 2118.9046492907855, 2123.108294516307, 922.4511415707777, 1045.9895401046044, 1050.495762034228, 1065.2223876570147, 1091.748897753521, 20455.4886127848, 20826.47933801667, 20975.79024209769, 20995.16019359239, 21184.607446217156, 1015.2030655935056, 1016.0753714120116, 1026.5380578739598, 1046.5186014433446, 1051.2115913065202, 11149.554017839358, 11569.76860462786, 11624.186306958432, 11692.181547291608, 11780.941095294469, 497007.0, 500195.0, 503001.0, 505155.0, 517332.0, 91034.54482727677, 93193.53403232941, 103536.0, 103867.0, 104781.47609261905, 1073.120041565924, 1104.2711810205387, 1127.4982632728356, 1129.6215767717906, 1136.8351589019712, 18458.63082738311, 18874.43376698701, 19182.90408548004, 19212.231510739974, 19341.430472421423, 42434.0, 43121.137577248905, 43360.0, 43816.123677526906, 44664.77667611641, 59482.0, 59925.70037149961, 60969.695151523956, 62383.37616623957, 62536.0, 9068.546572671472, 9165.037671044443, 9200.493972831558, 9322.254219662547, 9403.388779729295, 12366.814497782361, 14050.437982480586, 14172.43310267619, 14533.128012319296, 14619.780703289247, 11524.250923689933, 11526.135539834379, 12227.816582751087, 12379.62861114168, 12441.626751197116, 11672.47473863695, 11752.58865939688, 11876.703082423008, 12367.567135150226, 12736.61790146297, 5782.5952183348445, 9073.59168837417, 11004.614838480615, 12025.63923082309, 12279.508819647113, 9047.728568142966, 9122.087791220825, 9130.817383652422, 9426.198773104363, 9456.810863782823, 11224.775504490028, 11863.406829658656, 11863.881361186277, 12104.394780261127, 12121.636350909486], "value": 9821.437030406392, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1577.203512226326, 1591.1725902530682, 1791.3372052340635, 1867.570458794478, 1895.2703209264437, 8630.093840146772, 9134.451932884032, 9242.30682698797, 10134.442605656686, 10159.492025398737, 9116.726498205064, 9410.670626528074, 9426.622935082589, 9432.52837358131, 9461.006594307602, 2519.3449703077194, 2563.8333508321994, 2569.922902312924, 2573.74262573743, 2575.394782226185, 2498.3129639349145, 2594.0661361909697, 2605.7524535169127, 2613.242159773673, 2613.542630039745, 2564.4614630927435, 2579.690437147545, 2611.2949503634104, 2613.1376645706964, 2613.8301010434357, 2477.541654793869, 2500.8374455660323, 2513.39678477166, 2535.6830396200476, 2555.3611597100726, 2434.0, 2455.300239431753, 2459.827812053157, 2503.324102492317, 2541.275736415113, 9553.665621703276, 9554.378965367228, 9603.855942160873, 9643.324967252294, 9643.517824108836, 5650.689212093374, 5711.600187986843, 5762.740676669518, 5792.275965504312, 5804.912926306109, 3527.153483164022, 3590.425531914881, 3652.7990960497164, 3669.4495825626104, 3727.8881633550764, 1109.2346281066039, 1114.6656003199007, 1119.6696974392573, 1129.232122156933, 1132.824412216108, 13449.260290684106, 13547.932260338635, 13623.727525449436, 13659.521916732967, 13747.243901585409, 4861.955546695889, 4999.0, 5169.276301317818, 5244.501772331622, 5263.736813159327, 11301.26541774782, 11336.6032188873, 11379.260348077349, 11382.772344553063, 11394.37330946806, 5864.794732184353, 5901.0, 5936.050231962951, 5941.851453713605, 5949.137375080589, 4852.95147048525, 5214.713190774544, 5297.867553311198, 5343.973280133574, 5482.396936337, 4388.1223755249075, 4421.911561768747, 4455.487618923865, 4474.574915383034, 4513.029698614813, 1368.7946807978683, 1385.9445622175201, 1398.930053497321, 1402.5792262321263, 1406.2406300597768, 1063.8563793887881, 1105.729096371378, 1107.9612213572648, 1111.0389513071136, 1125.7298248420484, 6811.489138314646, 6811.489138314646, 6828.351306625863, 6847.828804279933, 6903.447724182053, 494.8144445832813, 510.4181233393934, 512.8051340490664, 512.8153864608738, 517.056372120881, 4759.214729569651, 4761.833335833229, 4787.736674482867, 4802.51974802517, 4872.098661747588, 11277.71805704864, 11766.941165294118, 11811.173217874666, 11822.172447928562, 11834.82247766267, 11087.667369978913, 11213.887861121284, 11255.831162532568, 11376.715582110513, 11542.0, 8444.0, 8659.090795466385, 8701.129887011375, 8711.825763484821, 8737.257333126629, 6242.875142497215, 6278.466330361788, 6338.366163383809, 6404.231492220963, 6418.422341989243, 10164.745881353025, 10418.374897506174, 10430.26988110825, 10470.790584188122, 10481.0, 10582.417967011888, 10656.25406221557, 10672.679819605424, 10820.67537973862, 10938.45307734626, 7912.9208707912185, 8226.588670566567, 8385.287250583524, 8429.072801991899, 8522.190391913007, 9235.353525253167, 9247.815043698954, 9258.166764991183, 9317.767055823659, 9334.486603236886, 8009.959950200445, 8124.837503249783, 8126.878096828434, 8183.3862460316905, 8385.58072096405, 7635.312821846061, 7635.541867487968, 7711.305982461606, 7831.373490120663, 7982.08206056311, 7209.6034718091005, 7276.090488688914, 7280.453965952681, 7293.307135822089, 7346.449016323691, 5472.835814925558, 5483.4516548344845, 5627.8874422512135, 5642.125470552071, 5703.458171473703, 5768.1636162757, 5802.709864506842, 5806.158107074366, 5842.298924129132, 5878.147668587945], "value": 4944.69921971532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.771329352687, 351.7361978516113, 352.54697713438236, 353.5562868599907, 354.9183687751817, 5700.7719691212405, 5786.643746626957, 5837.795677151301, 5918.852028699284, 5949.613275137113, 5784.3058832940105, 5792.971035144818, 5814.5057670098095, 5815.38938411467, 5837.0, 4512.63898888089, 4528.343390208418, 4536.682432229745, 4541.43232095988, 4560.3843481130125, 4465.330200469923, 4533.0480599074235, 4570.520095389986, 4573.474050484204, 4617.792199351037, 4499.055198408339, 4509.864704058883, 4519.819207231707, 4520.593146616804, 4529.456465224178, 4507.301368287905, 4518.864434066983, 4536.319552067183, 4549.772511374451, 4572.839950601713, 3924.2936271471126, 4020.175863947913, 4048.473698419216, 4050.2102090092444, 4066.2883995301, 5678.148277758328, 5708.800191993302, 5710.77156913723, 5711.200431939532, 5742.047703780693, 5129.769160387791, 5149.871253218662, 5210.921836172422, 5214.765335559871, 5219.921701174486, 5938.584299099065, 6092.268927728701, 6111.449969502722, 6117.632942023492, 6148.477379422754, 4391.604755572014, 4420.4803262140595, 4425.867223983286, 4431.246688063037, 4435.889102772457, 6278.968605157036, 6385.936140638581, 6401.935980640227, 6419.743210271584, 6446.613203207822, 4448.421705178371, 4471.597556219956, 4489.416375871116, 4489.865304040884, 4519.7740112994215, 4550.362949187116, 4556.476005259437, 4562.0, 4565.863024109282, 4575.931361029588, 5086.862269108227, 5198.584113270929, 5208.895822083613, 5217.530422262014, 5232.973835130876, 5956.702164891824, 6034.9396506035255, 6116.755329786848, 6132.356102609251, 6203.294094124152, 1517.87856971442, 1547.81426228851, 1548.3355392952622, 1556.8832337574727, 1559.0567706537609, 969.471637957315, 971.8882328532168, 994.9503274045807, 996.9152622027209, 1007.3049595778889, 5255.78976840922, 5263.683879156184, 5266.236395722599, 5266.262723218676, 5276.551493123051, 5542.63972841764, 5557.416471270459, 5569.162176481832, 5577.74900129487, 5589.720513974285, 4978.991911511268, 5225.399079105951, 5285.894282114336, 5299.655522391032, 5354.973225133927, 3003.57949887016, 3018.3661431099713, 3035.362573859514, 3041.4981528048065, 3062.8162310261455, 5726.3700992890745, 5736.856578585569, 5761.164631128463, 5809.651420914759, 5835.387284335084, 6028.5478589106, 6470.611763294123, 6475.672487140125, 6486.83237017332, 6516.413522782972, 6562.212534495891, 6622.2715501294815, 6644.933550664528, 6648.867022659617, 6665.80002599913, 6155.753769849335, 6342.255879633138, 6349.333320001518, 6353.77761778354, 6379.617222966637, 6297.779577714758, 6312.400321969406, 6336.651484168231, 6341.397567231105, 6368.42684158428, 6446.5165112615805, 6613.957579513478, 6635.568688035262, 6669.23265334677, 6681.665916704243, 4422.867313980586, 4475.66432517569, 4487.371767952424, 4504.301833215857, 4510.616597589111, 6136.355682653436, 6144.2319710036245, 6473.482121430368, 6499.447546958561, 6506.186726659168, 6084.330723620486, 6102.877942441215, 6116.785912493041, 6134.601250918676, 6187.938120618736, 6079.57130074441, 6204.728030753595, 6212.596181248205, 6231.4391704746795, 6304.5271604628915, 6082.391760823883, 6115.969420152871, 6145.8156255312415, 6153.353897840662, 6157.568970172224, 6236.34518375564, 6239.906401404073, 6259.812205633838, 6275.717592708431, 6303.023031430135, 5810.461416069974, 6071.787487437919, 6079.726412311545, 6108.869859076085, 6113.5414843886], "value": 4607.59665427751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.0662644585174, 831.2560258568581, 839.8740188971657, 906.4923642760057, 928.336239588694, 15811.0, 17224.397146099887, 17297.308107675693, 17442.476725698216, 17503.38738144165, 16234.431794887181, 16424.507264782074, 16632.50102496927, 16635.168241587933, 16645.250963706632, 853.6542700206427, 881.5548148185158, 893.2050475077186, 894.4096896048609, 895.0780695883251, 876.7106854737953, 887.3034667785789, 888.1518150166576, 891.2246345679268, 892.959816808242, 943.9622415103388, 954.7422196007074, 955.5843208204453, 955.6464108279912, 956.9617215311414, 930.5626355612851, 931.0503286647581, 935.508857849631, 936.1621348892771, 937.9202767764714, 692.8510370270366, 694.1323345817727, 696.9128858892639, 698.8183072401162, 699.3251512290614, 16089.436869709622, 16121.83878161215, 16281.755773663286, 16318.83681163185, 16370.099644519547, 14390.0, 14503.782443263359, 14612.634684132876, 15222.238888055554, 16261.268242929096, 14353.425862965469, 14612.415503379852, 14675.412983480648, 14873.0, 14941.402343906231, 4786.7845946932475, 4825.445073816486, 4825.662203645747, 4827.541383568555, 4919.2867034279825, 93911.53044234804, 94285.52857235739, 95162.52418737861, 95304.0, 95501.0449895499, 12506.499740010298, 12624.684382890502, 12639.368031598384, 12842.357882105858, 12876.80684789729, 22687.546249075258, 22696.886515567647, 22714.205002824823, 22812.543749124925, 22841.88579057094, 9158.129977652112, 9234.676786312446, 9441.386309889835, 9556.713298601053, 9670.70987870365, 4904.117258893362, 4936.111499929975, 4958.752062396866, 5019.447860735389, 5143.434222235551, 3700.703943684499, 3775.2638235543805, 3788.6021967693546, 3803.7527560708463, 3806.219724956433, 646.1761254400583, 652.9249136349285, 660.5904339309659, 665.0921492163259, 669.8291935556402, 630.4735545819201, 699.1679900917892, 749.4678778067524, 761.5430741555023, 772.2432016623741, 9771.853422198672, 10207.336523126121, 10410.583576657, 10731.83902241467, 10886.782264354826, 719.7013239505546, 753.2053683363994, 766.6013672890066, 787.2835719495232, 790.5454363740872, 4613.8846528836375, 4814.759262036884, 4816.157172494799, 4830.2513110467935, 4837.56461918422, 126067.0, 136761.0, 136773.0, 138660.0, 140941.0, 115751.86748944597, 116220.09449763654, 118229.40885295518, 120371.0, 122086.0, 62483.0, 65650.34349656443, 89658.55170724104, 102358.95282094316, 105755.47122264493, 9072.364934454525, 9100.681476148304, 9176.495292758962, 9237.445753254826, 9260.907390926004, 61404.385956141654, 61987.0, 62145.37854621396, 63380.0, 63695.36304636894, 72525.6373718128, 82141.0, 89553.55223223842, 90316.0, 91294.0, 13944.721105578035, 14091.886217067522, 14808.0, 14959.850401495845, 15257.237138143271, 16073.919630401773, 16344.0, 16411.343546258493, 16577.751333729764, 16944.830551694817, 13843.307834608027, 14327.212003339917, 14716.55850324492, 15095.773563396777, 15204.391824326904, 15164.49793754852, 15325.693486130438, 15373.846261537241, 15589.0, 16044.839551604335, 13807.585772426843, 14411.855881441052, 14712.705745885236, 14930.402783888523, 15358.0, 11657.533698652298, 12352.444140013542, 12886.871131288568, 12912.935435322763, 13043.28261945602, 12104.93947530292, 12342.568010119961, 12416.627501174979, 12456.377181141088, 13046.0], "value": 8351.571418390427, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 106.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 112.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 145.0, 168.0, 122.0, 144.0, 147.0, 119.0, 167.0, 123.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 162.0, 163.0, 162.0, 162.0, 162.0, 167.0, 163.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 167.0, 77.0, 75.0, 77.0, 77.0, 182.0, 76.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 154.0, 84.0, 83.0, 83.0, 150.0, 84.0, 84.0, 153.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 148.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 54.0, 52.0, 51.0, 53.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 75.0, 73.0, 72.0, 74.0, 82.0, 75.0, 78.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 123.0, 119.0, 125.0, 119.0, 139.0, 125.0, 122.0, 150.0, 118.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 155.0, 157.0, 154.0, 153.0, 157.0, 152.0, 155.0, 153.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 68.0, 62.0, 63.0, 63.0, 63.0, 62.0, 62.0, 61.0], "value": 63.2, "unit": "score"}]}]} 07:20:19 INFO - Return code: 0 07:20:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:19 ERROR - # TBPL SUCCESS # 07:20:19 INFO - Running post-action listener: _resource_record_post_action 07:20:19 INFO - [mozharness: 2016-09-01 14:20:19.344308Z] Finished run-tests step (success) 07:20:19 INFO - Running post-run listener: _resource_record_post_run 07:20:19 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325849088; Read time: 12; Write time: 336900 07:20:19 INFO - TinderboxPrint: CPU usage
13.3% 07:20:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:20:19 INFO - TinderboxPrint: I/O write bytes / time
325,849,088 / 336,900 07:20:19 INFO - TinderboxPrint: CPU idle
8,227.3 (86.7%) 07:20:19 INFO - TinderboxPrint: CPU user
1,201.9 (12.7%) 07:20:19 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:19 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78454784; Read time: 0; Write time: 129728 07:20:19 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 246394880; Read time: 0; Write time: 207124 07:20:19 INFO - Running post-run listener: _upload_blobber_files 07:20:19 INFO - Blob upload gear active. 07:20:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:20:19 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:20:19 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:20:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:21 INFO - (blobuploader) - INFO - Done attempting. 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:22 INFO - (blobuploader) - INFO - Done attempting. 07:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:35 INFO - (blobuploader) - INFO - Done attempting. 07:20:35 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:35 INFO - Return code: 0 07:20:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc6cb3b49c7f5a32f57612f85dde0bdffbdab77012d5b7ddf8b90436f4fffa2a3e041125cddd2c2a936ca12951727f0ef87d6be65748d22a87e69ac1817efbc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e7ba04562b15058e068992cb074f00340f296ea1c430cbeb7eb919a9920fa7d342706307fa8c47a5565eb6f140f6442b17da199f60fb0470fe4c06ab7b61f38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b23eba39c137ddb985fdeefa727130bc68d32e44dec813e9b416e55c5f0cf10299886f7768f78b33ed0f2fa68d3bb1c6524d5281d72f1fb1eafe5e7a8b85d5de"} 07:20:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:35 INFO - Contents: 07:20:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc6cb3b49c7f5a32f57612f85dde0bdffbdab77012d5b7ddf8b90436f4fffa2a3e041125cddd2c2a936ca12951727f0ef87d6be65748d22a87e69ac1817efbc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e7ba04562b15058e068992cb074f00340f296ea1c430cbeb7eb919a9920fa7d342706307fa8c47a5565eb6f140f6442b17da199f60fb0470fe4c06ab7b61f38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b23eba39c137ddb985fdeefa727130bc68d32e44dec813e9b416e55c5f0cf10299886f7768f78b33ed0f2fa68d3bb1c6524d5281d72f1fb1eafe5e7a8b85d5de"} 07:20:35 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:35 INFO - Copying logs to upload dir... 07:20:35 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1686.897149 ========= master_lag: 2.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-01 07:20:36.046921) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 15 secs) (at 2016-09-01 07:20:36.054155) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc6cb3b49c7f5a32f57612f85dde0bdffbdab77012d5b7ddf8b90436f4fffa2a3e041125cddd2c2a936ca12951727f0ef87d6be65748d22a87e69ac1817efbc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e7ba04562b15058e068992cb074f00340f296ea1c430cbeb7eb919a9920fa7d342706307fa8c47a5565eb6f140f6442b17da199f60fb0470fe4c06ab7b61f38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b23eba39c137ddb985fdeefa727130bc68d32e44dec813e9b416e55c5f0cf10299886f7768f78b33ed0f2fa68d3bb1c6524d5281d72f1fb1eafe5e7a8b85d5de"} build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc6cb3b49c7f5a32f57612f85dde0bdffbdab77012d5b7ddf8b90436f4fffa2a3e041125cddd2c2a936ca12951727f0ef87d6be65748d22a87e69ac1817efbc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e7ba04562b15058e068992cb074f00340f296ea1c430cbeb7eb919a9920fa7d342706307fa8c47a5565eb6f140f6442b17da199f60fb0470fe4c06ab7b61f38", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b23eba39c137ddb985fdeefa727130bc68d32e44dec813e9b416e55c5f0cf10299886f7768f78b33ed0f2fa68d3bb1c6524d5281d72f1fb1eafe5e7a8b85d5de"}' ========= master_lag: 15.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 15 secs) (at 2016-09-01 07:20:51.539831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:51.540200) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1472737644.476547-323420039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004036 ========= master_lag: 2.62 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:54.164995) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:54.165388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:54.912014) ========= ========= Total master_lag: 20.32 =========