builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1471673090.9 results: success (0) buildid: 20160819202315 builduid: 215fb267a87248bdb558162da64d4cec revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:50.909965) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:50.910657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:50.911002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:50.932462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:50.932856) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 23:04:51-- 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% 18.6M=0.001s 2016-08-19 23:04:51 (18.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114168 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.061946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.062603) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015138 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.115822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.116229) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f1c9caedd87771d03d5e2808a4e821136679221 --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 6f1c9caedd87771d03d5e2808a4e821136679221 --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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 23:04:51,606 truncating revision to first 12 chars 2016-08-19 23:04:51,606 Setting DEBUG logging. 2016-08-19 23:04:51,606 attempt 1/10 2016-08-19 23:04:51,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6f1c9caedd87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 23:04:51,913 unpacking tar archive at: mozilla-aurora-6f1c9caedd87/testing/mozharness/ program finished with exit code 0 elapsedTime=0.640386 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.771793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.772129) ========= script_repo_revision: 6f1c9caedd87771d03d5e2808a4e821136679221 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.772615) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.773201) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 23:04:51.789805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-19 23:04:51.790244) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:04:52 INFO - MultiFileLogger online at 20160819 23:04:52 in /builds/slave/test 23:04:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:04:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:04:52 INFO - {'append_to_log': False, 23:04:52 INFO - 'base_work_dir': '/builds/slave/test', 23:04:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:04:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:04:52 INFO - 'branch': 'Mozilla-Aurora', 23:04:52 INFO - 'buildbot_json_path': 'buildprops.json', 23:04:52 INFO - 'config_files': ('talos/linux_config.py',), 23:04:52 INFO - 'default_actions': ('clobber', 23:04:52 INFO - 'read-buildbot-config', 23:04:52 INFO - 'download-and-extract', 23:04:52 INFO - 'populate-webroot', 23:04:52 INFO - 'create-virtualenv', 23:04:52 INFO - 'install', 23:04:52 INFO - 'run-tests'), 23:04:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:04:52 INFO - 'download_minidump_stackwalk': True, 23:04:52 INFO - 'download_symbols': 'ondemand', 23:04:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:04:52 INFO - 'tooltool.py': '/tools/tooltool.py', 23:04:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:04:52 INFO - '/tools/misc-python/virtualenv.py')}, 23:04:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:04:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:04:52 INFO - 'installer_path': 'installer.exe', 23:04:52 INFO - 'log_level': 'info', 23:04:52 INFO - 'log_name': 'talos', 23:04:52 INFO - 'log_to_console': True, 23:04:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:04:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:04:52 INFO - 'opt_config_files': (), 23:04:52 INFO - 'pip_index': False, 23:04:52 INFO - 'sps_profile': False, 23:04:52 INFO - 'sps_profile_interval': 0, 23:04:52 INFO - 'suite': 'dromaeojs', 23:04:52 INFO - 'system_bits': '32', 23:04:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:04:52 INFO - 'title': 'talos-linux64-ix-023', 23:04:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:04:52 INFO - 'use_talos_json': True, 23:04:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:04:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:04:52 INFO - 'work_dir': 'build'} 23:04:52 INFO - [mozharness: 2016-08-20 06:04:52.370444Z] Running clobber step. 23:04:52 INFO - Running pre-action listener: _resource_record_pre_action 23:04:52 INFO - Running main action method: clobber 23:04:52 INFO - rmtree: /builds/slave/test/build 23:04:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:04:53 INFO - Running post-action listener: _resource_record_post_action 23:04:53 INFO - [mozharness: 2016-08-20 06:04:53.349014Z] Finished clobber step (success) 23:04:53 INFO - [mozharness: 2016-08-20 06:04:53.349117Z] Running read-buildbot-config step. 23:04:53 INFO - Running pre-action listener: _resource_record_pre_action 23:04:53 INFO - Running main action method: read_buildbot_config 23:04:53 INFO - Using buildbot properties: 23:04:53 INFO - { 23:04:53 INFO - "project": "", 23:04:53 INFO - "product": "firefox", 23:04:53 INFO - "script_repo_revision": "production", 23:04:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:04:53 INFO - "repository": "", 23:04:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:04:53 INFO - "buildid": "20160819202315", 23:04:53 INFO - "pgo_build": "True", 23:04:53 INFO - "basedir": "/builds/slave/test", 23:04:53 INFO - "buildnumber": 478, 23:04:53 INFO - "slavename": "talos-linux64-ix-023", 23:04:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:04:53 INFO - "platform": "ubuntu64_hw", 23:04:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:04:53 INFO - "branch": "mozilla-aurora", 23:04:53 INFO - "repo_path": "releases/mozilla-aurora", 23:04:53 INFO - "revision": "6f1c9caedd87771d03d5e2808a4e821136679221", 23:04:53 INFO - "stage_platform": "linux64-pgo", 23:04:53 INFO - "builduid": "215fb267a87248bdb558162da64d4cec", 23:04:53 INFO - "slavebuilddir": "test" 23:04:53 INFO - } 23:04:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 23:04:53 INFO - Running post-action listener: _resource_record_post_action 23:04:53 INFO - [mozharness: 2016-08-20 06:04:53.351567Z] Finished read-buildbot-config step (success) 23:04:53 INFO - [mozharness: 2016-08-20 06:04:53.351656Z] Running download-and-extract step. 23:04:53 INFO - Running pre-action listener: _resource_record_pre_action 23:04:53 INFO - Running main action method: download_and_extract 23:04:53 INFO - mkdir: /builds/slave/test/build/tests 23:04:53 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')]} 23:04:53 INFO - https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:04:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:04:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:04:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:04:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:04:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:04:53 INFO - retry: Failed, sleeping 30 seconds before retrying 23:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:05:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:05:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:05:23 INFO - retry: Failed, sleeping 60 seconds before retrying 23:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:06:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:06:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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! 23:06:23 INFO - Caught exception: HTTP Error 404: Not Found 23:06:23 INFO - Caught exception: HTTP Error 404: Not Found 23:06:23 INFO - Caught exception: HTTP Error 404: Not Found 23:06:23 INFO - trying https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:06:23 INFO - Downloading https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:24 INFO - Downloaded 1361 bytes. 23:06:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:06:24 INFO - Using the following test package requirements: 23:06:24 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:06:24 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:06:24 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:06:24 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'jsshell-linux-x86_64.zip'], 23:06:24 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:06:24 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:06:24 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:06:24 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:06:24 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:06:24 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:06:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:06:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:24 INFO - https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:06:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:06:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:06:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:06:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:06:24 INFO - retry: Failed, sleeping 30 seconds before retrying 23:06:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:06:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:06:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:06:54 INFO - retry: Failed, sleeping 60 seconds before retrying 23:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:07:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:07:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:07:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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! 23:07:54 INFO - Caught exception: HTTP Error 404: Not Found 23:07:54 INFO - Caught exception: HTTP Error 404: Not Found 23:07:54 INFO - Caught exception: HTTP Error 404: Not Found 23:07:54 INFO - trying https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:07:54 INFO - Downloading https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:07:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:07:55 INFO - Downloaded 21769733 bytes. 23:07:55 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'] 23:07:55 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 23:07:56 INFO - Return code: 0 23:07:56 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 23:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:56 INFO - https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:07:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:07:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:07:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:07:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:07:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:07:56 INFO - retry: Failed, sleeping 30 seconds before retrying 23:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:08:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:08:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:08:26 INFO - retry: Failed, sleeping 60 seconds before retrying 23:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:09:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:09:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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! 23:09:26 INFO - Caught exception: HTTP Error 404: Not Found 23:09:26 INFO - Caught exception: HTTP Error 404: Not Found 23:09:26 INFO - Caught exception: HTTP Error 404: Not Found 23:09:26 INFO - trying https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:09:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:27 INFO - Downloaded 21769733 bytes. 23:09:27 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'] 23:09:27 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 23:09:28 INFO - Return code: 0 23:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:28 INFO - https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:09:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:09:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:09:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:09:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:09:28 INFO - retry: Failed, sleeping 30 seconds before retrying 23:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:09:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:09:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:09:58 INFO - retry: Failed, sleeping 60 seconds before retrying 23:10:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:10:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:10:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:10:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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! 23:10:58 INFO - Caught exception: HTTP Error 404: Not Found 23:10:58 INFO - Caught exception: HTTP Error 404: Not Found 23:10:58 INFO - Caught exception: HTTP Error 404: Not Found 23:10:58 INFO - trying https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:10:58 INFO - Downloading https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:10:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:10:59 INFO - Downloaded 14413723 bytes. 23:10:59 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'] 23:10:59 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 23:10:59 INFO - Return code: 0 23:10: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')]} 23:10:59 INFO - https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:10:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:10:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:10:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:10:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:10:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:10:59 INFO - retry: Failed, sleeping 30 seconds before retrying 23:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:11:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:11:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:11:29 INFO - retry: Failed, sleeping 60 seconds before retrying 23:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:12:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:12:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:12:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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! 23:12:29 INFO - Caught exception: HTTP Error 404: Not Found 23:12:29 INFO - Caught exception: HTTP Error 404: Not Found 23:12:29 INFO - Caught exception: HTTP Error 404: Not Found 23:12:29 INFO - trying https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/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 23:12:30 INFO - Downloaded 63742838 bytes. 23:12:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:12:30 INFO - mkdir: /builds/slave/test/properties 23:12:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:12:30 INFO - Writing to file /builds/slave/test/properties/build_url 23:12:30 INFO - Contents: 23:12:30 INFO - build_url:https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:12:31 INFO - Running post-action listener: _resource_record_post_action 23:12:31 INFO - Running post-action listener: set_extra_try_arguments 23:12:31 INFO - [mozharness: 2016-08-20 06:12:31.628941Z] Finished download-and-extract step (success) 23:12:31 INFO - [mozharness: 2016-08-20 06:12:31.629171Z] Running populate-webroot step. 23:12:31 INFO - Running pre-action listener: _resource_record_pre_action 23:12:31 INFO - Running main action method: populate_webroot 23:12:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:12:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:12:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:12:31 INFO - '--tppagecycles', 23:12:31 INFO - '7'], 23:12:31 INFO - 'tests': ['tsvgm']}}, 23:12:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:12:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:12:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:12:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:12:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:12:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:12:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['damp', 'tps']}, 23:12:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'tests': ['damp', 'tps']}, 23:12:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['dromaeo_dom']}, 23:12:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:12:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['basic_compositor_video']}, 23:12:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:12:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'other-e10s': {'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['a11yr', 23:12:31 INFO - 'ts_paint', 23:12:31 INFO - 'tpaint', 23:12:31 INFO - 'sessionrestore', 23:12:31 INFO - 'sessionrestore_no_auto_restore', 23:12:31 INFO - 'tabpaint']}, 23:12:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['tsvgx', 23:12:31 INFO - 'tsvgr_opacity', 23:12:31 INFO - 'tart', 23:12:31 INFO - 'tscrollx', 23:12:31 INFO - 'cart']}, 23:12:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:12:31 INFO - 'tsvgr_opacity', 23:12:31 INFO - 'tart', 23:12:31 INFO - 'tscrollx', 23:12:31 INFO - 'cart']}, 23:12:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'talos_options': ['--disable-e10s'], 23:12:31 INFO - 'tests': ['tp5o']}, 23:12:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'tests': ['tp5o']}, 23:12:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'talos_options': ['--disable-e10s', 23:12:31 INFO - '--xperf_path', 23:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:12:31 INFO - 'tests': ['tp5n']}, 23:12:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:12:31 INFO - 'talos_options': ['--xperf_path', 23:12:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:12:31 INFO - 'tests': ['tp5n']}}, 23:12:31 INFO - 'talos.zip': {'path': '', 23:12:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:12:31 INFO - Running post-action listener: _resource_record_post_action 23:12:31 INFO - [mozharness: 2016-08-20 06:12:31.645584Z] Finished populate-webroot step (success) 23:12:31 INFO - [mozharness: 2016-08-20 06:12:31.645673Z] Running create-virtualenv step. 23:12:31 INFO - Running pre-action listener: _resource_record_pre_action 23:12:31 INFO - Running main action method: create_virtualenv 23:12:31 INFO - Creating virtualenv /builds/slave/test/build/venv 23:12:31 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 23:12:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:12:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:12:31 INFO - Using real prefix '/usr' 23:12:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:12:32 INFO - Installing distribute.............................................................................................................................................................................................done. 23:12:34 INFO - Installing pip.................done. 23:12:34 INFO - Return code: 0 23:12:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:34 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:34 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 23:12:34 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 23:12:34 INFO - Using env: {'DISPLAY': ':0', 23:12:34 INFO - 'HOME': '/home/cltbld', 23:12:34 INFO - 'LANG': 'en_US.UTF-8', 23:12:34 INFO - 'LANGUAGE': 'en_US:en', 23:12:34 INFO - 'LOGNAME': 'cltbld', 23:12:34 INFO - 'MAIL': '/var/mail/cltbld', 23:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:34 INFO - 'MOZ_NO_REMOTE': '1', 23:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:34 INFO - 'NO_EM_RESTART': '1', 23:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:34 INFO - 'PWD': '/builds/slave/test', 23:12:34 INFO - 'SHELL': '/bin/bash', 23:12:34 INFO - 'SHLVL': '1', 23:12:34 INFO - 'TERM': 'linux', 23:12:34 INFO - 'TMOUT': '86400', 23:12:34 INFO - 'USER': 'cltbld', 23:12:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:34 INFO - '_': '/tools/buildbot/bin/python'} 23:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:34 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 23:12:34 INFO - Cleaning up... 23:12:34 INFO - Return code: 0 23:12:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:34 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:34 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 23:12:34 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 23:12:34 INFO - Using env: {'DISPLAY': ':0', 23:12:34 INFO - 'HOME': '/home/cltbld', 23:12:34 INFO - 'LANG': 'en_US.UTF-8', 23:12:34 INFO - 'LANGUAGE': 'en_US:en', 23:12:34 INFO - 'LOGNAME': 'cltbld', 23:12:34 INFO - 'MAIL': '/var/mail/cltbld', 23:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:34 INFO - 'MOZ_NO_REMOTE': '1', 23:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:34 INFO - 'NO_EM_RESTART': '1', 23:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:34 INFO - 'PWD': '/builds/slave/test', 23:12:34 INFO - 'SHELL': '/bin/bash', 23:12:34 INFO - 'SHLVL': '1', 23:12:34 INFO - 'TERM': 'linux', 23:12:34 INFO - 'TMOUT': '86400', 23:12:34 INFO - 'USER': 'cltbld', 23:12:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:34 INFO - '_': '/tools/buildbot/bin/python'} 23:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:34 INFO - Downloading/unpacking psutil>=3.1.1 23:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:12:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:12:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:12:36 INFO - Installing collected packages: psutil 23:12:36 INFO - Running setup.py install for psutil 23:12:36 INFO - building 'psutil._psutil_linux' extension 23:12:36 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 23:12:36 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 23:12:36 INFO - building 'psutil._psutil_posix' extension 23:12:36 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 23:12:37 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 23:12:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:12:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:12:37 INFO - Successfully installed psutil 23:12:37 INFO - Cleaning up... 23:12:37 INFO - Return code: 0 23:12:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:12:37 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')]} 23:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:37 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')]} 23:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:37 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:37 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 23:12:37 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 23:12:37 INFO - Using env: {'DISPLAY': ':0', 23:12:37 INFO - 'HOME': '/home/cltbld', 23:12:37 INFO - 'LANG': 'en_US.UTF-8', 23:12:37 INFO - 'LANGUAGE': 'en_US:en', 23:12:37 INFO - 'LOGNAME': 'cltbld', 23:12:37 INFO - 'MAIL': '/var/mail/cltbld', 23:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:37 INFO - 'MOZ_NO_REMOTE': '1', 23:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:37 INFO - 'NO_EM_RESTART': '1', 23:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:37 INFO - 'PWD': '/builds/slave/test', 23:12:37 INFO - 'SHELL': '/bin/bash', 23:12:37 INFO - 'SHLVL': '1', 23:12:37 INFO - 'TERM': 'linux', 23:12:37 INFO - 'TMOUT': '86400', 23:12:37 INFO - 'USER': 'cltbld', 23:12:37 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:37 INFO - '_': '/tools/buildbot/bin/python'} 23:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:12:37 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 23:12:37 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 23:12:37 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 23:12:37 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 23:12:37 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 23:12:37 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 23:12:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:12:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:12:39 INFO - Installing collected packages: mozsystemmonitor 23:12:39 INFO - Running setup.py install for mozsystemmonitor 23:12:39 INFO - Successfully installed mozsystemmonitor 23:12:39 INFO - Cleaning up... 23:12:39 INFO - Return code: 0 23:12:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:12:39 INFO - Using env: {'DISPLAY': ':0', 23:12:39 INFO - 'HOME': '/home/cltbld', 23:12:39 INFO - 'LANG': 'en_US.UTF-8', 23:12:39 INFO - 'LANGUAGE': 'en_US:en', 23:12:39 INFO - 'LOGNAME': 'cltbld', 23:12:39 INFO - 'MAIL': '/var/mail/cltbld', 23:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:39 INFO - 'MOZ_NO_REMOTE': '1', 23:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:39 INFO - 'NO_EM_RESTART': '1', 23:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:39 INFO - 'PWD': '/builds/slave/test', 23:12:39 INFO - 'SHELL': '/bin/bash', 23:12:39 INFO - 'SHLVL': '1', 23:12:39 INFO - 'TERM': 'linux', 23:12:39 INFO - 'TMOUT': '86400', 23:12:39 INFO - 'USER': 'cltbld', 23:12:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:39 INFO - '_': '/tools/buildbot/bin/python'} 23:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:39 INFO - Downloading/unpacking jsonschema==2.5.1 23:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:12:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:41 INFO - Downloading functools32-3.2.3-2.tar.gz 23:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:12:41 INFO - warning: no files found matching '*.txt' 23:12:41 INFO - no previously-included directories found matching 'build' 23:12:41 INFO - no previously-included directories found matching 'dist' 23:12:41 INFO - no previously-included directories found matching '.git*' 23:12:41 INFO - Installing collected packages: jsonschema, functools32 23:12:41 INFO - Running setup.py install for functools32 23:12:41 INFO - warning: no files found matching '*.txt' 23:12:41 INFO - no previously-included directories found matching 'build' 23:12:41 INFO - no previously-included directories found matching 'dist' 23:12:41 INFO - no previously-included directories found matching '.git*' 23:12:41 INFO - Successfully installed jsonschema functools32 23:12:41 INFO - Cleaning up... 23:12:42 INFO - Return code: 0 23:12:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:12:42 INFO - Using env: {'DISPLAY': ':0', 23:12:42 INFO - 'HOME': '/home/cltbld', 23:12:42 INFO - 'LANG': 'en_US.UTF-8', 23:12:42 INFO - 'LANGUAGE': 'en_US:en', 23:12:42 INFO - 'LOGNAME': 'cltbld', 23:12:42 INFO - 'MAIL': '/var/mail/cltbld', 23:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:42 INFO - 'MOZ_NO_REMOTE': '1', 23:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:42 INFO - 'NO_EM_RESTART': '1', 23:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:42 INFO - 'PWD': '/builds/slave/test', 23:12:42 INFO - 'SHELL': '/bin/bash', 23:12:42 INFO - 'SHLVL': '1', 23:12:42 INFO - 'TERM': 'linux', 23:12:42 INFO - 'TMOUT': '86400', 23:12:42 INFO - 'USER': 'cltbld', 23:12:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:42 INFO - '_': '/tools/buildbot/bin/python'} 23:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:12:42 INFO - Cleaning up... 23:12:42 INFO - Return code: 0 23:12:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:12:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:12:42 INFO - Using env: {'DISPLAY': ':0', 23:12:42 INFO - 'HOME': '/home/cltbld', 23:12:42 INFO - 'LANG': 'en_US.UTF-8', 23:12:42 INFO - 'LANGUAGE': 'en_US:en', 23:12:42 INFO - 'LOGNAME': 'cltbld', 23:12:42 INFO - 'MAIL': '/var/mail/cltbld', 23:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:42 INFO - 'MOZ_NO_REMOTE': '1', 23:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:42 INFO - 'NO_EM_RESTART': '1', 23:12:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:42 INFO - 'PWD': '/builds/slave/test', 23:12:42 INFO - 'SHELL': '/bin/bash', 23:12:42 INFO - 'SHLVL': '1', 23:12:42 INFO - 'TERM': 'linux', 23:12:42 INFO - 'TMOUT': '86400', 23:12:42 INFO - 'USER': 'cltbld', 23:12:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:42 INFO - '_': '/tools/buildbot/bin/python'} 23:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:42 INFO - Downloading/unpacking blobuploader==1.2.4 23:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:44 INFO - Downloading blobuploader-1.2.4.tar.gz 23:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:12:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:12:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:45 INFO - Downloading docopt-0.6.1.tar.gz 23:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:12:45 INFO - Installing collected packages: blobuploader, requests, docopt 23:12:45 INFO - Running setup.py install for blobuploader 23:12:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:12:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:12:45 INFO - Running setup.py install for requests 23:12:45 INFO - Running setup.py install for docopt 23:12:45 INFO - Successfully installed blobuploader requests docopt 23:12:45 INFO - Cleaning up... 23:12:45 INFO - Return code: 0 23:12:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12: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')]} 23:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12: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')]} 23:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:45 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:45 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 23:12:45 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 23:12:45 INFO - Using env: {'DISPLAY': ':0', 23:12:45 INFO - 'HOME': '/home/cltbld', 23:12:45 INFO - 'LANG': 'en_US.UTF-8', 23:12:45 INFO - 'LANGUAGE': 'en_US:en', 23:12:45 INFO - 'LOGNAME': 'cltbld', 23:12:45 INFO - 'MAIL': '/var/mail/cltbld', 23:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:45 INFO - 'MOZ_NO_REMOTE': '1', 23:12:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:45 INFO - 'NO_EM_RESTART': '1', 23:12:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:45 INFO - 'PWD': '/builds/slave/test', 23:12:45 INFO - 'SHELL': '/bin/bash', 23:12:45 INFO - 'SHLVL': '1', 23:12:45 INFO - 'TERM': 'linux', 23:12:45 INFO - 'TMOUT': '86400', 23:12:45 INFO - 'USER': 'cltbld', 23:12:45 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:45 INFO - '_': '/tools/buildbot/bin/python'} 23:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:12:45 INFO - Running setup.py (path:/tmp/pip-vwHwlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:12:46 INFO - Running setup.py (path:/tmp/pip-qh_DZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:12:46 INFO - Running setup.py (path:/tmp/pip-3gAkal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:12:46 INFO - Running setup.py (path:/tmp/pip-1r_DD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:12:46 INFO - Running setup.py (path:/tmp/pip-IWHQgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:12:46 INFO - Running setup.py (path:/tmp/pip-HGXHUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:12:46 INFO - Running setup.py (path:/tmp/pip-6heDcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:12:46 INFO - Running setup.py (path:/tmp/pip-1fq7j9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:12:46 INFO - Running setup.py (path:/tmp/pip-oa638C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:12:46 INFO - Running setup.py (path:/tmp/pip-B3ImeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:12:46 INFO - Running setup.py (path:/tmp/pip-3fmw9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:12:46 INFO - Running setup.py (path:/tmp/pip-gBXvAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:12:46 INFO - Running setup.py (path:/tmp/pip-a1foNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:12:46 INFO - Running setup.py (path:/tmp/pip-NSxs4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:46 INFO - Running setup.py (path:/tmp/pip-yZZZ06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:12:47 INFO - Running setup.py (path:/tmp/pip-M2WgGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:12:47 INFO - Running setup.py (path:/tmp/pip-T1o8ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:12:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:12:47 INFO - Running setup.py install for manifestparser 23:12:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Running setup.py install for mozcrash 23:12:47 INFO - Running setup.py install for mozdebug 23:12:47 INFO - Running setup.py install for mozdevice 23:12:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Running setup.py install for mozfile 23:12:47 INFO - Running setup.py install for mozhttpd 23:12:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Running setup.py install for mozinfo 23:12:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Running setup.py install for mozInstall 23:12:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:12:47 INFO - Running setup.py install for mozleak 23:12:48 INFO - Running setup.py install for mozlog 23:12:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Running setup.py install for moznetwork 23:12:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Running setup.py install for mozprocess 23:12:48 INFO - Running setup.py install for mozprofile 23:12:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Running setup.py install for mozrunner 23:12:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Running setup.py install for mozscreenshot 23:12:48 INFO - Running setup.py install for moztest 23:12:48 INFO - Running setup.py install for mozversion 23:12:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:12:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:12:48 INFO - Cleaning up... 23:12:48 INFO - Return code: 0 23:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:48 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:48 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 23:12:48 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 23:12:48 INFO - Using env: {'DISPLAY': ':0', 23:12:48 INFO - 'HOME': '/home/cltbld', 23:12:48 INFO - 'LANG': 'en_US.UTF-8', 23:12:48 INFO - 'LANGUAGE': 'en_US:en', 23:12:48 INFO - 'LOGNAME': 'cltbld', 23:12:48 INFO - 'MAIL': '/var/mail/cltbld', 23:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:48 INFO - 'MOZ_NO_REMOTE': '1', 23:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:48 INFO - 'NO_EM_RESTART': '1', 23:12:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:48 INFO - 'PWD': '/builds/slave/test', 23:12:48 INFO - 'SHELL': '/bin/bash', 23:12:48 INFO - 'SHLVL': '1', 23:12:48 INFO - 'TERM': 'linux', 23:12:48 INFO - 'TMOUT': '86400', 23:12:48 INFO - 'USER': 'cltbld', 23:12:48 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:48 INFO - '_': '/tools/buildbot/bin/python'} 23:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:12:49 INFO - Running setup.py (path:/tmp/pip-HrNcu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:12:49 INFO - Running setup.py (path:/tmp/pip-THDzBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:12:49 INFO - Running setup.py (path:/tmp/pip-Mad7C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:12:49 INFO - Running setup.py (path:/tmp/pip-jiKTpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:12:49 INFO - Running setup.py (path:/tmp/pip-wNAeM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:12:49 INFO - Running setup.py (path:/tmp/pip-TAQY5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:12:49 INFO - Running setup.py (path:/tmp/pip-nK4Ypn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:12:49 INFO - Running setup.py (path:/tmp/pip-kxmz7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:12:49 INFO - Running setup.py (path:/tmp/pip-nTFVSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:12:49 INFO - Running setup.py (path:/tmp/pip-ETaBE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:12:49 INFO - Running setup.py (path:/tmp/pip-wZoYxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:12:49 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)) 23:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:12:49 INFO - Running setup.py (path:/tmp/pip-hrZjpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:12:50 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)) 23:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:12:50 INFO - Running setup.py (path:/tmp/pip-bZu0lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:12:50 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)) 23:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:12:50 INFO - Running setup.py (path:/tmp/pip-dxPUey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:12:50 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)) 23:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:50 INFO - Running setup.py (path:/tmp/pip-XZqhCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:50 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)) 23:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:12:50 INFO - Running setup.py (path:/tmp/pip-F9VMTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:12:50 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)) 23:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:12:50 INFO - Running setup.py (path:/tmp/pip-b3BYh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:12:50 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)) 23:12:50 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)) 23:12:50 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)) 23:12:50 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)) 23:12:50 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)) 23:12:50 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)) 23:12:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:52 INFO - Downloading blessings-1.6.tar.gz 23:12:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:12:52 INFO - Installing collected packages: blessings 23:12:52 INFO - Running setup.py install for blessings 23:12:52 INFO - Successfully installed blessings 23:12:52 INFO - Cleaning up... 23:12:52 INFO - Return code: 0 23:12:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:12:52 INFO - Reading from file tmpfile_stdout 23:12:52 INFO - Current package versions: 23:12:52 INFO - argparse == 1.2.1 23:12:52 INFO - blessings == 1.6 23:12:52 INFO - blobuploader == 1.2.4 23:12:52 INFO - docopt == 0.6.1 23:12:52 INFO - functools32 == 3.2.3-2 23:12:52 INFO - jsonschema == 2.5.1 23:12:52 INFO - manifestparser == 1.1 23:12:52 INFO - mozInstall == 1.12 23:12:52 INFO - mozcrash == 0.17 23:12:52 INFO - mozdebug == 0.1 23:12:52 INFO - mozdevice == 0.48 23:12:52 INFO - mozfile == 1.2 23:12:52 INFO - mozhttpd == 0.7 23:12:52 INFO - mozinfo == 0.9 23:12:52 INFO - mozleak == 0.1 23:12:52 INFO - mozlog == 3.3 23:12:52 INFO - moznetwork == 0.27 23:12:52 INFO - mozprocess == 0.23 23:12:52 INFO - mozprofile == 0.28 23:12:52 INFO - mozrunner == 6.12 23:12:52 INFO - mozscreenshot == 0.1 23:12:52 INFO - mozsystemmonitor == 0.3 23:12:52 INFO - moztest == 0.7 23:12:52 INFO - mozversion == 1.4 23:12:52 INFO - psutil == 3.1.1 23:12:52 INFO - requests == 1.2.3 23:12:52 INFO - wsgiref == 0.1.2 23:12:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12:52 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')]} 23:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:52 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')]} 23:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:52 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:52 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 23:12:52 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 23:12:52 INFO - Using env: {'DISPLAY': ':0', 23:12:52 INFO - 'HOME': '/home/cltbld', 23:12:52 INFO - 'LANG': 'en_US.UTF-8', 23:12:52 INFO - 'LANGUAGE': 'en_US:en', 23:12:52 INFO - 'LOGNAME': 'cltbld', 23:12:52 INFO - 'MAIL': '/var/mail/cltbld', 23:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:52 INFO - 'MOZ_NO_REMOTE': '1', 23:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:52 INFO - 'NO_EM_RESTART': '1', 23:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:52 INFO - 'PWD': '/builds/slave/test', 23:12:52 INFO - 'SHELL': '/bin/bash', 23:12:52 INFO - 'SHLVL': '1', 23:12:52 INFO - 'TERM': 'linux', 23:12:52 INFO - 'TMOUT': '86400', 23:12:52 INFO - 'USER': 'cltbld', 23:12:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:52 INFO - '_': '/tools/buildbot/bin/python'} 23:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 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)) 23:12:52 INFO - Cleaning up... 23:12:52 INFO - Return code: 0 23:12:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:12:52 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')]} 23:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:52 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')]} 23:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:52 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 0x19ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', '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 23:12:52 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 23:12:52 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 23:12:52 INFO - Using env: {'DISPLAY': ':0', 23:12:52 INFO - 'HOME': '/home/cltbld', 23:12:52 INFO - 'LANG': 'en_US.UTF-8', 23:12:52 INFO - 'LANGUAGE': 'en_US:en', 23:12:52 INFO - 'LOGNAME': 'cltbld', 23:12:52 INFO - 'MAIL': '/var/mail/cltbld', 23:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:52 INFO - 'MOZ_NO_REMOTE': '1', 23:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:52 INFO - 'NO_EM_RESTART': '1', 23:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:52 INFO - 'PWD': '/builds/slave/test', 23:12:52 INFO - 'SHELL': '/bin/bash', 23:12:52 INFO - 'SHLVL': '1', 23:12:52 INFO - 'TERM': 'linux', 23:12:52 INFO - 'TMOUT': '86400', 23:12:52 INFO - 'USER': 'cltbld', 23:12:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:52 INFO - '_': '/tools/buildbot/bin/python'} 23:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:12:53 INFO - Cleaning up... 23:12:53 INFO - Return code: 0 23:12:53 INFO - Running post-action listener: _resource_record_post_action 23:12:53 INFO - Running post-action listener: _start_resource_monitoring 23:12:53 INFO - Starting resource monitoring. 23:12:53 INFO - [mozharness: 2016-08-20 06:12:53.216905Z] Finished create-virtualenv step (success) 23:12:53 INFO - [mozharness: 2016-08-20 06:12:53.217336Z] Running install step. 23:12:53 INFO - Running pre-action listener: _resource_record_pre_action 23:12:53 INFO - Running main action method: install 23:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:12:53 INFO - Reading from file tmpfile_stdout 23:12:53 INFO - Detecting whether we're running mozinstall >=1.0... 23:12:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:12:53 INFO - Reading from file tmpfile_stdout 23:12:53 INFO - Output received: 23:12:53 INFO - Usage: mozinstall [options] installer 23:12:53 INFO - Options: 23:12:53 INFO - -h, --help show this help message and exit 23:12:53 INFO - -d DEST, --destination=DEST 23:12:53 INFO - Directory to install application into. [default: 23:12:53 INFO - "/builds/slave/test"] 23:12:53 INFO - --app=APP Application being installed. [default: firefox] 23:12:53 INFO - mkdir: /builds/slave/test/build/application 23:12:53 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'] 23:12:53 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 23:13:09 INFO - Reading from file tmpfile_stdout 23:13:09 INFO - Output received: 23:13:09 INFO - /builds/slave/test/build/application/firefox/firefox 23:13:09 INFO - Running post-action listener: _resource_record_post_action 23:13:09 INFO - [mozharness: 2016-08-20 06:13:09.481870Z] Finished install step (success) 23:13:09 INFO - [mozharness: 2016-08-20 06:13:09.482316Z] Running run-tests step. 23:13:09 INFO - Running pre-action listener: _resource_record_pre_action 23:13:09 INFO - Running main action method: run_tests 23:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:13:09 INFO - Python 2.7.3 23:13:09 INFO - Return code: 0 23:13:09 INFO - grabbing minidump binary from tooltool 23:13:09 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')]} 23:13:09 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 0x1c6c3b0>, '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 0x1c6d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:13:09 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 23:13:09 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 23:13:09 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 23:13:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:13:09 INFO - Return code: 0 23:13:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:13:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:13:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:13:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:13:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:13:09 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:13:09 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:13:09 INFO - Using env: {'DISPLAY': ':0', 23:13:09 INFO - 'HOME': '/home/cltbld', 23:13:09 INFO - 'LANG': 'en_US.UTF-8', 23:13:09 INFO - 'LANGUAGE': 'en_US:en', 23:13:09 INFO - 'LOGNAME': 'cltbld', 23:13:09 INFO - 'MAIL': '/var/mail/cltbld', 23:13:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:13:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:09 INFO - 'MOZ_NO_REMOTE': '1', 23:13:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:09 INFO - 'NO_EM_RESTART': '1', 23:13:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:09 INFO - 'PWD': '/builds/slave/test', 23:13:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:13:09 INFO - 'SHELL': '/bin/bash', 23:13:09 INFO - 'SHLVL': '1', 23:13:09 INFO - 'TERM': 'linux', 23:13:09 INFO - 'TMOUT': '86400', 23:13:09 INFO - 'USER': 'cltbld', 23:13:09 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495', 23:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:09 INFO - '_': '/tools/buildbot/bin/python'} 23:13:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:13:09 INFO - mozversion application_buildid: 20160819202315 23:13:09 INFO - mozversion application_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 23:13:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:13:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:13:09 INFO - mozversion application_name: Firefox 23:13:09 INFO - mozversion application_remotingname: firefox-dev 23:13:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:13:09 INFO - mozversion application_vendor: Mozilla 23:13:09 INFO - mozversion application_version: 50.0a2 23:13:09 INFO - mozversion platform_buildid: 20160819202315 23:13:09 INFO - mozversion platform_changeset: 6f1c9caedd87771d03d5e2808a4e821136679221 23:13:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:13:09 INFO - mozversion platform_version: 50.0a2 23:13:09 INFO - using testdate: 1471673589 23:13:09 INFO - actual date: 1471673589 23:13:09 INFO - starting webserver on 'localhost:45373' 23:13:09 INFO - SUITE-START | Running 2 tests 23:13:09 INFO - TEST-START | dromaeo_css 23:13:09 INFO - operating with platform_type : linux_ 23:13:09 INFO - Initialising browser for dromaeo_css test... 23:13:09 INFO - TEST-INFO | started process 31942 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkdmxle/profile http://localhost:45373/getInfo.html) 23:13:13 INFO - PROCESS | 31942 | __metrics Screen width/height:1600/1200 23:13:13 INFO - PROCESS | 31942 | colorDepth:24 23:13:13 INFO - PROCESS | 31942 | Browser inner width/height: 1024/697 23:13:13 INFO - PROCESS | 31942 | __metrics 23:13:14 INFO - PROCESS | 31942 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:13:14 INFO - PROCESS | 31942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:13:15 INFO - TEST-INFO | 31942: exit 0 23:13:15 INFO - Browser initialized. 23:13:15 INFO - Running cycle 1/1 for dromaeo_css test... 23:13:15 INFO - TEST-INFO | started process 32030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkdmxle/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:27 INFO - PROCESS | 32030 | RSS: Main: 145477632 23:13:27 INFO - PROCESS | 32030 | 23:14:18 INFO - PROCESS | 32030 | 1471673658089 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] 23:16:27 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45373/tests/dromaeo/cssquery-ext.html) 23:16:28 INFO - PROCESS | 32030 | RSS: Main: 222449664 23:16:28 INFO - PROCESS | 32030 | 23:19:30 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-ext.html (next: http://localhost:45373/tests/dromaeo/cssquery-jquery.html) 23:19:31 INFO - PROCESS | 32030 | RSS: Main: 236183552 23:19:31 INFO - PROCESS | 32030 | 23:22:33 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45373/tests/dromaeo/cssquery-mootools.html) 23:22:33 INFO - PROCESS | 32030 | RSS: Main: 178114560 23:22:33 INFO - PROCESS | 32030 | 23:25:35 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45373/tests/dromaeo/cssquery-prototype.html) 23:25:36 INFO - PROCESS | 32030 | RSS: Main: 226205696 23:25:36 INFO - PROCESS | 32030 | 23:28:38 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45373/tests/dromaeo/cssquery-yui.html) 23:28:39 INFO - PROCESS | 32030 | RSS: Main: 176730112 23:28:39 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | Cycle 1(1): loaded http://localhost:45373/tests/dromaeo/cssquery-yui.html (next: http://localhost:45373/tests/dromaeo/cssquery-dojo.html) 23:31:42 INFO - PROCESS | 32030 | RSS: Main: 262049792 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | __start_tp_report 23:31:42 INFO - PROCESS | 32030 | _x_x_mozilla_page_load 23:31:42 INFO - PROCESS | 32030 | _x_x_mozilla_page_load_details 23:31:42 INFO - PROCESS | 32030 | |i|pagename|runs| 23:31:42 INFO - PROCESS | 32030 | |0;dojo.html;3268.477043673012;3271.165852707559;3275.8198299093556;3301.5377847101395;3317.3365326934604;5640.182173584828;5762.510186634141;5762.567807414445;5765.4522820332;5786.56600754943;5781.335146458158;5827.883442331151;5829.8251052468495;5834.066549352105;5846.853828654286;5059.139946209135;5240.947590524103;5286.154215325549;5315.36215654122;5334.119870221428;5085.23721441783;5189.532942035216;5216.530512253897;5231.581473482112;5233.241180028894;5116.820911268106;5129.897402051956;5149.382074151107;5152.046871328797;5235.528802407783;5221.2951251580935;5231.267622532849;5235.39792923811;5248.134057880445;5271.156614941591;5044.192929131322;5141.974290128563;5142.588592912595;5149.330587023663;5185.351831021122;5675.971620141914;5723.141528770676;5739.540836733051;5744.856378590527;5773.480386765169;6253.593516421418;6258.937410625881;6262.2172228471445;6264.1543391642;6267.279262884781;5984.042553191469;6126.8841215407765;6202.131701561785;6210.596311239755;6292.653904035276;5834.5915785895;5843.211166492511;5856.824295271063;5861.443162899518;5864.501517370986;6379.776707815297;6385.584936979081;6388.425041746173;6390.392912673382;6393.6483493408305;5854.590178687451;5905.1142328650185;5933.821985340447;5951.3751056139345;5969.104634304889;6046.486048685823;6055.818325450244;6058.939410605837;6078.878422431527;6084.726187321581;5988;6047.03247480401;6056.969715151484;6060.515158787286;6064.02975523914;5930.28836539618;6121.173641558422;6165.506759459233;6166.105914642441;6242.157308763259;4716.622670186377;4739.526047395233;4756.8097276108565;4773.212419950739;4787.138315103315;4222.873313800591;4231.661467082626;4240.151969606091;4252.383404406344;4263.062126332202;5602.159676048625;5837.416258374214;5888.705564721832;5902.940970590239;5956.04703247487;6178.382161783786;6237.033259844731;6244.594101383395;6247.406496382836;6257.655828929362;5895.705214739246;5913.053911374245;5914.357229574764;5918.674472904032;5951.494122999594;5095.210242412358;5242.580593552507;5258.50044245796;5264.078786212397;5353.785848566014;6356.459700925473;6375.995783150471;6407.359264073555;6410.102585638015;6410.583312084699;6143.692815359124;6217.160683307877;6219.0982307564245;6224.097505861534;6224.439800417984;6035.245594300713;6173.166622505904;6199.3490683477585;6229.875402491834;6363;6035.849103772441;6042.425969532887;6048.827161416549;6050.152978582913;6079.148919151234;6143.232095988002;6146.569740118328;6152.415520525543;6155.938440615716;6158.784442544489;5816.680082595498;6082.787102451393;6140.385961403824;6143.754249829957;6189.473894718999;6112;6115.785947491817;6117.449429551361;6125.356837531995;6140.692965351625;6002.729877155626;6050;6060.303065147565;6062;6070.2412198475195;5799.565032622486;6065.3934606539;6130.816075517741;6133.018717005258;6167.167432396748;5914.4381283777975;6003.249593800775;6021.307549631849;6027.547933905063;6031.185789918481 23:31:42 INFO - PROCESS | 32030 | |1;ext.html;619.9783007594735;641.4675819070172;663.118052989524;677.0149432575603;677.3260605697332;35813.10467238317;46083.53916460839;46939.29591056137;47031.764841175835;47083;49360.50639493613;49811.75094124534;49985.99999999991;50014;50127.24809127857;8494.660213591449;9278.81442371154;9416.81166376672;9417.576209070574;9422.246220302393;8683.782905427383;8774.780630484258;8796.956015219916;10900.291481053746;11168.441577921072;11207.327560346364;11267.26762760422;11279.154063445234;11280.323180609148;11291.378974156418;10998.780024399468;11036.613718519853;11061.557537698482;11069.059129973963;11105.111591072695;10883;11098.500567474483;11103;11107.611233606865;11119.166062545342;46062.30906536405;46343.07313853704;46366.30450543245;46410.76794616039;46415.30377044347;3646.890593282206;3756.8121593920196;3802.6007269236607;3976.4035394691027;4387.3857659927635;35404.82297588521;35492.64507354945;35584.466233006526;35713.642863571295;35970.28059438823;4662.883427914296;4694.95305046952;4721.6458765592715;4722.150012997659;4781.928271075936;360469;361594;361880;362034.9999999974;364212;63043.6847815758;63203.36796632067;63324;63525.36474635287;63561;39306.21387572232;40081.59918400837;40160.799196003834;41664.166716665495;41808.79095604503;17564.91217543904;17729.46811595654;17732.02473863924;17741.7338739919;17744;12380.442880070419;13263.933680331536;13373.46506139763;13383.598492045254;13389.866101338863;19908.60182796365;20827.687584686242;20949.057292421876;21475.892620536797;21525.354239372842;7403.666834992438;7451.962740186264;7560.810979725441;7597.924020759721;7786.727464538714;1014.2241185493103;1049.499216120939;1070.6895000449938;1073.656429942411;1155.774623948322;34712.65287347094;35090;35488.64511354905;35653.28693426117;35737.46393804095;1122.6407549584055;1162.0877611075266;1181.6632259805915;1200.591798788416;1228.6436933289424;17514.64970700579;18602.627947441248;18675.43973680792;18733;18795;597115;597746;601753;602196;606272;64041.35958640354;64789;64942;64956.35043649597;65092;1343.7446885091802;1414.9363278652486;1440.863118003788;1504.9021813582083;1507.6984603079427;34220.657793422746;34847.825760872045;36075.819620901726;36612.2540809435;37258.254834903695;58480.70759646175;63194;63331.36668633439;63562.046569300575;63670.68164659147;580004;583970;584292;586092;586495;30227.395452091274;31265.374692506477;31379.529307059955;32877.17807054843;33022.33955320928;51786.22320665118;52836.47163528315;53285;53636;53675.46324536705;44179.267807401004;45350.09299814051;45515.77242113901;46073.76963115163;46889.29666054944;45661.54338456573;45716.54283457256;46356;46433;47182.29226561673;45041.774791125834;45709.08581828411;46096.769516152206;46214.30678539892;46270;37181.25637487289;40495;41509.58490415057;41791.37312940248;41906.58093419027;29707.702922970493;30614.540781887845;30753.40116611413;31162.68837311598;31196.68803311938 23:31:42 INFO - PROCESS | 32030 | |2;jquery.html;373.21065945525186;395.728925685905;401.4820881063428;404.348998113038;415.8190738303218;17433.651326973468;20314.390568282975;20966.16135354586;21273.787262127375;23392;19320;19899.60200795983;20081.196752129934;20989.37031889046;21480.677789833164;5244.816431424901;5788.623739456922;6009.9399006010035;6031.4270144336215;6044.788432404866;4033.4353190553347;5955.017422125366;6033.215681961339;6043.033114701649;6043.244594425697;5876.206712093876;6091.451769340759;6131.969340153293;6206.2862770781485;6217.409346112135;5987.640741555499;6134.202553668039;6176.197094377724;6180.258368995726;6194.783182588588;5978.880422391571;5981.611195272337;5984.4613984741145;5987.341392446828;5987.670678112702;14579.48971785993;14668.55994320172;14904;14920.701585968329;14987;2370.0638247892157;2398.136670798511;2433.209207007712;2486.78862296705;2492.426741849367;10506.474676266156;10582.894171058344;10900.672979810619;11080.113590912708;11262.267952583055;2016.3446879764135;2037.9796202037937;2048.3445297504804;2067.7518697756364;2093.2568938027084;87571.56214218952;89682.55158724164;91028.54485727595;91233;91459;10738.248322617497;10840.403777792297;10897.618583349704;11085.50115244816;11089.112870970303;15013;15039.398424062914;15073.472428464951;15083.170425626477;15522.767158492632;6616.206055273398;6662.400383965466;7838.804029899298;8233.925802218724;8464.238218560236;8138.308243799225;8143.982002249719;8199.549024803693;8354.8329033419;8362.372822038371;4471.8434854779925;4482.529334419839;4488.573585509372;4494.685372023992;4604.286335617984;1882.3599976008222;1929.4211736479;1938.0987840654163;1941.1555973151724;1961.7743959444847;964.508100868548;1007.9798404031878;1058.2697938422464;1065.3366583541147;1086.0008791911303;20449.386518404473;21219.68170477444;21220.681689774665;21233.78766212349;21252.78747212508;1024.1601886453186;1044.8328267477168;1051.2378525569054;1081.734974931131;1084.2843723142687;11135.944320278346;11644.126690498077;11686.24039437434;11752.529898804123;11786.116041296766;492422;503380;504594;511328;514212;93755.53122234344;96010.51994740122;96858;98902.01097989072;101280.49359753155;1011.2365164301053;1046.5238316565944;1055.8069381598743;1057.3285963413289;1063.7021633942509;17794.733079004083;17984;18733;18797.812021879607;18871.622567548846;44098.55901441073;45126.54873451223;45435;45693.54306456893;45914.311285331416;61428.385716142264;62446;62482.68758656178;62738.37261627326;63259;8884.511351875708;9000.099990000948;9098.818023639622;9163.358564900393;9295.907040929775;10476.476176191312;13879.236641984788;13893.65265868321;14009.439622415397;14439.061461005002;11307.151963602599;11813.763724725286;12119.212251203644;12120.454579543766;12309.630711078682;11347.432628368384;11488.597899073493;11858.762824743284;11952.880471195176;12514.12401131912;12146.149769516049;12157.574484892986;12168.513259469522;12228.755424891275;12401.379931003594;8861.601227944888;9004.819903602021;9014.737936688738;9039.59321830506;9127.269818414643;11065.668029959113;11462.713432164262;11826.704332391415;11875.287482751062;12354.258744475 23:31:42 INFO - PROCESS | 32030 | |3;mootools.html;1438.3743071763777;1499.6925630245798;1519.0809560216073;1739.7390391441281;1852.6387354465876;8493.36299777516;9133.68032118876;9449.244060475163;9457.101575350347;9458.290628202894;8783.516906570136;8829.602667879944;8829.779255518615;9126.95436522818;9144.314176436777;2493.2769496845895;2538.708048574419;2539.415934335104;2546.719860815318;2547.9745202547924;2447.21689059501;2511.7990560755097;2514.0446630280467;2516.2702816183287;2517.6097704855683;2491.700995880488;2502.987485062572;2545.0964907457865;2548.0062775517563;2557.565213913639;2491.5016996600752;2507.3104896153613;2514.2959971208;2529.3297276221815;2531.974680253192;2481.913133040353;2503.2114883811723;2506.786923111538;2510.221831232324;2524.432002799374;9245.72262832116;9622.230221582256;9716.417014979053;9720.368176068603;9722.659706910295;5624.943750562524;5768.336641286264;5768.538516918637;5768.913466298009;5778.682172480512;3697.2605478904325;3704.240630670733;3745.494358261627;3754.568224654145;3761.9059523512133;1197.868234494205;1205.2346759807585;1208.516593362662;1227.613301809936;1258.2261908926037;13478.932605336911;13669.589912302647;13745.106568073043;13801.171929684151;13801.171929684251;4977.850664480072;5062.772175252123;5078.060558796635;5078.4413714491375;5303.946960530422;11765.352905590275;11780.528778848922;11821.645350639494;11890.227135236182;11899.88100119005;5715.142728590661;5717.085266357363;5721.284839395075;5722.54219662426;5727.914081288784;4474.373587697725;4560.407147070926;4641.2109941310055;5095.592352611782;5257.395399529027;4375.16871794358;4397.560243975577;4441.356003379492;4470;4501.257292546758;1377.0635967542169;1387.8681525255085;1410.2173293821822;1419.0775995602735;1425.1947649577955;855.3200205836375;863.9092895246034;976.7606936300509;1045.7803861189084;1052.984205236922;6491.221053473613;6564.934350656432;6681.398674119256;6752.76365327221;6817.9659101705165;998.4957596413765;1006.2151521812998;1009.1674368645896;1024.3239461955075;1091.7652704668535;4491.281394976788;4544.227481328182;4545.2954792007295;4563.680542362003;4564.748938808398;11908.761824763456;12016.158868879269;12039.63881083569;12255.387230638433;12316.076294277966;12171.330576818184;12176.147669663216;12198.634040978784;12204.084693648012;12207.328596927076;8691.261242794308;8847;8926.509042002623;8939.374243803002;9172.266218702622;6493.253275873146;6645.966770166118;6675.198976122896;6722.596644201363;6864.24493305746;10928.453577320943;11303.547858085913;11305.99999999967;11410.087193024705;11412.144089193178;11126.276792008493;11152.107831373633;11428.085753139896;11464.828027579426;11532.135089868461;9183;9246.583903724482;9304.209142222986;9307.906920930704;9392.201662858733;9320.72037838866;9425.38734982224;9432;9445.622175113192;9450.149486546245;8055;8267.214614611603;8283.544405057539;8418.074011858813;8432.32541396699;7965.4822436541435;8111.391645626483;8163.265306122477;8235.341172706288;8237.50574965504;7610.6194690266375;7690.538567685957;7779.299863012356;7784.9610751945875;8011.799705007422;5610.214569960127;5622.746976385991;5704.686242256716;5810.331811841692;5827.796027139199;5585.273914391103;5618.887622247614;5632.408597097246;5732.455416735404;5769.336526299529 23:31:42 INFO - PROCESS | 32030 | |4;prototype.html;351.0836716170794;353.4327404515752;361.6852740289049;361.90047736872356;366.2071614953625;5395.758975435652;5501.724913754307;5534.142207957763;5581.776728930843;5585.8603534911645;5458.344998600173;5464.453554644543;5483.8338856151795;5484.396716361197;5502.202180683808;4340.761258130801;4349.173657005174;4350.08648183881;4351.672739814356;4356.542563030884;4160.39674247234;4300.290452075419;4344.197158179351;4350.108227813306;4352.564743525654;4286.699931004831;4287.027967135122;4298.226319262531;4301.440812694345;4306.935395969063;4297.355396690521;4300.440942677463;4301.870943871689;4315.870523884289;4353.782310884443;3701.371396585521;3804.372278574032;3819.5416550014174;3841.5006049213703;3846.134619710571;5379.488948549882;5379.838604841861;5390.4879036491475;5393.7572809223675;5410.702411367373;4932.827351042696;4941;4941.234108713119;4943.555080042777;4970.701757894538;5566.638168519034;5724.914126288109;5731.570132240098;5734.913976290359;5743.827685169452;4106.096658735103;4145.357469592218;4152.294110001307;4155.937660935088;4191.1827193697245;5796.594238403271;6066.726997285133;6083.661594449214;6095.329513753483;6101.352634788626;4221.176870510221;4241.363795430649;4263.147370525907;4264.33902745075;4297.290946993711;4346.717463364871;4356.194104090753;4363.847265345697;4365.323374876835;4369.737815731066;4960.429550601655;4962.875928101754;4967.006598680278;4976.377952755905;4990.850274491771;5475.342958844884;5638.774449022075;5765.567582431335;5767.192593036978;5810.1865738796605;1402.5021117503343;1442.451868290043;1446.5968011029286;1454.1711224602086;1454.3092031285055;922.5608051439775;941.4633658814547;954.9522523873917;958.3195930889008;962.138885697311;4868.294097355863;4883.754642566123;4914.827981020647;4955.6531042826655;5000.549950504472;5169.71566563835;5196.583588631304;5212.843614691566;5218.973905130526;5240.6069544784295;4763.618910487153;4899.363082799213;4969.378827646544;4976.2038073908;4979.950200498021;2736.329599248157;2739.493193759161;2747.8900843966017;2757.9724202758116;2768.584712293132;5325.733713314319;5334.733263336818;5342.439043900412;5366.463353664602;5368.194770784414;4901.583365413909;5235.973820130875;5252.65857719247;5268.2088090049565;5298.231756395301;5261.526462618306;5278.340207474066;5288.312519372534;5306.708131052752;5339.866503337447;5084.618653600921;5137.6660517066275;5148.76830542619;5218.347706536683;5220.556252718409;5118.948810511847;5452.454754524516;5784.595078344475;5872.677002764889;5875.559333050124;6110.419510146529;6122.071547462535;6122.510199183966;6122.877542449215;6139.631622102509;4023.3562629979065;4278.6790990676445;4299.613034826881;4309.9353509698;4336.869893903188;5670.432956704297;5840.445157710181;5918.260217472816;5936.7922122725495;5989.1615173874825;5745.224394706828;5818.389069147848;5831.096180092092;5846.239988801429;5854.063349864001;5792.218050563289;5801.622894511843;5812;5817.981853175762;5822;5612.5509959202345;5726.284214473191;5760.510356619734;5777.277840269966;5791.218185544897;5849.151872978479;5850.765969361178;5855.180274761452;5858.9414105860105;5866.735996880236;5555.166724991283;5647.554991455291;5674.375818659862;5818.272715910512;5821.301443826768 23:31:42 INFO - PROCESS | 32030 | |5;yui.html;804.13555427915;843.2410830252771;877.4428238068826;885.5218182181623;928.8341787084925;16421;16921.40775072871;17021.574460638458;17034.82965170348;17065.146742662862;16559.91720041398;16742.079185644816;16747.91626041871;16751.413700520454;16765.832341676578;726.7819654103774;863.3697400897348;864.9394542382036;877.3770622857778;887.1660638999352;886.8182022685334;888.4935586715575;888.6667499687617;889.1242126505387;890.2388457868526;946.2666433514013;947.2422062350092;948.3977674176913;949.0225068179786;949.0272470717535;758.2303961479075;906.1663269791808;912.7170577121047;925.3661242049151;947.0340252941995;682.576802382526;683.6479213205204;684.3601232847254;685.6605980039913;685.8319711670623;16076.839231607652;16184.514464566082;16186.676266474722;16191.190440477929;16194.676106477804;14083.295835208199;14499;14647;14757;14841.70316593673;14236.857631423656;14368;14386.568402947929;14718.779218311734;14779.778303325458;4567.725936443824;4667.8599642010795;4697.060587882403;4779.187538118528;4805.807767689288;89126;91412.54293728556;91477.54261228717;91512;91544.54227728886;11695.883041169649;11706.356150411639;11752.118591105702;11945.104117191246;12038.277703337828;21244.25645102414;21267.149314027574;21287.68068478974;21304;21306.467338316357;8726.781830454289;9048.954755226314;9101;9315.767105822273;9430.90569094314;4765.7140571565815;4932.383452068491;4971.602271818246;5099.97450012755;5265.052290587731;3725.3480640887738;3865.3042452358845;3865.820924617962;3867.651911327938;3870.1292209253256;671.7514519627769;675.6621689155422;685.3215316836296;701.9052427922268;712.2058904321748;631.5515983651567;669.1468377818223;731.579341878422;746.9066366704162;780.3640033372783;9214.216791572791;10222.284440089272;10794.892051079545;11145.72135696614;11192.608258710907;607.6445279511447;624.8906441372742;713.0445203427432;726.4297526441718;727.1165533876366;4848.587870031016;5105.489451054865;5224.92162617561;5261.947380526222;5292.841214763564;77642.61178694184;94600;138726;138842;139977;116389.41805291089;119322;119623;121319;123135;101199;101282.49358753307;101378;104325.47837260766;104582;9930.106290433896;10006.149477294512;10034.1972642187;10034.548445320124;10041.849372259569;49622;60262;60555;61176.38823611885;61567.69216153891;73367.26632733818;76296;91386;91824.54087729519;94397.5280123595;14700.264986750406;14811.629709257355;14899.404023839359;15088.924555377153;15328.693426131638;16013.759793603338;16403.507894763177;16570;16581.336746530003;16958.152092395085;15422.305996230421;15844.287007084478;15859.920700396886;15926.362945482051;16860;15553.922230388776;15671.373145074069;15804.68390632158;16230.18849057566;16576.171191440622;15105.244737763287;15713.607159820638;16126.919365403099;16127.596810079842;16487.917560412123;12509;12893.935530322664;12937.417816198105;12962.287074210632;13139.751723586243;12553.748925021631;12988.480460781462;13232.338383081;13255.337233138498;13508.189508629514 23:31:42 INFO - PROCESS | 32030 | __end_tp_report 23:31:42 INFO - PROCESS | 32030 | __start_cc_report 23:31:42 INFO - PROCESS | 32030 | _x_x_mozilla_cycle_collect,1036 23:31:42 INFO - PROCESS | 32030 | __end_cc_report 23:31:42 INFO - PROCESS | 32030 | __startTimestamp1471674702289__endTimestamp 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | ------- Summary: start ------- 23:31:42 INFO - PROCESS | 32030 | Number of tests: 6 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#0] dojo.html Cycles:165 Average:5724.52 Median:5956.05 stddev:660.09 (11.1%) stddev-sans-first:633.37 23:31:42 INFO - PROCESS | 32030 | Values: 3268.5 3271.2 3275.8 3301.5 3317.3 5640.2 5762.5 5762.6 5765.5 5786.6 5781.3 5827.9 5829.8 5834.1 5846.9 5059.1 5240.9 5286.2 5315.4 5334.1 5085.2 5189.5 5216.5 5231.6 5233.2 5116.8 5129.9 5149.4 5152.0 5235.5 5221.3 5231.3 5235.4 5248.1 5271.2 5044.2 5142.0 5142.6 5149.3 5185.4 5676.0 5723.1 5739.5 5744.9 5773.5 6253.6 6258.9 6262.2 6264.2 6267.3 5984.0 6126.9 6202.1 6210.6 6292.7 5834.6 5843.2 5856.8 5861.4 5864.5 6379.8 6385.6 6388.4 6390.4 6393.6 5854.6 5905.1 5933.8 5951.4 5969.1 6046.5 6055.8 6058.9 6078.9 6084.7 5988.0 6047.0 6057.0 6060.5 6064.0 5930.3 6121.2 6165.5 6166.1 6242.2 4716.6 4739.5 4756.8 4773.2 4787.1 4222.9 4231.7 4240.2 4252.4 4263.1 5602.2 5837.4 5888.7 5902.9 5956.0 6178.4 6237.0 6244.6 6247.4 6257.7 5895.7 5913.1 5914.4 5918.7 5951.5 5095.2 5242.6 5258.5 5264.1 5353.8 6356.5 6376.0 6407.4 6410.1 6410.6 6143.7 6217.2 6219.1 6224.1 6224.4 6035.2 6173.2 6199.3 6229.9 6363.0 6035.8 6042.4 6048.8 6050.2 6079.1 6143.2 6146.6 6152.4 6155.9 6158.8 5816.7 6082.8 6140.4 6143.8 6189.5 6112.0 6115.8 6117.4 6125.4 6140.7 6002.7 6050.0 6060.3 6062.0 6070.2 5799.6 6065.4 6130.8 6133.0 6167.2 5914.4 6003.2 6021.3 6027.5 6031.2 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#1] ext.html Cycles:180 Average:68410.29 Median:40327.90 stddev:140225.71 (347.7%) stddev-sans-first:140526.71 23:31:42 INFO - PROCESS | 32030 | Values: 620.0 641.5 663.1 677.0 677.3 35813.1 46083.5 46939.3 47031.8 47083.0 49360.5 49811.8 49986.0 50014.0 50127.2 8494.7 9278.8 9416.8 9417.6 9422.2 8683.8 8774.8 8797.0 10900.3 11168.4 11207.3 11267.3 11279.2 11280.3 11291.4 10998.8 11036.6 11061.6 11069.1 11105.1 10883.0 11098.5 11103.0 11107.6 11119.2 46062.3 46343.1 46366.3 46410.8 46415.3 3646.9 3756.8 3802.6 3976.4 4387.4 35404.8 35492.6 35584.5 35713.6 35970.3 4662.9 4695.0 4721.6 4722.2 4781.9 360469.0 361594.0 361880.0 362035.0 364212.0 63043.7 63203.4 63324.0 63525.4 63561.0 39306.2 40081.6 40160.8 41664.2 41808.8 17564.9 17729.5 17732.0 17741.7 17744.0 12380.4 13263.9 13373.5 13383.6 13389.9 19908.6 20827.7 20949.1 21475.9 21525.4 7403.7 7452.0 7560.8 7597.9 7786.7 1014.2 1049.5 1070.7 1073.7 1155.8 34712.7 35090.0 35488.6 35653.3 35737.5 1122.6 1162.1 1181.7 1200.6 1228.6 17514.6 18602.6 18675.4 18733.0 18795.0 597115.0 597746.0 601753.0 602196.0 606272.0 64041.4 64789.0 64942.0 64956.4 65092.0 1343.7 1414.9 1440.9 1504.9 1507.7 34220.7 34847.8 36075.8 36612.3 37258.3 58480.7 63194.0 63331.4 63562.0 63670.7 580004.0 583970.0 584292.0 586092.0 586495.0 30227.4 31265.4 31379.5 32877.2 33022.3 51786.2 52836.5 53285.0 53636.0 53675.5 44179.3 45350.1 45515.8 46073.8 46889.3 45661.5 45716.5 46356.0 46433.0 47182.3 45041.8 45709.1 46096.8 46214.3 46270.0 37181.3 40495.0 41509.6 41791.4 41906.6 29707.7 30614.5 30753.4 31162.7 31196.7 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#2] jquery.html Cycles:180 Average:30095.70 Median:21243.29 stddev:83556.39 (393.3%) stddev-sans-first:83760.99 23:31:42 INFO - PROCESS | 32030 | Values: 373.2 395.7 401.5 404.3 415.8 17433.7 20314.4 20966.2 21273.8 23392.0 19320.0 19899.6 20081.2 20989.4 21480.7 5244.8 5788.6 6009.9 6031.4 6044.8 4033.4 5955.0 6033.2 6043.0 6043.2 5876.2 6091.5 6132.0 6206.3 6217.4 5987.6 6134.2 6176.2 6180.3 6194.8 5978.9 5981.6 5984.5 5987.3 5987.7 14579.5 14668.6 14904.0 14920.7 14987.0 2370.1 2398.1 2433.2 2486.8 2492.4 10506.5 10582.9 10900.7 11080.1 11262.3 2016.3 2038.0 2048.3 2067.8 2093.3 87571.6 89682.6 91028.5 91233.0 91459.0 10738.2 10840.4 10897.6 11085.5 11089.1 15013.0 15039.4 15073.5 15083.2 15522.8 6616.2 6662.4 7838.8 8233.9 8464.2 8138.3 8144.0 8199.5 8354.8 8362.4 4471.8 4482.5 4488.6 4494.7 4604.3 1882.4 1929.4 1938.1 1941.2 1961.8 964.5 1008.0 1058.3 1065.3 1086.0 20449.4 21219.7 21220.7 21233.8 21252.8 1024.2 1044.8 1051.2 1081.7 1084.3 11135.9 11644.1 11686.2 11752.5 11786.1 492422.0 503380.0 504594.0 511328.0 514212.0 93755.5 96010.5 96858.0 98902.0 101280.5 1011.2 1046.5 1055.8 1057.3 1063.7 17794.7 17984.0 18733.0 18797.8 18871.6 44098.6 45126.5 45435.0 45693.5 45914.3 61428.4 62446.0 62482.7 62738.4 63259.0 8884.5 9000.1 9098.8 9163.4 9295.9 10476.5 13879.2 13893.7 14009.4 14439.1 11307.2 11813.8 12119.2 12120.5 12309.6 11347.4 11488.6 11858.8 11952.9 12514.1 12146.1 12157.6 12168.5 12228.8 12401.4 8861.6 9004.8 9014.7 9039.6 9127.3 11065.7 11462.7 11826.7 11875.3 12354.3 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#3] mootools.html Cycles:180 Average:6378.39 Median:4562.04 stddev:3675.93 (80.6%) stddev-sans-first:3667.50 23:31:42 INFO - PROCESS | 32030 | Values: 1438.4 1499.7 1519.1 1739.7 1852.6 8493.4 9133.7 9449.2 9457.1 9458.3 8783.5 8829.6 8829.8 9127.0 9144.3 2493.3 2538.7 2539.4 2546.7 2548.0 2447.2 2511.8 2514.0 2516.3 2517.6 2491.7 2503.0 2545.1 2548.0 2557.6 2491.5 2507.3 2514.3 2529.3 2532.0 2481.9 2503.2 2506.8 2510.2 2524.4 9245.7 9622.2 9716.4 9720.4 9722.7 5624.9 5768.3 5768.5 5768.9 5778.7 3697.3 3704.2 3745.5 3754.6 3761.9 1197.9 1205.2 1208.5 1227.6 1258.2 13478.9 13669.6 13745.1 13801.2 13801.2 4977.9 5062.8 5078.1 5078.4 5303.9 11765.4 11780.5 11821.6 11890.2 11899.9 5715.1 5717.1 5721.3 5722.5 5727.9 4474.4 4560.4 4641.2 5095.6 5257.4 4375.2 4397.6 4441.4 4470.0 4501.3 1377.1 1387.9 1410.2 1419.1 1425.2 855.3 863.9 976.8 1045.8 1053.0 6491.2 6564.9 6681.4 6752.8 6818.0 998.5 1006.2 1009.2 1024.3 1091.8 4491.3 4544.2 4545.3 4563.7 4564.7 11908.8 12016.2 12039.6 12255.4 12316.1 12171.3 12176.1 12198.6 12204.1 12207.3 8691.3 8847.0 8926.5 8939.4 9172.3 6493.3 6646.0 6675.2 6722.6 6864.2 10928.5 11303.5 11306.0 11410.1 11412.1 11126.3 11152.1 11428.1 11464.8 11532.1 9183.0 9246.6 9304.2 9307.9 9392.2 9320.7 9425.4 9432.0 9445.6 9450.1 8055.0 8267.2 8283.5 8418.1 8432.3 7965.5 8111.4 8163.3 8235.3 8237.5 7610.6 7690.5 7779.3 7785.0 8011.8 5610.2 5622.7 5704.7 5810.3 5827.8 5585.3 5618.9 5632.4 5732.5 5769.3 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#4] prototype.html Cycles:180 Average:4719.11 Median:5228.27 stddev:1369.38 (26.2%) stddev-sans-first:1333.39 23:31:42 INFO - PROCESS | 32030 | Values: 351.1 353.4 361.7 361.9 366.2 5395.8 5501.7 5534.1 5581.8 5585.9 5458.3 5464.5 5483.8 5484.4 5502.2 4340.8 4349.2 4350.1 4351.7 4356.5 4160.4 4300.3 4344.2 4350.1 4352.6 4286.7 4287.0 4298.2 4301.4 4306.9 4297.4 4300.4 4301.9 4315.9 4353.8 3701.4 3804.4 3819.5 3841.5 3846.1 5379.5 5379.8 5390.5 5393.8 5410.7 4932.8 4941.0 4941.2 4943.6 4970.7 5566.6 5724.9 5731.6 5734.9 5743.8 4106.1 4145.4 4152.3 4155.9 4191.2 5796.6 6066.7 6083.7 6095.3 6101.4 4221.2 4241.4 4263.1 4264.3 4297.3 4346.7 4356.2 4363.8 4365.3 4369.7 4960.4 4962.9 4967.0 4976.4 4990.9 5475.3 5638.8 5765.6 5767.2 5810.2 1402.5 1442.5 1446.6 1454.2 1454.3 922.6 941.5 955.0 958.3 962.1 4868.3 4883.8 4914.8 4955.7 5000.5 5169.7 5196.6 5212.8 5219.0 5240.6 4763.6 4899.4 4969.4 4976.2 4980.0 2736.3 2739.5 2747.9 2758.0 2768.6 5325.7 5334.7 5342.4 5366.5 5368.2 4901.6 5236.0 5252.7 5268.2 5298.2 5261.5 5278.3 5288.3 5306.7 5339.9 5084.6 5137.7 5148.8 5218.3 5220.6 5118.9 5452.5 5784.6 5872.7 5875.6 6110.4 6122.1 6122.5 6122.9 6139.6 4023.4 4278.7 4299.6 4309.9 4336.9 5670.4 5840.4 5918.3 5936.8 5989.2 5745.2 5818.4 5831.1 5846.2 5854.1 5792.2 5801.6 5812.0 5818.0 5822.0 5612.6 5726.3 5760.5 5777.3 5791.2 5849.2 5850.8 5855.2 5858.9 5866.7 5555.2 5647.6 5674.4 5818.3 5821.3 23:31:42 INFO - PROCESS | 32030 | 23:31:42 INFO - PROCESS | 32030 | [#5] yui.html Cycles:180 Average:23566.59 Median:12515.91 stddev:34451.55 (275.3%) stddev-sans-first:34505.80 23:31:42 INFO - PROCESS | 32030 | Values: 804.1 843.2 877.4 885.5 928.8 16421.0 16921.4 17021.6 17034.8 17065.1 16559.9 16742.1 16747.9 16751.4 16765.8 726.8 863.4 864.9 877.4 887.2 886.8 888.5 888.7 889.1 890.2 946.3 947.2 948.4 949.0 949.0 758.2 906.2 912.7 925.4 947.0 682.6 683.6 684.4 685.7 685.8 16076.8 16184.5 16186.7 16191.2 16194.7 14083.3 14499.0 14647.0 14757.0 14841.7 14236.9 14368.0 14386.6 14718.8 14779.8 4567.7 4667.9 4697.1 4779.2 4805.8 89126.0 91412.5 91477.5 91512.0 91544.5 11695.9 11706.4 11752.1 11945.1 12038.3 21244.3 21267.1 21287.7 21304.0 21306.5 8726.8 9049.0 9101.0 9315.8 9430.9 4765.7 4932.4 4971.6 5100.0 5265.1 3725.3 3865.3 3865.8 3867.7 3870.1 671.8 675.7 685.3 701.9 712.2 631.6 669.1 731.6 746.9 780.4 9214.2 10222.3 10794.9 11145.7 11192.6 607.6 624.9 713.0 726.4 727.1 4848.6 5105.5 5224.9 5261.9 5292.8 77642.6 94600.0 138726.0 138842.0 139977.0 116389.4 119322.0 119623.0 121319.0 123135.0 101199.0 101282.5 101378.0 104325.5 104582.0 9930.1 10006.1 10034.2 10034.5 10041.8 49622.0 60262.0 60555.0 61176.4 61567.7 73367.3 76296.0 91386.0 91824.5 94397.5 14700.3 14811.6 14899.4 15088.9 15328.7 16013.8 16403.5 16570.0 16581.3 16958.2 15422.3 15844.3 15859.9 15926.4 16860.0 15553.9 15671.4 15804.7 16230.2 16576.2 15105.2 15713.6 16126.9 16127.6 16487.9 12509.0 12893.9 12937.4 12962.3 13139.8 12553.7 12988.5 13232.3 13255.3 13508.2 23:31:42 INFO - PROCESS | 32030 | -------- Summary: end -------- 23:31:42 INFO - PROCESS | 32030 | 23:31:43 INFO - TEST-INFO | 32030: exit 0 23:31:43 INFO - TEST-OK | dromaeo_css | took 1113291ms 23:31:43 INFO - TEST-START | kraken 23:31:43 INFO - operating with platform_type : linux_ 23:31:43 INFO - Initialising browser for kraken test... 23:31:43 INFO - TEST-INFO | started process 32231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxhKhhf/profile http://localhost:45373/getInfo.html) 23:31:47 INFO - PROCESS | 32231 | __metrics Screen width/height:1600/1200 23:31:47 INFO - PROCESS | 32231 | colorDepth:24 23:31:47 INFO - PROCESS | 32231 | Browser inner width/height: 1024/697 23:31:47 INFO - PROCESS | 32231 | __metrics 23:31:47 INFO - PROCESS | 32231 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:31:47 INFO - PROCESS | 32231 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:31:48 INFO - TEST-INFO | 32231: exit 0 23:31:48 INFO - Browser initialized. 23:31:48 INFO - Running cycle 1/1 for kraken test... 23:31:48 INFO - TEST-INFO | started process 32319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxhKhhf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:32:00 INFO - PROCESS | 32319 | RSS: Main: 144420864 23:32:00 INFO - PROCESS | 32319 | 23:32:03 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45373/tests/kraken/driver.html?testName=audio-beat-detection) 23:32:03 INFO - PROCESS | 32319 | RSS: Main: 191234048 23:32:03 INFO - PROCESS | 32319 | 23:32:06 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45373/tests/kraken/driver.html?testName=audio-dft) 23:32:06 INFO - PROCESS | 32319 | RSS: Main: 181346304 23:32:06 INFO - PROCESS | 32319 | 23:32:09 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45373/tests/kraken/driver.html?testName=audio-fft) 23:32:10 INFO - PROCESS | 32319 | RSS: Main: 269426688 23:32:10 INFO - PROCESS | 32319 | 23:32:12 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45373/tests/kraken/driver.html?testName=audio-oscillator) 23:32:12 INFO - PROCESS | 32319 | RSS: Main: 175099904 23:32:12 INFO - PROCESS | 32319 | 23:32:15 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45373/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:32:15 INFO - PROCESS | 32319 | RSS: Main: 176570368 23:32:15 INFO - PROCESS | 32319 | 23:32:18 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45373/tests/kraken/driver.html?testName=imaging-darkroom) 23:32:19 INFO - PROCESS | 32319 | RSS: Main: 212512768 23:32:19 INFO - PROCESS | 32319 | 23:32:22 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45373/tests/kraken/driver.html?testName=imaging-desaturate) 23:32:23 INFO - PROCESS | 32319 | RSS: Main: 211046400 23:32:23 INFO - PROCESS | 32319 | 23:32:26 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45373/tests/kraken/driver.html?testName=json-parse-financial) 23:32:26 INFO - PROCESS | 32319 | RSS: Main: 211419136 23:32:26 INFO - PROCESS | 32319 | 23:32:28 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45373/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:32:28 INFO - PROCESS | 32319 | RSS: Main: 175583232 23:32:28 INFO - PROCESS | 32319 | 23:32:30 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:32:31 INFO - PROCESS | 32319 | RSS: Main: 182628352 23:32:31 INFO - PROCESS | 32319 | 23:32:32 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:32:33 INFO - PROCESS | 32319 | RSS: Main: 186802176 23:32:33 INFO - PROCESS | 32319 | 23:32:36 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:32:36 INFO - PROCESS | 32319 | RSS: Main: 180875264 23:32:36 INFO - PROCESS | 32319 | 23:32:39 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:32:39 INFO - PROCESS | 32319 | RSS: Main: 184885248 23:32:39 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | Cycle 1(1): loaded http://localhost:45373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45373/tests/kraken/driver.html?testName=ai-astar) 23:32:41 INFO - PROCESS | 32319 | RSS: Main: 235814912 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | __start_tp_report 23:32:41 INFO - PROCESS | 32319 | _x_x_mozilla_page_load 23:32:41 INFO - PROCESS | 32319 | _x_x_mozilla_page_load_details 23:32:41 INFO - PROCESS | 32319 | |i|pagename|runs| 23:32:41 INFO - PROCESS | 32319 | |0;ai-astar;99;109;107;110;109;109;109;109;110;111 23:32:41 INFO - PROCESS | 32319 | |1;audio-beat-detection;147;116;118;168;115;121;137;115;146;115 23:32:41 INFO - PROCESS | 32319 | |2;audio-dft;161;160;159;161;160;161;160;161;159;160 23:32:41 INFO - PROCESS | 32319 | |3;audio-fft;75;74;73;109;75;76;75;74;99;74 23:32:41 INFO - PROCESS | 32319 | |4;audio-oscillator;83;84;126;84;83;82;120;83;83;136 23:32:41 INFO - PROCESS | 32319 | |5;imaging-gaussian-blur;117;117;118;117;117;117;117;116;116;116 23:32:41 INFO - PROCESS | 32319 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;148;147 23:32:41 INFO - PROCESS | 32319 | |7;imaging-desaturate;94;95;95;96;95;94;95;96;95;96 23:32:41 INFO - PROCESS | 32319 | |8;json-parse-financial;53;51;52;49;52;49;49;50;49;50 23:32:41 INFO - PROCESS | 32319 | |9;json-stringify-tinderbox;59;60;61;59;60;59;59;59;60;64 23:32:41 INFO - PROCESS | 32319 | |10;stanford-crypto-aes;73;80;74;71;72;71;72;72;75;72 23:32:41 INFO - PROCESS | 32319 | |11;stanford-crypto-ccm;119;116;118;117;115;115;116;114;156;127 23:32:41 INFO - PROCESS | 32319 | |12;stanford-crypto-pbkdf2;153;149;150;151;152;150;148;150;154;150 23:32:41 INFO - PROCESS | 32319 | |13;stanford-crypto-sha256-iterative;61;60;63;65;62;60;62;59;60;59 23:32:41 INFO - PROCESS | 32319 | __end_tp_report 23:32:41 INFO - PROCESS | 32319 | __start_cc_report 23:32:41 INFO - PROCESS | 32319 | _x_x_mozilla_cycle_collect,1613 23:32:41 INFO - PROCESS | 32319 | __end_cc_report 23:32:41 INFO - PROCESS | 32319 | __startTimestamp1471674761742__endTimestamp 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | ------- Summary: start ------- 23:32:41 INFO - PROCESS | 32319 | Number of tests: 14 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:3.39 (3.1%) stddev-sans-first:1.09 23:32:41 INFO - PROCESS | 32319 | Values: 99.0 109.0 107.0 110.0 109.0 109.0 109.0 109.0 110.0 111.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:129.00 stddev:18.66 (14.5%) stddev-sans-first:18.72 23:32:41 INFO - PROCESS | 32319 | Values: 147.0 116.0 118.0 168.0 115.0 121.0 137.0 115.0 146.0 115.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 23:32:41 INFO - PROCESS | 32319 | Values: 161.0 160.0 159.0 161.0 160.0 161.0 160.0 161.0 159.0 160.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#3] audio-fft Cycles:10 Average:80.40 Median:75.00 stddev:12.69 (16.9%) stddev-sans-first:13.30 23:32:41 INFO - PROCESS | 32319 | Values: 75.0 74.0 73.0 109.0 75.0 76.0 75.0 74.0 99.0 74.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:83.00 stddev:21.69 (26.1%) stddev-sans-first:22.46 23:32:41 INFO - PROCESS | 32319 | Values: 83.0 84.0 126.0 84.0 83.0 82.0 120.0 83.0 83.0 136.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 23:32:41 INFO - PROCESS | 32319 | Values: 117.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 23:32:41 INFO - PROCESS | 32319 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 148.0 147.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 23:32:41 INFO - PROCESS | 32319 | Values: 94.0 95.0 95.0 96.0 95.0 94.0 95.0 96.0 95.0 96.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:1.51 (3.0%) stddev-sans-first:1.27 23:32:41 INFO - PROCESS | 32319 | Values: 53.0 51.0 52.0 49.0 52.0 49.0 49.0 50.0 49.0 50.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.56 (2.6%) stddev-sans-first:1.62 23:32:41 INFO - PROCESS | 32319 | Values: 59.0 60.0 61.0 59.0 60.0 59.0 59.0 59.0 60.0 64.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:72.50 stddev:2.70 (3.7%) stddev-sans-first:2.86 23:32:41 INFO - PROCESS | 32319 | Values: 73.0 80.0 74.0 71.0 72.0 71.0 72.0 72.0 75.0 72.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:117.50 stddev:12.74 (10.8%) stddev-sans-first:13.48 23:32:41 INFO - PROCESS | 32319 | Values: 119.0 116.0 118.0 117.0 115.0 115.0 116.0 114.0 156.0 127.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:150.50 stddev:1.83 (1.2%) stddev-sans-first:1.74 23:32:41 INFO - PROCESS | 32319 | Values: 153.0 149.0 150.0 151.0 152.0 150.0 148.0 150.0 154.0 150.0 23:32:41 INFO - PROCESS | 32319 | 23:32:41 INFO - PROCESS | 32319 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.50 stddev:1.91 (3.1%) stddev-sans-first:2.03 23:32:41 INFO - PROCESS | 32319 | Values: 61.0 60.0 63.0 65.0 62.0 60.0 62.0 59.0 60.0 59.0 23:32:41 INFO - PROCESS | 32319 | -------- Summary: end -------- 23:32:41 INFO - PROCESS | 32319 | 23:32:42 INFO - TEST-INFO | 32319: exit 0 23:32:42 INFO - TEST-OK | kraken | took 59820ms 23:32:42 INFO - SUITE-END | took 1173s 23:32:42 INFO - Completed test suite (00:19:33) 23:32:42 INFO - javascript benchmark 23:32:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.477043673012, 3271.165852707559, 3275.8198299093556, 3301.5377847101395, 3317.3365326934604, 5640.182173584828, 5762.510186634141, 5762.567807414445, 5765.4522820332, 5786.56600754943, 5781.335146458158, 5827.883442331151, 5829.8251052468495, 5834.066549352105, 5846.853828654286, 5059.139946209135, 5240.947590524103, 5286.154215325549, 5315.36215654122, 5334.119870221428, 5085.23721441783, 5189.532942035216, 5216.530512253897, 5231.581473482112, 5233.241180028894, 5116.820911268106, 5129.897402051956, 5149.382074151107, 5152.046871328797, 5235.528802407783, 5221.2951251580935, 5231.267622532849, 5235.39792923811, 5248.134057880445, 5271.156614941591, 5044.192929131322, 5141.974290128563, 5142.588592912595, 5149.330587023663, 5185.351831021122, 5675.971620141914, 5723.141528770676, 5739.540836733051, 5744.856378590527, 5773.480386765169, 6253.593516421418, 6258.937410625881, 6262.2172228471445, 6264.1543391642, 6267.279262884781, 5984.042553191469, 6126.8841215407765, 6202.131701561785, 6210.596311239755, 6292.653904035276, 5834.5915785895, 5843.211166492511, 5856.824295271063, 5861.443162899518, 5864.501517370986, 6379.776707815297, 6385.584936979081, 6388.425041746173, 6390.392912673382, 6393.6483493408305, 5854.590178687451, 5905.1142328650185, 5933.821985340447, 5951.3751056139345, 5969.104634304889, 6046.486048685823, 6055.818325450244, 6058.939410605837, 6078.878422431527, 6084.726187321581, 5988.0, 6047.03247480401, 6056.969715151484, 6060.515158787286, 6064.02975523914, 5930.28836539618, 6121.173641558422, 6165.506759459233, 6166.105914642441, 6242.157308763259, 4716.622670186377, 4739.526047395233, 4756.8097276108565, 4773.212419950739, 4787.138315103315, 4222.873313800591, 4231.661467082626, 4240.151969606091, 4252.383404406344, 4263.062126332202, 5602.159676048625, 5837.416258374214, 5888.705564721832, 5902.940970590239, 5956.04703247487, 6178.382161783786, 6237.033259844731, 6244.594101383395, 6247.406496382836, 6257.655828929362, 5895.705214739246, 5913.053911374245, 5914.357229574764, 5918.674472904032, 5951.494122999594, 5095.210242412358, 5242.580593552507, 5258.50044245796, 5264.078786212397, 5353.785848566014, 6356.459700925473, 6375.995783150471, 6407.359264073555, 6410.102585638015, 6410.583312084699, 6143.692815359124, 6217.160683307877, 6219.0982307564245, 6224.097505861534, 6224.439800417984, 6035.245594300713, 6173.166622505904, 6199.3490683477585, 6229.875402491834, 6363.0, 6035.849103772441, 6042.425969532887, 6048.827161416549, 6050.152978582913, 6079.148919151234, 6143.232095988002, 6146.569740118328, 6152.415520525543, 6155.938440615716, 6158.784442544489, 5816.680082595498, 6082.787102451393, 6140.385961403824, 6143.754249829957, 6189.473894718999, 6112.0, 6115.785947491817, 6117.449429551361, 6125.356837531995, 6140.692965351625, 6002.729877155626, 6050.0, 6060.303065147565, 6062.0, 6070.2412198475195, 5799.565032622486, 6065.3934606539, 6130.816075517741, 6133.018717005258, 6167.167432396748, 5914.4381283777975, 6003.249593800775, 6021.307549631849, 6027.547933905063, 6031.185789918481], "value": 5679.167622326181, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.9783007594735, 641.4675819070172, 663.118052989524, 677.0149432575603, 677.3260605697332, 35813.10467238317, 46083.53916460839, 46939.29591056137, 47031.764841175835, 47083.0, 49360.50639493613, 49811.75094124534, 49985.99999999991, 50014.0, 50127.24809127857, 8494.660213591449, 9278.81442371154, 9416.81166376672, 9417.576209070574, 9422.246220302393, 8683.782905427383, 8774.780630484258, 8796.956015219916, 10900.291481053746, 11168.441577921072, 11207.327560346364, 11267.26762760422, 11279.154063445234, 11280.323180609148, 11291.378974156418, 10998.780024399468, 11036.613718519853, 11061.557537698482, 11069.059129973963, 11105.111591072695, 10883.0, 11098.500567474483, 11103.0, 11107.611233606865, 11119.166062545342, 46062.30906536405, 46343.07313853704, 46366.30450543245, 46410.76794616039, 46415.30377044347, 3646.890593282206, 3756.8121593920196, 3802.6007269236607, 3976.4035394691027, 4387.3857659927635, 35404.82297588521, 35492.64507354945, 35584.466233006526, 35713.642863571295, 35970.28059438823, 4662.883427914296, 4694.95305046952, 4721.6458765592715, 4722.150012997659, 4781.928271075936, 360469.0, 361594.0, 361880.0, 362034.9999999974, 364212.0, 63043.6847815758, 63203.36796632067, 63324.0, 63525.36474635287, 63561.0, 39306.21387572232, 40081.59918400837, 40160.799196003834, 41664.166716665495, 41808.79095604503, 17564.91217543904, 17729.46811595654, 17732.02473863924, 17741.7338739919, 17744.0, 12380.442880070419, 13263.933680331536, 13373.46506139763, 13383.598492045254, 13389.866101338863, 19908.60182796365, 20827.687584686242, 20949.057292421876, 21475.892620536797, 21525.354239372842, 7403.666834992438, 7451.962740186264, 7560.810979725441, 7597.924020759721, 7786.727464538714, 1014.2241185493103, 1049.499216120939, 1070.6895000449938, 1073.656429942411, 1155.774623948322, 34712.65287347094, 35090.0, 35488.64511354905, 35653.28693426117, 35737.46393804095, 1122.6407549584055, 1162.0877611075266, 1181.6632259805915, 1200.591798788416, 1228.6436933289424, 17514.64970700579, 18602.627947441248, 18675.43973680792, 18733.0, 18795.0, 597115.0, 597746.0, 601753.0, 602196.0, 606272.0, 64041.35958640354, 64789.0, 64942.0, 64956.35043649597, 65092.0, 1343.7446885091802, 1414.9363278652486, 1440.863118003788, 1504.9021813582083, 1507.6984603079427, 34220.657793422746, 34847.825760872045, 36075.819620901726, 36612.2540809435, 37258.254834903695, 58480.70759646175, 63194.0, 63331.36668633439, 63562.046569300575, 63670.68164659147, 580004.0, 583970.0, 584292.0, 586092.0, 586495.0, 30227.395452091274, 31265.374692506477, 31379.529307059955, 32877.17807054843, 33022.33955320928, 51786.22320665118, 52836.47163528315, 53285.0, 53636.0, 53675.46324536705, 44179.267807401004, 45350.09299814051, 45515.77242113901, 46073.76963115163, 46889.29666054944, 45661.54338456573, 45716.54283457256, 46356.0, 46433.0, 47182.29226561673, 45041.774791125834, 45709.08581828411, 46096.769516152206, 46214.30678539892, 46270.0, 37181.25637487289, 40495.0, 41509.58490415057, 41791.37312940248, 41906.58093419027, 29707.702922970493, 30614.540781887845, 30753.40116611413, 31162.68837311598, 31196.68803311938], "value": 22050.07159435296, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.21065945525186, 395.728925685905, 401.4820881063428, 404.348998113038, 415.8190738303218, 17433.651326973468, 20314.390568282975, 20966.16135354586, 21273.787262127375, 23392.0, 19320.0, 19899.60200795983, 20081.196752129934, 20989.37031889046, 21480.677789833164, 5244.816431424901, 5788.623739456922, 6009.9399006010035, 6031.4270144336215, 6044.788432404866, 4033.4353190553347, 5955.017422125366, 6033.215681961339, 6043.033114701649, 6043.244594425697, 5876.206712093876, 6091.451769340759, 6131.969340153293, 6206.2862770781485, 6217.409346112135, 5987.640741555499, 6134.202553668039, 6176.197094377724, 6180.258368995726, 6194.783182588588, 5978.880422391571, 5981.611195272337, 5984.4613984741145, 5987.341392446828, 5987.670678112702, 14579.48971785993, 14668.55994320172, 14904.0, 14920.701585968329, 14987.0, 2370.0638247892157, 2398.136670798511, 2433.209207007712, 2486.78862296705, 2492.426741849367, 10506.474676266156, 10582.894171058344, 10900.672979810619, 11080.113590912708, 11262.267952583055, 2016.3446879764135, 2037.9796202037937, 2048.3445297504804, 2067.7518697756364, 2093.2568938027084, 87571.56214218952, 89682.55158724164, 91028.54485727595, 91233.0, 91459.0, 10738.248322617497, 10840.403777792297, 10897.618583349704, 11085.50115244816, 11089.112870970303, 15013.0, 15039.398424062914, 15073.472428464951, 15083.170425626477, 15522.767158492632, 6616.206055273398, 6662.400383965466, 7838.804029899298, 8233.925802218724, 8464.238218560236, 8138.308243799225, 8143.982002249719, 8199.549024803693, 8354.8329033419, 8362.372822038371, 4471.8434854779925, 4482.529334419839, 4488.573585509372, 4494.685372023992, 4604.286335617984, 1882.3599976008222, 1929.4211736479, 1938.0987840654163, 1941.1555973151724, 1961.7743959444847, 964.508100868548, 1007.9798404031878, 1058.2697938422464, 1065.3366583541147, 1086.0008791911303, 20449.386518404473, 21219.68170477444, 21220.681689774665, 21233.78766212349, 21252.78747212508, 1024.1601886453186, 1044.8328267477168, 1051.2378525569054, 1081.734974931131, 1084.2843723142687, 11135.944320278346, 11644.126690498077, 11686.24039437434, 11752.529898804123, 11786.116041296766, 492422.0, 503380.0, 504594.0, 511328.0, 514212.0, 93755.53122234344, 96010.51994740122, 96858.0, 98902.01097989072, 101280.49359753155, 1011.2365164301053, 1046.5238316565944, 1055.8069381598743, 1057.3285963413289, 1063.7021633942509, 17794.733079004083, 17984.0, 18733.0, 18797.812021879607, 18871.622567548846, 44098.55901441073, 45126.54873451223, 45435.0, 45693.54306456893, 45914.311285331416, 61428.385716142264, 62446.0, 62482.68758656178, 62738.37261627326, 63259.0, 8884.511351875708, 9000.099990000948, 9098.818023639622, 9163.358564900393, 9295.907040929775, 10476.476176191312, 13879.236641984788, 13893.65265868321, 14009.439622415397, 14439.061461005002, 11307.151963602599, 11813.763724725286, 12119.212251203644, 12120.454579543766, 12309.630711078682, 11347.432628368384, 11488.597899073493, 11858.762824743284, 11952.880471195176, 12514.12401131912, 12146.149769516049, 12157.574484892986, 12168.513259469522, 12228.755424891275, 12401.379931003594, 8861.601227944888, 9004.819903602021, 9014.737936688738, 9039.59321830506, 9127.269818414643, 11065.668029959113, 11462.713432164262, 11826.704332391415, 11875.287482751062, 12354.258744475], "value": 9444.889262828325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.3743071763777, 1499.6925630245798, 1519.0809560216073, 1739.7390391441281, 1852.6387354465876, 8493.36299777516, 9133.68032118876, 9449.244060475163, 9457.101575350347, 9458.290628202894, 8783.516906570136, 8829.602667879944, 8829.779255518615, 9126.95436522818, 9144.314176436777, 2493.2769496845895, 2538.708048574419, 2539.415934335104, 2546.719860815318, 2547.9745202547924, 2447.21689059501, 2511.7990560755097, 2514.0446630280467, 2516.2702816183287, 2517.6097704855683, 2491.700995880488, 2502.987485062572, 2545.0964907457865, 2548.0062775517563, 2557.565213913639, 2491.5016996600752, 2507.3104896153613, 2514.2959971208, 2529.3297276221815, 2531.974680253192, 2481.913133040353, 2503.2114883811723, 2506.786923111538, 2510.221831232324, 2524.432002799374, 9245.72262832116, 9622.230221582256, 9716.417014979053, 9720.368176068603, 9722.659706910295, 5624.943750562524, 5768.336641286264, 5768.538516918637, 5768.913466298009, 5778.682172480512, 3697.2605478904325, 3704.240630670733, 3745.494358261627, 3754.568224654145, 3761.9059523512133, 1197.868234494205, 1205.2346759807585, 1208.516593362662, 1227.613301809936, 1258.2261908926037, 13478.932605336911, 13669.589912302647, 13745.106568073043, 13801.171929684151, 13801.171929684251, 4977.850664480072, 5062.772175252123, 5078.060558796635, 5078.4413714491375, 5303.946960530422, 11765.352905590275, 11780.528778848922, 11821.645350639494, 11890.227135236182, 11899.88100119005, 5715.142728590661, 5717.085266357363, 5721.284839395075, 5722.54219662426, 5727.914081288784, 4474.373587697725, 4560.407147070926, 4641.2109941310055, 5095.592352611782, 5257.395399529027, 4375.16871794358, 4397.560243975577, 4441.356003379492, 4470.0, 4501.257292546758, 1377.0635967542169, 1387.8681525255085, 1410.2173293821822, 1419.0775995602735, 1425.1947649577955, 855.3200205836375, 863.9092895246034, 976.7606936300509, 1045.7803861189084, 1052.984205236922, 6491.221053473613, 6564.934350656432, 6681.398674119256, 6752.76365327221, 6817.9659101705165, 998.4957596413765, 1006.2151521812998, 1009.1674368645896, 1024.3239461955075, 1091.7652704668535, 4491.281394976788, 4544.227481328182, 4545.2954792007295, 4563.680542362003, 4564.748938808398, 11908.761824763456, 12016.158868879269, 12039.63881083569, 12255.387230638433, 12316.076294277966, 12171.330576818184, 12176.147669663216, 12198.634040978784, 12204.084693648012, 12207.328596927076, 8691.261242794308, 8847.0, 8926.509042002623, 8939.374243803002, 9172.266218702622, 6493.253275873146, 6645.966770166118, 6675.198976122896, 6722.596644201363, 6864.24493305746, 10928.453577320943, 11303.547858085913, 11305.99999999967, 11410.087193024705, 11412.144089193178, 11126.276792008493, 11152.107831373633, 11428.085753139896, 11464.828027579426, 11532.135089868461, 9183.0, 9246.583903724482, 9304.209142222986, 9307.906920930704, 9392.201662858733, 9320.72037838866, 9425.38734982224, 9432.0, 9445.622175113192, 9450.149486546245, 8055.0, 8267.214614611603, 8283.544405057539, 8418.074011858813, 8432.32541396699, 7965.4822436541435, 8111.391645626483, 8163.265306122477, 8235.341172706288, 8237.50574965504, 7610.6194690266375, 7690.538567685957, 7779.299863012356, 7784.9610751945875, 8011.799705007422, 5610.214569960127, 5622.746976385991, 5704.686242256716, 5810.331811841692, 5827.796027139199, 5585.273914391103, 5618.887622247614, 5632.408597097246, 5732.455416735404, 5769.336526299529], "value": 5068.201246208734, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.0836716170794, 353.4327404515752, 361.6852740289049, 361.90047736872356, 366.2071614953625, 5395.758975435652, 5501.724913754307, 5534.142207957763, 5581.776728930843, 5585.8603534911645, 5458.344998600173, 5464.453554644543, 5483.8338856151795, 5484.396716361197, 5502.202180683808, 4340.761258130801, 4349.173657005174, 4350.08648183881, 4351.672739814356, 4356.542563030884, 4160.39674247234, 4300.290452075419, 4344.197158179351, 4350.108227813306, 4352.564743525654, 4286.699931004831, 4287.027967135122, 4298.226319262531, 4301.440812694345, 4306.935395969063, 4297.355396690521, 4300.440942677463, 4301.870943871689, 4315.870523884289, 4353.782310884443, 3701.371396585521, 3804.372278574032, 3819.5416550014174, 3841.5006049213703, 3846.134619710571, 5379.488948549882, 5379.838604841861, 5390.4879036491475, 5393.7572809223675, 5410.702411367373, 4932.827351042696, 4941.0, 4941.234108713119, 4943.555080042777, 4970.701757894538, 5566.638168519034, 5724.914126288109, 5731.570132240098, 5734.913976290359, 5743.827685169452, 4106.096658735103, 4145.357469592218, 4152.294110001307, 4155.937660935088, 4191.1827193697245, 5796.594238403271, 6066.726997285133, 6083.661594449214, 6095.329513753483, 6101.352634788626, 4221.176870510221, 4241.363795430649, 4263.147370525907, 4264.33902745075, 4297.290946993711, 4346.717463364871, 4356.194104090753, 4363.847265345697, 4365.323374876835, 4369.737815731066, 4960.429550601655, 4962.875928101754, 4967.006598680278, 4976.377952755905, 4990.850274491771, 5475.342958844884, 5638.774449022075, 5765.567582431335, 5767.192593036978, 5810.1865738796605, 1402.5021117503343, 1442.451868290043, 1446.5968011029286, 1454.1711224602086, 1454.3092031285055, 922.5608051439775, 941.4633658814547, 954.9522523873917, 958.3195930889008, 962.138885697311, 4868.294097355863, 4883.754642566123, 4914.827981020647, 4955.6531042826655, 5000.549950504472, 5169.71566563835, 5196.583588631304, 5212.843614691566, 5218.973905130526, 5240.6069544784295, 4763.618910487153, 4899.363082799213, 4969.378827646544, 4976.2038073908, 4979.950200498021, 2736.329599248157, 2739.493193759161, 2747.8900843966017, 2757.9724202758116, 2768.584712293132, 5325.733713314319, 5334.733263336818, 5342.439043900412, 5366.463353664602, 5368.194770784414, 4901.583365413909, 5235.973820130875, 5252.65857719247, 5268.2088090049565, 5298.231756395301, 5261.526462618306, 5278.340207474066, 5288.312519372534, 5306.708131052752, 5339.866503337447, 5084.618653600921, 5137.6660517066275, 5148.76830542619, 5218.347706536683, 5220.556252718409, 5118.948810511847, 5452.454754524516, 5784.595078344475, 5872.677002764889, 5875.559333050124, 6110.419510146529, 6122.071547462535, 6122.510199183966, 6122.877542449215, 6139.631622102509, 4023.3562629979065, 4278.6790990676445, 4299.613034826881, 4309.9353509698, 4336.869893903188, 5670.432956704297, 5840.445157710181, 5918.260217472816, 5936.7922122725495, 5989.1615173874825, 5745.224394706828, 5818.389069147848, 5831.096180092092, 5846.239988801429, 5854.063349864001, 5792.218050563289, 5801.622894511843, 5812.0, 5817.981853175762, 5822.0, 5612.5509959202345, 5726.284214473191, 5760.510356619734, 5777.277840269966, 5791.218185544897, 5849.151872978479, 5850.765969361178, 5855.180274761452, 5858.9414105860105, 5866.735996880236, 5555.166724991283, 5647.554991455291, 5674.375818659862, 5818.272715910512, 5821.301443826768], "value": 4287.043769589754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.13555427915, 843.2410830252771, 877.4428238068826, 885.5218182181623, 928.8341787084925, 16421.0, 16921.40775072871, 17021.574460638458, 17034.82965170348, 17065.146742662862, 16559.91720041398, 16742.079185644816, 16747.91626041871, 16751.413700520454, 16765.832341676578, 726.7819654103774, 863.3697400897348, 864.9394542382036, 877.3770622857778, 887.1660638999352, 886.8182022685334, 888.4935586715575, 888.6667499687617, 889.1242126505387, 890.2388457868526, 946.2666433514013, 947.2422062350092, 948.3977674176913, 949.0225068179786, 949.0272470717535, 758.2303961479075, 906.1663269791808, 912.7170577121047, 925.3661242049151, 947.0340252941995, 682.576802382526, 683.6479213205204, 684.3601232847254, 685.6605980039913, 685.8319711670623, 16076.839231607652, 16184.514464566082, 16186.676266474722, 16191.190440477929, 16194.676106477804, 14083.295835208199, 14499.0, 14647.0, 14757.0, 14841.70316593673, 14236.857631423656, 14368.0, 14386.568402947929, 14718.779218311734, 14779.778303325458, 4567.725936443824, 4667.8599642010795, 4697.060587882403, 4779.187538118528, 4805.807767689288, 89126.0, 91412.54293728556, 91477.54261228717, 91512.0, 91544.54227728886, 11695.883041169649, 11706.356150411639, 11752.118591105702, 11945.104117191246, 12038.277703337828, 21244.25645102414, 21267.149314027574, 21287.68068478974, 21304.0, 21306.467338316357, 8726.781830454289, 9048.954755226314, 9101.0, 9315.767105822273, 9430.90569094314, 4765.7140571565815, 4932.383452068491, 4971.602271818246, 5099.97450012755, 5265.052290587731, 3725.3480640887738, 3865.3042452358845, 3865.820924617962, 3867.651911327938, 3870.1292209253256, 671.7514519627769, 675.6621689155422, 685.3215316836296, 701.9052427922268, 712.2058904321748, 631.5515983651567, 669.1468377818223, 731.579341878422, 746.9066366704162, 780.3640033372783, 9214.216791572791, 10222.284440089272, 10794.892051079545, 11145.72135696614, 11192.608258710907, 607.6445279511447, 624.8906441372742, 713.0445203427432, 726.4297526441718, 727.1165533876366, 4848.587870031016, 5105.489451054865, 5224.92162617561, 5261.947380526222, 5292.841214763564, 77642.61178694184, 94600.0, 138726.0, 138842.0, 139977.0, 116389.41805291089, 119322.0, 119623.0, 121319.0, 123135.0, 101199.0, 101282.49358753307, 101378.0, 104325.47837260766, 104582.0, 9930.106290433896, 10006.149477294512, 10034.1972642187, 10034.548445320124, 10041.849372259569, 49622.0, 60262.0, 60555.0, 61176.38823611885, 61567.69216153891, 73367.26632733818, 76296.0, 91386.0, 91824.54087729519, 94397.5280123595, 14700.264986750406, 14811.629709257355, 14899.404023839359, 15088.924555377153, 15328.693426131638, 16013.759793603338, 16403.507894763177, 16570.0, 16581.336746530003, 16958.152092395085, 15422.305996230421, 15844.287007084478, 15859.920700396886, 15926.362945482051, 16860.0, 15553.922230388776, 15671.373145074069, 15804.68390632158, 16230.18849057566, 16576.171191440622, 15105.244737763287, 15713.607159820638, 16126.919365403099, 16127.596810079842, 16487.917560412123, 12509.0, 12893.935530322664, 12937.417816198105, 12962.287074210632, 13139.751723586243, 12553.748925021631, 12988.480460781462, 13232.338383081, 13255.337233138498, 13508.189508629514], "value": 8353.39996083141, "unit": "score"}], "name": "dromaeo_css", "value": 7738.114720685967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 107.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 111.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 118.0, 168.0, 115.0, 121.0, 137.0, 115.0, 146.0, 115.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 161.0, 160.0, 161.0, 160.0, 161.0, 159.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 73.0, 109.0, 75.0, 76.0, 75.0, 74.0, 99.0, 74.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 126.0, 84.0, 83.0, 82.0, 120.0, 83.0, 83.0, 136.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 148.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 96.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 52.0, 49.0, 52.0, 49.0, 49.0, 50.0, 49.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 64.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 74.0, 71.0, 72.0, 71.0, 72.0, 72.0, 75.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 118.0, 117.0, 115.0, 115.0, 116.0, 114.0, 156.0, 127.0], "value": 121.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 150.0, 151.0, 152.0, 150.0, 148.0, 150.0, 154.0, 150.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 63.0, 65.0, 62.0, 60.0, 62.0, 59.0, 60.0, 59.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1449.8, "alertThreshold": 2.0}]} 23:32:43 INFO - Return code: 0 23:32:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:32:43 INFO - # TBPL SUCCESS # 23:32:43 INFO - Running post-action listener: _resource_record_post_action 23:32:43 INFO - [mozharness: 2016-08-20 06:32:43.065034Z] Finished run-tests step (success) 23:32:43 INFO - Running post-run listener: _resource_record_post_run 23:32:43 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329920512; Read time: 16; Write time: 380684 23:32:43 INFO - TinderboxPrint: CPU usage
13.0% 23:32:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:32:43 INFO - TinderboxPrint: I/O write bytes / time
329,920,512 / 380,684 23:32:43 INFO - TinderboxPrint: CPU idle
8,257.8 (87.0%) 23:32:43 INFO - TinderboxPrint: CPU user
1,187.4 (12.5%) 23:32:43 INFO - TinderboxPrint: Swap in / out
0 / 0 23:32:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78348288; Read time: 0; Write time: 141972 23:32:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250589184; Read time: 0; Write time: 238664 23:32:43 INFO - Running post-run listener: _upload_blobber_files 23:32:43 INFO - Blob upload gear active. 23:32:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:32:43 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 23:32:43 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'] 23:32:43 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 23:32:44 INFO - (blobuploader) - INFO - Open directory for files ... 23:32:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:32:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:32:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:44 INFO - (blobuploader) - INFO - Done attempting. 23:32:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:32:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:32:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:45 INFO - (blobuploader) - INFO - Done attempting. 23:32:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:32:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:32:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:49 INFO - (blobuploader) - INFO - Done attempting. 23:32:49 INFO - (blobuploader) - INFO - Iteration through files over. 23:32:49 INFO - Return code: 0 23:32:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:32:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:32:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe60ff3fc926d1cd20bfa860f7fc50eb9cc449ab26be100bd60e67be9f1cc307e9060e8c4fe93509e8a5c6a161cd9b40f41c16067b097f85c04c270d72448fe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9ecbe1835cfcbd32b76acce5342bd7b2f28c5c876b7192708b08fc56719634a38e50145c69c4ee074e415f614c10e42cdf5d31b500d7badc0f5ff8cafda6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f433d1e70b00f714c639ead4fb866938452ff7bb62933ff3cff97e3d2847543f4d9292506f732a7ddb7e43f7aa1087b50cfdfb476af66ae55981d80311a04ccb"} 23:32:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:32:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:32:49 INFO - Contents: 23:32:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe60ff3fc926d1cd20bfa860f7fc50eb9cc449ab26be100bd60e67be9f1cc307e9060e8c4fe93509e8a5c6a161cd9b40f41c16067b097f85c04c270d72448fe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9ecbe1835cfcbd32b76acce5342bd7b2f28c5c876b7192708b08fc56719634a38e50145c69c4ee074e415f614c10e42cdf5d31b500d7badc0f5ff8cafda6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f433d1e70b00f714c639ead4fb866938452ff7bb62933ff3cff97e3d2847543f4d9292506f732a7ddb7e43f7aa1087b50cfdfb476af66ae55981d80311a04ccb"} 23:32:49 INFO - Running post-run listener: copy_logs_to_upload_dir 23:32:49 INFO - Copying logs to upload dir... 23:32:49 INFO - mkdir: /builds/slave/test/build/upload/logs 23:32:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.028200 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-19 23:32:48.843102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 23:32:48.850463) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe60ff3fc926d1cd20bfa860f7fc50eb9cc449ab26be100bd60e67be9f1cc307e9060e8c4fe93509e8a5c6a161cd9b40f41c16067b097f85c04c270d72448fe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9ecbe1835cfcbd32b76acce5342bd7b2f28c5c876b7192708b08fc56719634a38e50145c69c4ee074e415f614c10e42cdf5d31b500d7badc0f5ff8cafda6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f433d1e70b00f714c639ead4fb866938452ff7bb62933ff3cff97e3d2847543f4d9292506f732a7ddb7e43f7aa1087b50cfdfb476af66ae55981d80311a04ccb"} build_url:https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/KFqz0XPrTWyx_2vGgSLkwA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe60ff3fc926d1cd20bfa860f7fc50eb9cc449ab26be100bd60e67be9f1cc307e9060e8c4fe93509e8a5c6a161cd9b40f41c16067b097f85c04c270d72448fe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9ecbe1835cfcbd32b76acce5342bd7b2f28c5c876b7192708b08fc56719634a38e50145c69c4ee074e415f614c10e42cdf5d31b500d7badc0f5ff8cafda6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f433d1e70b00f714c639ead4fb866938452ff7bb62933ff3cff97e3d2847543f4d9292506f732a7ddb7e43f7aa1087b50cfdfb476af66ae55981d80311a04ccb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 23:32:48.879950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:32:48.880344) ========= 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=6d15e502d8e29d5d2676d664000001c3-1471672012.583099-637195495 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 23:32:48.960003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 23:32:48.960309) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 23:32:48.960591) ========= ========= Total master_lag: 0.19 =========