builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1471667991.18 results: success (0) buildid: 20160819184422 builduid: 696d33822efc441cab7f5928ccd54e75 revision: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.180684) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.181221) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.181607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.197889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.198350) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 21:39: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% 17.7M=0.001s 2016-08-19 21:39:51 (17.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075550 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.325593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.325909) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013949 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.356914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.357736) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 --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 f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 --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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 21:39:51,481 truncating revision to first 12 chars 2016-08-19 21:39:51,481 Setting DEBUG logging. 2016-08-19 21:39:51,481 attempt 1/10 2016-08-19 21:39:51,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f4aa3c3af595?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 21:39:51,797 unpacking tar archive at: mozilla-aurora-f4aa3c3af595/testing/mozharness/ program finished with exit code 0 elapsedTime=0.697432 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:52.073576) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:52.074272) ========= script_repo_revision: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:52.075140) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:52.075800) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:52.087723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-19 21:39:52.088044) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:39:52 INFO - MultiFileLogger online at 20160819 21:39:52 in /builds/slave/test 21:39: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 21:39:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:39:52 INFO - {'append_to_log': False, 21:39:52 INFO - 'base_work_dir': '/builds/slave/test', 21:39:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:39:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:39:52 INFO - 'branch': 'Mozilla-Aurora', 21:39:52 INFO - 'buildbot_json_path': 'buildprops.json', 21:39:52 INFO - 'config_files': ('talos/linux_config.py',), 21:39:52 INFO - 'default_actions': ('clobber', 21:39:52 INFO - 'read-buildbot-config', 21:39:52 INFO - 'download-and-extract', 21:39:52 INFO - 'populate-webroot', 21:39:52 INFO - 'create-virtualenv', 21:39:52 INFO - 'install', 21:39:52 INFO - 'run-tests'), 21:39:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:39:52 INFO - 'download_minidump_stackwalk': True, 21:39:52 INFO - 'download_symbols': 'ondemand', 21:39:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:39:52 INFO - 'tooltool.py': '/tools/tooltool.py', 21:39:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:39:52 INFO - '/tools/misc-python/virtualenv.py')}, 21:39:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:39:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:39:52 INFO - 'installer_path': 'installer.exe', 21:39:52 INFO - 'log_level': 'info', 21:39:52 INFO - 'log_name': 'talos', 21:39:52 INFO - 'log_to_console': True, 21:39:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:39:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:39:52 INFO - 'opt_config_files': (), 21:39:52 INFO - 'pip_index': False, 21:39:52 INFO - 'sps_profile': False, 21:39:52 INFO - 'sps_profile_interval': 0, 21:39:52 INFO - 'suite': 'dromaeojs', 21:39:52 INFO - 'system_bits': '32', 21:39:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:39:52 INFO - 'title': 'talos-linux64-ix-005', 21:39:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:39:52 INFO - 'use_talos_json': True, 21:39:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:39:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:39:52 INFO - 'work_dir': 'build'} 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.301669Z] Running clobber step. 21:39:52 INFO - Running pre-action listener: _resource_record_pre_action 21:39:52 INFO - Running main action method: clobber 21:39:52 INFO - rmtree: /builds/slave/test/build 21:39:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:39:53 INFO - Running post-action listener: _resource_record_post_action 21:39:53 INFO - [mozharness: 2016-08-20 04:39:53.261804Z] Finished clobber step (success) 21:39:53 INFO - [mozharness: 2016-08-20 04:39:53.261907Z] Running read-buildbot-config step. 21:39:53 INFO - Running pre-action listener: _resource_record_pre_action 21:39:53 INFO - Running main action method: read_buildbot_config 21:39:53 INFO - Using buildbot properties: 21:39:53 INFO - { 21:39:53 INFO - "project": "", 21:39:53 INFO - "product": "firefox", 21:39:53 INFO - "script_repo_revision": "production", 21:39:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:39:53 INFO - "repository": "", 21:39:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:39:53 INFO - "buildid": "20160819184422", 21:39:53 INFO - "pgo_build": "True", 21:39:53 INFO - "basedir": "/builds/slave/test", 21:39:53 INFO - "buildnumber": 477, 21:39:53 INFO - "slavename": "talos-linux64-ix-005", 21:39:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:39:53 INFO - "platform": "ubuntu64_hw", 21:39:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:39:53 INFO - "branch": "mozilla-aurora", 21:39:53 INFO - "repo_path": "releases/mozilla-aurora", 21:39:53 INFO - "revision": "f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2", 21:39:53 INFO - "stage_platform": "linux64-pgo", 21:39:53 INFO - "builduid": "696d33822efc441cab7f5928ccd54e75", 21:39:53 INFO - "slavebuilddir": "test" 21:39:53 INFO - } 21:39:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 21:39:53 INFO - Running post-action listener: _resource_record_post_action 21:39:53 INFO - [mozharness: 2016-08-20 04:39:53.264376Z] Finished read-buildbot-config step (success) 21:39:53 INFO - [mozharness: 2016-08-20 04:39:53.264465Z] Running download-and-extract step. 21:39:53 INFO - Running pre-action listener: _resource_record_pre_action 21:39:53 INFO - Running main action method: download_and_extract 21:39:53 INFO - mkdir: /builds/slave/test/build/tests 21:39: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')]} 21:39:53 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:39:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:39:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:39:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:39:53 INFO - retry: Failed, sleeping 30 seconds before retrying 21:40:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:40:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:40:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:40:23 INFO - retry: Failed, sleeping 60 seconds before retrying 21:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:41:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:41:23 INFO - Caught exception: HTTP Error 404: Not Found 21:41:23 INFO - Caught exception: HTTP Error 404: Not Found 21:41:23 INFO - Caught exception: HTTP Error 404: Not Found 21:41:23 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:23 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 INFO - Downloaded 1361 bytes. 21:41:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:23 INFO - Using the following test package requirements: 21:41:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:41:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:41:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:41:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'jsshell-linux-x86_64.zip'], 21:41:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:41:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:41:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:41:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:41:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:41:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:41:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:41:23 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')]} 21:41:23 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:41:23 INFO - retry: Failed, sleeping 30 seconds before retrying 21:41:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:41:54 INFO - retry: Failed, sleeping 60 seconds before retrying 21:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:42:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:42:54 INFO - Caught exception: HTTP Error 404: Not Found 21:42:54 INFO - Caught exception: HTTP Error 404: Not Found 21:42:54 INFO - Caught exception: HTTP Error 404: Not Found 21:42:54 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:54 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:54 INFO - Downloaded 21768454 bytes. 21:42:54 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'] 21:42:54 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 21:42:56 INFO - Return code: 0 21:42: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 21:42: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')]} 21:42:56 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:42:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:42:56 INFO - retry: Failed, sleeping 30 seconds before retrying 21:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:43:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:43:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:43:26 INFO - retry: Failed, sleeping 60 seconds before retrying 21:44:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:44:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:44:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:44:26 INFO - Caught exception: HTTP Error 404: Not Found 21:44:26 INFO - Caught exception: HTTP Error 404: Not Found 21:44:26 INFO - Caught exception: HTTP Error 404: Not Found 21:44:26 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:44:26 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:26 INFO - Downloaded 21768454 bytes. 21:44:26 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'] 21:44:26 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 21:44:28 INFO - Return code: 0 21:44: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')]} 21:44:28 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:44:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:44:28 INFO - retry: Failed, sleeping 30 seconds before retrying 21:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:44:58 INFO - retry: Failed, sleeping 60 seconds before retrying 21:45:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:45:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:45:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:45:58 INFO - Caught exception: HTTP Error 404: Not Found 21:45:58 INFO - Caught exception: HTTP Error 404: Not Found 21:45:58 INFO - Caught exception: HTTP Error 404: Not Found 21:45:58 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:45:58 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 INFO - Downloaded 14413723 bytes. 21:45: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'] 21:45: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 21:45:59 INFO - Return code: 0 21:45: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')]} 21:45:59 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:45:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:45:59 INFO - retry: Failed, sleeping 30 seconds before retrying 21:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:46:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:46:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:46:29 INFO - retry: Failed, sleeping 60 seconds before retrying 21:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:29 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:31 INFO - Downloaded 63751724 bytes. 21:47:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:31 INFO - mkdir: /builds/slave/test/properties 21:47:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:31 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:31 INFO - Contents: 21:47:31 INFO - build_url:https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:31 INFO - Running post-action listener: _resource_record_post_action 21:47:31 INFO - Running post-action listener: set_extra_try_arguments 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.852526Z] Finished download-and-extract step (success) 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.852768Z] Running populate-webroot step. 21:47:31 INFO - Running pre-action listener: _resource_record_pre_action 21:47:31 INFO - Running main action method: populate_webroot 21:47:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:31 INFO - '--tppagecycles', 21:47:31 INFO - '7'], 21:47:31 INFO - 'tests': ['tsvgm']}}, 21:47:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['damp', 'tps']}, 21:47:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['damp', 'tps']}, 21:47:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['dromaeo_dom']}, 21:47:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['basic_compositor_video']}, 21:47:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:47:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tsvgx', 21:47:31 INFO - 'tsvgr_opacity', 21:47:31 INFO - 'tart', 21:47:31 INFO - 'tscrollx', 21:47:31 INFO - 'cart']}, 21:47:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:31 INFO - 'tsvgr_opacity', 21:47:31 INFO - 'tart', 21:47:31 INFO - 'tscrollx', 21:47:31 INFO - 'cart']}, 21:47:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tp5o']}, 21:47:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['tp5o']}, 21:47:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s', 21:47:31 INFO - '--xperf_path', 21:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:31 INFO - 'tests': ['tp5n']}, 21:47:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--xperf_path', 21:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:31 INFO - 'tests': ['tp5n']}}, 21:47:31 INFO - 'talos.zip': {'path': '', 21:47:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:31 INFO - Running post-action listener: _resource_record_post_action 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.873603Z] Finished populate-webroot step (success) 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.873692Z] Running create-virtualenv step. 21:47:31 INFO - Running pre-action listener: _resource_record_pre_action 21:47:31 INFO - Running main action method: create_virtualenv 21:47:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47: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 21:47:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:31 INFO - Using real prefix '/usr' 21:47:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:47:34 INFO - Installing pip.................done. 21:47:34 INFO - Return code: 0 21:47:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:34 INFO - Using env: {'DISPLAY': ':0', 21:47:34 INFO - 'HOME': '/home/cltbld', 21:47:34 INFO - 'LANG': 'en_US.UTF-8', 21:47:34 INFO - 'LANGUAGE': 'en_US:en', 21:47:34 INFO - 'LOGNAME': 'cltbld', 21:47:34 INFO - 'MAIL': '/var/mail/cltbld', 21:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:34 INFO - 'MOZ_NO_REMOTE': '1', 21:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:34 INFO - 'NO_EM_RESTART': '1', 21:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:34 INFO - 'PWD': '/builds/slave/test', 21:47:34 INFO - 'SHELL': '/bin/bash', 21:47:34 INFO - 'SHLVL': '1', 21:47:34 INFO - 'TERM': 'linux', 21:47:34 INFO - 'TMOUT': '86400', 21:47:34 INFO - 'USER': 'cltbld', 21:47:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:34 INFO - '_': '/tools/buildbot/bin/python'} 21:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47: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 21:47:34 INFO - Cleaning up... 21:47:34 INFO - Return code: 0 21:47:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:34 INFO - Using env: {'DISPLAY': ':0', 21:47:34 INFO - 'HOME': '/home/cltbld', 21:47:34 INFO - 'LANG': 'en_US.UTF-8', 21:47:34 INFO - 'LANGUAGE': 'en_US:en', 21:47:34 INFO - 'LOGNAME': 'cltbld', 21:47:34 INFO - 'MAIL': '/var/mail/cltbld', 21:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:34 INFO - 'MOZ_NO_REMOTE': '1', 21:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:34 INFO - 'NO_EM_RESTART': '1', 21:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:34 INFO - 'PWD': '/builds/slave/test', 21:47:34 INFO - 'SHELL': '/bin/bash', 21:47:34 INFO - 'SHLVL': '1', 21:47:34 INFO - 'TERM': 'linux', 21:47:34 INFO - 'TMOUT': '86400', 21:47:34 INFO - 'USER': 'cltbld', 21:47:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:34 INFO - '_': '/tools/buildbot/bin/python'} 21:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:34 INFO - Downloading/unpacking psutil>=3.1.1 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:47:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:36 INFO - Installing collected packages: psutil 21:47:36 INFO - Running setup.py install for psutil 21:47:36 INFO - building 'psutil._psutil_linux' extension 21:47: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 21:47: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:47:37 INFO - building 'psutil._psutil_posix' extension 21:47:37 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 21:47: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 21:47:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:37 INFO - Successfully installed psutil 21:47:37 INFO - Cleaning up... 21:47:37 INFO - Return code: 0 21:47:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:37 INFO - Using env: {'DISPLAY': ':0', 21:47:37 INFO - 'HOME': '/home/cltbld', 21:47:37 INFO - 'LANG': 'en_US.UTF-8', 21:47:37 INFO - 'LANGUAGE': 'en_US:en', 21:47:37 INFO - 'LOGNAME': 'cltbld', 21:47:37 INFO - 'MAIL': '/var/mail/cltbld', 21:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:37 INFO - 'MOZ_NO_REMOTE': '1', 21:47:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:37 INFO - 'NO_EM_RESTART': '1', 21:47:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:37 INFO - 'PWD': '/builds/slave/test', 21:47:37 INFO - 'SHELL': '/bin/bash', 21:47:37 INFO - 'SHLVL': '1', 21:47:37 INFO - 'TERM': 'linux', 21:47:37 INFO - 'TMOUT': '86400', 21:47:37 INFO - 'USER': 'cltbld', 21:47:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:37 INFO - '_': '/tools/buildbot/bin/python'} 21:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:47:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:47:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:47:39 INFO - Installing collected packages: mozsystemmonitor 21:47:39 INFO - Running setup.py install for mozsystemmonitor 21:47:39 INFO - Successfully installed mozsystemmonitor 21:47:39 INFO - Cleaning up... 21:47:39 INFO - Return code: 0 21:47:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:39 INFO - Using env: {'DISPLAY': ':0', 21:47:39 INFO - 'HOME': '/home/cltbld', 21:47:39 INFO - 'LANG': 'en_US.UTF-8', 21:47:39 INFO - 'LANGUAGE': 'en_US:en', 21:47:39 INFO - 'LOGNAME': 'cltbld', 21:47:39 INFO - 'MAIL': '/var/mail/cltbld', 21:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:39 INFO - 'MOZ_NO_REMOTE': '1', 21:47:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:39 INFO - 'NO_EM_RESTART': '1', 21:47:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:39 INFO - 'PWD': '/builds/slave/test', 21:47:39 INFO - 'SHELL': '/bin/bash', 21:47:39 INFO - 'SHLVL': '1', 21:47:39 INFO - 'TERM': 'linux', 21:47:39 INFO - 'TMOUT': '86400', 21:47:39 INFO - 'USER': 'cltbld', 21:47:39 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:39 INFO - '_': '/tools/buildbot/bin/python'} 21:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:39 INFO - Downloading/unpacking jsonschema==2.5.1 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:47:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:41 INFO - Downloading functools32-3.2.3-2.tar.gz 21:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:47:42 INFO - warning: no files found matching '*.txt' 21:47:42 INFO - no previously-included directories found matching 'build' 21:47:42 INFO - no previously-included directories found matching 'dist' 21:47:42 INFO - no previously-included directories found matching '.git*' 21:47:42 INFO - Installing collected packages: jsonschema, functools32 21:47:42 INFO - Running setup.py install for functools32 21:47:42 INFO - warning: no files found matching '*.txt' 21:47:42 INFO - no previously-included directories found matching 'build' 21:47:42 INFO - no previously-included directories found matching 'dist' 21:47:42 INFO - no previously-included directories found matching '.git*' 21:47:42 INFO - Successfully installed jsonschema functools32 21:47:42 INFO - Cleaning up... 21:47:42 INFO - Return code: 0 21:47:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:42 INFO - Using env: {'DISPLAY': ':0', 21:47:42 INFO - 'HOME': '/home/cltbld', 21:47:42 INFO - 'LANG': 'en_US.UTF-8', 21:47:42 INFO - 'LANGUAGE': 'en_US:en', 21:47:42 INFO - 'LOGNAME': 'cltbld', 21:47:42 INFO - 'MAIL': '/var/mail/cltbld', 21:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:42 INFO - 'MOZ_NO_REMOTE': '1', 21:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:42 INFO - 'NO_EM_RESTART': '1', 21:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:42 INFO - 'PWD': '/builds/slave/test', 21:47:42 INFO - 'SHELL': '/bin/bash', 21:47:42 INFO - 'SHLVL': '1', 21:47:42 INFO - 'TERM': 'linux', 21:47:42 INFO - 'TMOUT': '86400', 21:47:42 INFO - 'USER': 'cltbld', 21:47:42 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:42 INFO - '_': '/tools/buildbot/bin/python'} 21:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:47:42 INFO - Cleaning up... 21:47:42 INFO - Return code: 0 21:47:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47: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 21:47: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 21:47:42 INFO - Using env: {'DISPLAY': ':0', 21:47:42 INFO - 'HOME': '/home/cltbld', 21:47:42 INFO - 'LANG': 'en_US.UTF-8', 21:47:42 INFO - 'LANGUAGE': 'en_US:en', 21:47:42 INFO - 'LOGNAME': 'cltbld', 21:47:42 INFO - 'MAIL': '/var/mail/cltbld', 21:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:42 INFO - 'MOZ_NO_REMOTE': '1', 21:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:42 INFO - 'NO_EM_RESTART': '1', 21:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:42 INFO - 'PWD': '/builds/slave/test', 21:47:42 INFO - 'SHELL': '/bin/bash', 21:47:42 INFO - 'SHLVL': '1', 21:47:42 INFO - 'TERM': 'linux', 21:47:42 INFO - 'TMOUT': '86400', 21:47:42 INFO - 'USER': 'cltbld', 21:47:42 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:42 INFO - '_': '/tools/buildbot/bin/python'} 21:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:42 INFO - Downloading/unpacking blobuploader==1.2.4 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:44 INFO - Downloading blobuploader-1.2.4.tar.gz 21:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:47:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:47:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:45 INFO - Downloading docopt-0.6.1.tar.gz 21:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:47:45 INFO - Installing collected packages: blobuploader, requests, docopt 21:47:45 INFO - Running setup.py install for blobuploader 21:47:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:47:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:47:45 INFO - Running setup.py install for requests 21:47:45 INFO - Running setup.py install for docopt 21:47:46 INFO - Successfully installed blobuploader requests docopt 21:47:46 INFO - Cleaning up... 21:47:46 INFO - Return code: 0 21:47:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:46 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')]} 21:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:46 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')]} 21:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:46 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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47:46 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 21:47:46 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 21:47:46 INFO - Using env: {'DISPLAY': ':0', 21:47:46 INFO - 'HOME': '/home/cltbld', 21:47:46 INFO - 'LANG': 'en_US.UTF-8', 21:47:46 INFO - 'LANGUAGE': 'en_US:en', 21:47:46 INFO - 'LOGNAME': 'cltbld', 21:47:46 INFO - 'MAIL': '/var/mail/cltbld', 21:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:46 INFO - 'MOZ_NO_REMOTE': '1', 21:47:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:46 INFO - 'NO_EM_RESTART': '1', 21:47:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:46 INFO - 'PWD': '/builds/slave/test', 21:47:46 INFO - 'SHELL': '/bin/bash', 21:47:46 INFO - 'SHLVL': '1', 21:47:46 INFO - 'TERM': 'linux', 21:47:46 INFO - 'TMOUT': '86400', 21:47:46 INFO - 'USER': 'cltbld', 21:47:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:46 INFO - '_': '/tools/buildbot/bin/python'} 21:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:46 INFO - Running setup.py (path:/tmp/pip-8okeJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:46 INFO - Running setup.py (path:/tmp/pip-bufP25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:46 INFO - Running setup.py (path:/tmp/pip-jD215k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:46 INFO - Running setup.py (path:/tmp/pip-dRVvdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:46 INFO - Running setup.py (path:/tmp/pip-AJjo2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:46 INFO - Running setup.py (path:/tmp/pip-bUEmlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:46 INFO - Running setup.py (path:/tmp/pip-rPzzE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:46 INFO - Running setup.py (path:/tmp/pip-UeLTyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:46 INFO - Running setup.py (path:/tmp/pip-SUEPm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:46 INFO - Running setup.py (path:/tmp/pip-2h5KLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:47 INFO - Running setup.py (path:/tmp/pip-0vkgWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:47 INFO - Running setup.py (path:/tmp/pip-kZVW0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:47 INFO - Running setup.py (path:/tmp/pip-9kCz2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:47 INFO - Running setup.py (path:/tmp/pip-b00mSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:47 INFO - Running setup.py (path:/tmp/pip-Ghrypu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:47 INFO - Running setup.py (path:/tmp/pip-1EIi_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:47 INFO - Running setup.py (path:/tmp/pip-UMUHIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:47:47 INFO - Running setup.py install for manifestparser 21:47:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozcrash 21:47:47 INFO - Running setup.py install for mozdebug 21:47:47 INFO - Running setup.py install for mozdevice 21:47:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozfile 21:47:48 INFO - Running setup.py install for mozhttpd 21:47:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozinfo 21:47:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozInstall 21:47:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozleak 21:47:48 INFO - Running setup.py install for mozlog 21:47:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for moznetwork 21:47:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozprocess 21:47:48 INFO - Running setup.py install for mozprofile 21:47:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozrunner 21:47:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:47:49 INFO - Running setup.py install for mozscreenshot 21:47:49 INFO - Running setup.py install for moztest 21:47:49 INFO - Running setup.py install for mozversion 21:47:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:47:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:47:49 INFO - Cleaning up... 21:47:49 INFO - Return code: 0 21:47:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:49 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')]} 21:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:49 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')]} 21:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:49 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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47:49 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 21:47:49 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 21:47:49 INFO - Using env: {'DISPLAY': ':0', 21:47:49 INFO - 'HOME': '/home/cltbld', 21:47:49 INFO - 'LANG': 'en_US.UTF-8', 21:47:49 INFO - 'LANGUAGE': 'en_US:en', 21:47:49 INFO - 'LOGNAME': 'cltbld', 21:47:49 INFO - 'MAIL': '/var/mail/cltbld', 21:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:49 INFO - 'MOZ_NO_REMOTE': '1', 21:47:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:49 INFO - 'NO_EM_RESTART': '1', 21:47:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:49 INFO - 'PWD': '/builds/slave/test', 21:47:49 INFO - 'SHELL': '/bin/bash', 21:47:49 INFO - 'SHLVL': '1', 21:47:49 INFO - 'TERM': 'linux', 21:47:49 INFO - 'TMOUT': '86400', 21:47:49 INFO - 'USER': 'cltbld', 21:47:49 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:49 INFO - '_': '/tools/buildbot/bin/python'} 21:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:49 INFO - Running setup.py (path:/tmp/pip-orin1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:49 INFO - Running setup.py (path:/tmp/pip-ZW3Y2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:49 INFO - Running setup.py (path:/tmp/pip-lsmdtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:49 INFO - Running setup.py (path:/tmp/pip-2i2AGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:49 INFO - Running setup.py (path:/tmp/pip-7LBs5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:49 INFO - Running setup.py (path:/tmp/pip-OOlqNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47: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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:49 INFO - Running setup.py (path:/tmp/pip-nokxIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:50 INFO - Running setup.py (path:/tmp/pip-zTx1w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:50 INFO - Running setup.py (path:/tmp/pip-oq7wPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:50 INFO - Running setup.py (path:/tmp/pip-7jnxFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:50 INFO - Running setup.py (path:/tmp/pip-QCpGpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:50 INFO - Running setup.py (path:/tmp/pip-pFqFTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47: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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:50 INFO - Running setup.py (path:/tmp/pip-PhJDfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47: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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:50 INFO - Running setup.py (path:/tmp/pip-MQKC54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47: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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:50 INFO - Running setup.py (path:/tmp/pip-sOcQMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47: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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:50 INFO - Running setup.py (path:/tmp/pip-uEU2ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47: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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:50 INFO - Running setup.py (path:/tmp/pip-4zUvgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47: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)) 21:47: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)) 21:47: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)) 21:47: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)) 21:47: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)) 21:47: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)) 21:47:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:52 INFO - Downloading blessings-1.6.tar.gz 21:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:47:52 INFO - Installing collected packages: blessings 21:47:52 INFO - Running setup.py install for blessings 21:47:52 INFO - Successfully installed blessings 21:47:52 INFO - Cleaning up... 21:47:52 INFO - Return code: 0 21:47:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:47:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:53 INFO - Reading from file tmpfile_stdout 21:47:53 INFO - Current package versions: 21:47:53 INFO - argparse == 1.2.1 21:47:53 INFO - blessings == 1.6 21:47:53 INFO - blobuploader == 1.2.4 21:47:53 INFO - docopt == 0.6.1 21:47:53 INFO - functools32 == 3.2.3-2 21:47:53 INFO - jsonschema == 2.5.1 21:47:53 INFO - manifestparser == 1.1 21:47:53 INFO - mozInstall == 1.12 21:47:53 INFO - mozcrash == 0.17 21:47:53 INFO - mozdebug == 0.1 21:47:53 INFO - mozdevice == 0.48 21:47:53 INFO - mozfile == 1.2 21:47:53 INFO - mozhttpd == 0.7 21:47:53 INFO - mozinfo == 0.9 21:47:53 INFO - mozleak == 0.1 21:47:53 INFO - mozlog == 3.3 21:47:53 INFO - moznetwork == 0.27 21:47:53 INFO - mozprocess == 0.23 21:47:53 INFO - mozprofile == 0.28 21:47:53 INFO - mozrunner == 6.12 21:47:53 INFO - mozscreenshot == 0.1 21:47:53 INFO - mozsystemmonitor == 0.3 21:47:53 INFO - moztest == 0.7 21:47:53 INFO - mozversion == 1.4 21:47:53 INFO - psutil == 3.1.1 21:47:53 INFO - requests == 1.2.3 21:47:53 INFO - wsgiref == 0.1.2 21:47:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:53 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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47:53 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 21:47:53 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 21:47:53 INFO - Using env: {'DISPLAY': ':0', 21:47:53 INFO - 'HOME': '/home/cltbld', 21:47:53 INFO - 'LANG': 'en_US.UTF-8', 21:47:53 INFO - 'LANGUAGE': 'en_US:en', 21:47:53 INFO - 'LOGNAME': 'cltbld', 21:47:53 INFO - 'MAIL': '/var/mail/cltbld', 21:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:53 INFO - 'MOZ_NO_REMOTE': '1', 21:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:53 INFO - 'NO_EM_RESTART': '1', 21:47:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:53 INFO - 'PWD': '/builds/slave/test', 21:47:53 INFO - 'SHELL': '/bin/bash', 21:47:53 INFO - 'SHLVL': '1', 21:47:53 INFO - 'TERM': 'linux', 21:47:53 INFO - 'TMOUT': '86400', 21:47:53 INFO - 'USER': 'cltbld', 21:47:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:53 INFO - '_': '/tools/buildbot/bin/python'} 21:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 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)) 21:47:53 INFO - Cleaning up... 21:47:53 INFO - Return code: 0 21:47:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:53 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 0x118e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x144f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', '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 21:47:53 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 21:47:53 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 21:47:53 INFO - Using env: {'DISPLAY': ':0', 21:47:53 INFO - 'HOME': '/home/cltbld', 21:47:53 INFO - 'LANG': 'en_US.UTF-8', 21:47:53 INFO - 'LANGUAGE': 'en_US:en', 21:47:53 INFO - 'LOGNAME': 'cltbld', 21:47:53 INFO - 'MAIL': '/var/mail/cltbld', 21:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:53 INFO - 'MOZ_NO_REMOTE': '1', 21:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:53 INFO - 'NO_EM_RESTART': '1', 21:47:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:53 INFO - 'PWD': '/builds/slave/test', 21:47:53 INFO - 'SHELL': '/bin/bash', 21:47:53 INFO - 'SHLVL': '1', 21:47:53 INFO - 'TERM': 'linux', 21:47:53 INFO - 'TMOUT': '86400', 21:47:53 INFO - 'USER': 'cltbld', 21:47:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:53 INFO - '_': '/tools/buildbot/bin/python'} 21:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:47:53 INFO - Cleaning up... 21:47:53 INFO - Return code: 0 21:47:53 INFO - Running post-action listener: _resource_record_post_action 21:47:53 INFO - Running post-action listener: _start_resource_monitoring 21:47:53 INFO - Starting resource monitoring. 21:47:53 INFO - [mozharness: 2016-08-20 04:47:53.595408Z] Finished create-virtualenv step (success) 21:47:53 INFO - [mozharness: 2016-08-20 04:47:53.595894Z] Running install step. 21:47:53 INFO - Running pre-action listener: _resource_record_pre_action 21:47:53 INFO - Running main action method: install 21:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:53 INFO - Reading from file tmpfile_stdout 21:47:53 INFO - Detecting whether we're running mozinstall >=1.0... 21:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:47:53 INFO - Reading from file tmpfile_stdout 21:47:53 INFO - Output received: 21:47:53 INFO - Usage: mozinstall [options] installer 21:47:53 INFO - Options: 21:47:53 INFO - -h, --help show this help message and exit 21:47:53 INFO - -d DEST, --destination=DEST 21:47:53 INFO - Directory to install application into. [default: 21:47:53 INFO - "/builds/slave/test"] 21:47:53 INFO - --app=APP Application being installed. [default: firefox] 21:47:53 INFO - mkdir: /builds/slave/test/build/application 21:47: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'] 21:47: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 21:48:10 INFO - Reading from file tmpfile_stdout 21:48:10 INFO - Output received: 21:48:10 INFO - /builds/slave/test/build/application/firefox/firefox 21:48:10 INFO - Running post-action listener: _resource_record_post_action 21:48:10 INFO - [mozharness: 2016-08-20 04:48:10.397289Z] Finished install step (success) 21:48:10 INFO - [mozharness: 2016-08-20 04:48:10.397567Z] Running run-tests step. 21:48:10 INFO - Running pre-action listener: _resource_record_pre_action 21:48:10 INFO - Running main action method: run_tests 21:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:10 INFO - Python 2.7.3 21:48:10 INFO - Return code: 0 21:48:10 INFO - grabbing minidump binary from tooltool 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:10 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 0x144d3b0>, '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 0x144e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x144e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:10 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 21:48:10 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 21:48:10 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 21:48:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:10 INFO - Return code: 0 21:48:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:48:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:48:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 21:48:10 INFO - Using env: {'DISPLAY': ':0', 21:48:10 INFO - 'HOME': '/home/cltbld', 21:48:10 INFO - 'LANG': 'en_US.UTF-8', 21:48:10 INFO - 'LANGUAGE': 'en_US:en', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MAIL': '/var/mail/cltbld', 21:48:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SHLVL': '1', 21:48:10 INFO - 'TERM': 'linux', 21:48:10 INFO - 'TMOUT': '86400', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - '_': '/tools/buildbot/bin/python'} 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 21:48:10 INFO - mozversion application_buildid: 20160819184422 21:48:10 INFO - mozversion application_changeset: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 21:48:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:48:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:10 INFO - mozversion application_name: Firefox 21:48:10 INFO - mozversion application_remotingname: firefox-dev 21:48:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:10 INFO - mozversion application_vendor: Mozilla 21:48:10 INFO - mozversion application_version: 50.0a2 21:48:10 INFO - mozversion platform_buildid: 20160819184422 21:48:10 INFO - mozversion platform_changeset: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 21:48:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:10 INFO - mozversion platform_version: 50.0a2 21:48:10 INFO - using testdate: 1471668490 21:48:10 INFO - actual date: 1471668490 21:48:10 INFO - starting webserver on 'localhost:43268' 21:48:10 INFO - SUITE-START | Running 2 tests 21:48:10 INFO - TEST-START | dromaeo_css 21:48:10 INFO - operating with platform_type : linux_ 21:48:10 INFO - Initialising browser for dromaeo_css test... 21:48:10 INFO - TEST-INFO | started process 11572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Jm03e/profile http://localhost:43268/getInfo.html) 21:48:14 INFO - PROCESS | 11572 | __metrics Screen width/height:1600/1200 21:48:14 INFO - PROCESS | 11572 | colorDepth:24 21:48:14 INFO - PROCESS | 11572 | Browser inner width/height: 1024/697 21:48:14 INFO - PROCESS | 11572 | __metrics 21:48:15 INFO - PROCESS | 11572 | 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" 21:48:15 INFO - PROCESS | 11572 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:16 INFO - TEST-INFO | 11572: exit 0 21:48:16 INFO - Browser initialized. 21:48:16 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:16 INFO - TEST-INFO | started process 11662 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3Jm03e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:28 INFO - PROCESS | 11662 | RSS: Main: 145645568 21:48:28 INFO - PROCESS | 11662 | 21:49:18 INFO - PROCESS | 11662 | 1471668558793 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] 21:51:27 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43268/tests/dromaeo/cssquery-ext.html) 21:51:28 INFO - PROCESS | 11662 | RSS: Main: 257753088 21:51:28 INFO - PROCESS | 11662 | 21:54:31 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-ext.html (next: http://localhost:43268/tests/dromaeo/cssquery-jquery.html) 21:54:31 INFO - PROCESS | 11662 | RSS: Main: 227753984 21:54:31 INFO - PROCESS | 11662 | 21:57:33 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43268/tests/dromaeo/cssquery-mootools.html) 21:57:34 INFO - PROCESS | 11662 | RSS: Main: 175439872 21:57:34 INFO - PROCESS | 11662 | 22:00:36 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43268/tests/dromaeo/cssquery-prototype.html) 22:00:36 INFO - PROCESS | 11662 | RSS: Main: 223756288 22:00:36 INFO - PROCESS | 11662 | 22:03:39 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43268/tests/dromaeo/cssquery-yui.html) 22:03:40 INFO - PROCESS | 11662 | RSS: Main: 178671616 22:03:40 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | Cycle 1(1): loaded http://localhost:43268/tests/dromaeo/cssquery-yui.html (next: http://localhost:43268/tests/dromaeo/cssquery-dojo.html) 22:06:43 INFO - PROCESS | 11662 | RSS: Main: 263798784 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | __start_tp_report 22:06:43 INFO - PROCESS | 11662 | _x_x_mozilla_page_load 22:06:43 INFO - PROCESS | 11662 | _x_x_mozilla_page_load_details 22:06:43 INFO - PROCESS | 11662 | |i|pagename|runs| 22:06:43 INFO - PROCESS | 11662 | |0;dojo.html;3234.2399536109015;3247.6265229498586;3261.592300962379;3276.2464633134364;3301.0096970908735;5543.889122217553;5638.520725738312;5658.519025882794;5661.688607126616;5684.801031963882;5592.5246354059955;5625.943740562593;5632.155176723493;5659;5685.744141513632;5000.07498612756;5111.591072714184;5135.486451354854;5153.613478989091;5211.478852114796;5014.022265658198;5120.692758434488;5163.664361816489;5182.352205974254;5182.870428239286;5020.272060551236;5064.961682855024;5077.41609714882;5083;5176.66351687141;5180.093483640367;5185.092608793484;5187.688738675692;5207.921881171786;5227;5026.321446604698;5038.546530812245;5044.445111037783;5076.111680455905;5088.465711100355;5519.254900588409;5526.806561770359;5552.639078459887;5571.693556854371;5597.552195824324;6087.8819223424425;6097.146399504073;6097.969510152465;6103.138856883458;6133.080037994292;5859.707014649251;5896.056630939029;5924.259467566554;5941.2276404067625;6095.0006233636705;5524.06090964537;5543.6396634218645;5548.029094908375;5549.250851135126;5550.528205102531;6058.256287901417;6072.535555179074;6074.89002182083;6092.939070609237;6103;5760.683162426107;5808.82573522795;5850.736716847752;5857.619254748428;5932.58471906971;5941.465268125803;5970.910436343458;5977.342492325927;5981.641101533922;5981.730822113015;5947.286325640865;5954.434328738763;5955.315138759098;5956.434138756811;5961.970190149022;5719.3136823581435;5911.7635294587735;5948.613340132878;5950.642961422329;6033.5474839387225;4580.587747102777;4609.216433206363;4612.654050946193;4626.282926146453;4638.5593368629925;4116.094459218967;4123.876283711494;4133.379993001074;4136.482939632546;4148.730332528376;5602.4957753802355;5723.6279641823985;5788.21859049023;5793.2179155814265;5826.271716035496;6085.1785009023215;6088.573799833969;6091.4363154433695;6097.085437184458;6132.110843927606;5806.796762113306;5809.651420914759;5813.389594092644;5819.359870414251;5820.447057529528;5060.873478163076;5132.948670513321;5152.458991805881;5153.819616313411;5233.973830130825;6256.092866534418;6258.123862659231;6261.186045814015;6261.5616906816085;6262.624242545462;5978.521718262616;6005.579609427298;6032.33644299118;6044.486218671462;6047.667378294236;6069.605475644069;6073;6079.209702738615;6141.792871882774;6172.475339596005;5793.275840519935;5805.274340707411;5840.853978650568;5898.174255604305;5927.081302398135;5946.43508866657;5964.612300200473;5966.254218222723;5974.850628734142;6012.1582978382185;5765.279340082489;5809.186713859978;5837.591368604157;5843.7954671587995;5858.179854820414;5477.589180811535;5683.77264909399;5813.680247586423;5820.097884827858;5874.236349274567;5868.471837534642;5870.911936321044;5879.617824841372;5880.735366908414;5909.054551271776;5891.764329426775;5949.0481522956115;5954.166416701579;5992.850178745566;5995.5803093783015;5613.550915926798;5620.297462817148;5634.577406694432;5637.830865074055;5784.479396854304 22:06:43 INFO - PROCESS | 11662 | |1;ext.html;604.9516038716903;617.4689766800551;621.9004959206525;634.3909846547316;646.4311405962752;34095.7837740547;44902;45833.54166458335;45906.770466147704;45940;45551.31673024899;45899.54100459003;45939.08121837561;46159.76920115395;46196.53803461965;8385;9199.586018629177;9339.299552533586;9339.813203735921;10927.235093543455;10714.357138571711;11090;11094.889051109467;11095.11239100874;11098.667039988813;11176;11216.831747523753;11228.831567526493;11232.775344493106;11237;11069.50187241572;11111.777764444665;11117.277376970553;11128.443577821117;11145.1641126915;10988.560457581687;11009.339439633648;11012.119030477543;11040.944795276053;11054.170937179744;44291.77854110741;44443.77778111121;44484.332735009004;44488.55511444876;44654.33018504725;3703.370427027412;3740.121071548211;3772.7547709399078;3795.2978698940783;3919.1377896862646;34003.48994765081;34066.48900266498;34303;34378.76538025046;35760.46359304612;4660.813567457298;4702.6473014524045;4817.349657796188;4848.878778030577;4912.754362281871;352992.2350388257;356606;357238;357265;358624;61183.694081529306;61403;61414.692926535085;61615;61808.690956545826;63117.05324420137;63227.36772632215;63309;63345;63476;18559.35042273541;19388.321408750628;19404.611907761766;19966.30117945865;19998.800011999985;13634.318284085954;13868.167909925436;14698.191599462132;14937.25313734309;14983.775243371358;20428.489287767923;20506.179752810018;20577.53069631624;20676.17295308201;20760.169593216404;7418.629068546551;7562.2437756223935;7726.806829829186;7827.804304892423;7864.449488535747;1056.6090546497844;1129.1813435259535;1179.5694571481347;1190.119311709329;1234.4259919137464;35147.4727879082;35566.822165889;35638;35647.28705425929;35929.64070359263;1178.7406770510645;1191.9046476281876;1197.9401029948467;1219.5243854896598;1344.9398499225845;17543.210555524776;18496.907515462604;18572.25710971573;18603;18780.906095469436;162483;162867;163401;164425;165528;62420.68789656023;62591.68704156541;63144;63194.684026579576;63343;1360.7210521843197;1444.6821699226152;1456.8563677513102;1499.3627708223744;1509.5094094419358;34018.489722653685;36223.75316610554;36456.27087458289;36689.44965825461;36930.26139477142;52549.7372513135;53155;53196;53248.2012769801;53267.93464130773;412640;416170;416210;417437;533073;45591;45805.77097114493;46073.53926460692;46152.53847461483;46294;50018.74990625024;50385.244221337445;50732.74633626808;50827;51031.48968510267;42666.5733342675;42676.359854601586;42698.5730142707;43144.78427607842;44146.558534414246;42772.786136069124;43146;43663.05452922284;43909.56090439182;44282.3357649642;42811.78594107134;43424.657534246326;43779.78110109429;43834;43964.120717586105;35099.44660566569;37025.44461833128;37786;38955.415668765556;39151.41272880852;29515.543538340007;29599.556006660347;29904.551431728105;30014.3997120052;30080.699193008666 22:06:43 INFO - PROCESS | 11662 | |2;jquery.html;392.4936831487381;403.64680904208797;421.50262690025767;424.71331851000576;430.82120919818277;15698;20137.59724805503;20210.89894550525;20265.39203823884;20364.08361623727;19826.702599460983;20080.59838803223;20092.296769613065;20638.03938625832;20819.479513012182;5745.942540574603;5841.182234487175;5882.323532793719;5890.175375447441;5909.054551271776;4340.305551111807;5804.82585522435;5835.44563266489;6234.438900498954;6468.74125034998;6521.47828173745;6538.444232240266;6546.149000629912;6546.607203567801;6556.377144171296;6457.8708425831455;6554.180727409074;6590.604563726191;6597.538172327939;6597.835054123638;5179.559737422323;5429.4299098594865;5880.294364676223;5892.734826932799;5895.557833162487;14224.85775142256;14408.855911440856;14563.927180364137;14668.853311466855;14680.04579702316;2437.8094583420407;2451.0930955546382;2459.0163934426196;2525.4317778499876;2993.7006299370105;10535.683929482127;10680;10693.732656683567;10737.731556711145;10822.18833587484;1887.707405352159;1937.4865660599953;1949.541857663448;1971.142552989454;2008.1766475744873;81096.59451702704;81264.18735812625;82562.58718706368;83379;84722.15277847266;10859.83710244347;10904.400257985886;10952.4523773811;11173.664790056311;11212.887871121347;15526.611834704223;15534.300956456986;15600.375984960474;15637.921810390875;15655.452059177875;8470.449420787629;8549.401541892008;8726.08376120511;8894.955525222462;8953.238974687094;8859;8881.42270752412;8882;8912.8663070054;9058.411203271766;4692.648051396159;4710.458297295786;4769.523047695203;4800.687955282965;4912.901741965212;1976.5651556657513;1980.3464856597288;2021.5047313408309;2023.6559784836613;2032.6239645665598;1005.8742657167854;1060.7931453366518;1120.3557954176217;1129.4465711801322;1132.2923173016864;20276.391708248775;20785.376438706862;21115.577688446454;21144.471388215417;21322.14711411557;1068.9518971646294;1071.3090056913404;1079.2876701377054;1080.9837852432063;1119.7368618374676;10770.623028193975;11427;11669.533218671158;11670.416479176178;11727.530898764124;466213;542405;548527;549183;583392;91415.54292228496;92164;97527.51236243916;97870.51064744631;101526;476.65918868008845;499.22121490474973;501.17806796853915;501.19057721779063;503.0115822408984;11444.42777861087;11839.881601184223;11883.465244064211;11954.103442241692;12095.939520302341;34480.137996550286;38007;43620.34569481524;44869;45704.31443528416;55227.895442091736;57658.423415765305;61393.386066138766;62929;62981.370186299384;8208.630611622611;8757.605907734294;8777.91222087771;8791.120887911413;8827.293816494794;9740.756481087747;13501.59495215145;13586.116902401312;13620.114692544952;14104.153750774987;11306.152038596974;12013.519459221534;12139;12584.370781460708;12704.936475317565;12071.939640301742;12428.502859885502;12451.128421010442;12507.687307817014;12669.936650317059;12126.636200913634;12391.75216495647;12501.24992500453;12671.746565068832;12685.619431417072;8869.157430044135;8870.822583548423;8950.060243674321;8990.370674052754;9057.139571740674;11501.424928753697;11654.30074195551;11794.410279486163;12324.383780811102;12368.876311237133 22:06:43 INFO - PROCESS | 11662 | |3;mootools.html;1417.5534706567437;1548.8141423029235;1587.6189714468528;1738.4784564630609;1800.4688616858025;8431.367647426445;9153.450792952419;9264.536773161348;9328.626854925795;9728.951355243214;9466.905330946689;9554.856677149848;9560.139587437146;9572.377795443292;9594.952025239882;2523.5836087045664;2570.755778201077;2575.33041409234;2576.6650335456366;2584.5347837389263;2437.171361737009;2493.339265094751;2531.531666641677;2536.797056235506;2538.4034751833306;2461.433870209853;2504.887280072392;2504.887280072401;2521.936951576207;2523.4196134888984;2399.520095980802;2405.374602603318;2409.144753612469;2455.7789798918184;2456.6192240202795;2345.2612427085414;2366.1363602285214;2372.146027430124;2374.655674927126;2423.1761201191503;9425.151736343694;9476.715698528986;9523.809523809554;9526.237900967975;9583.520823958845;5649.406812284692;5870.295564532241;5879.206307148523;5882.35294117651;5882.529397648221;3640.3629364861044;3698.0754811297174;3700.85196592136;3756.567994680592;3764.5106136202116;1216.7688139253514;1219.0491416694992;1235.1971218707822;1248.1949142802841;1254.7866862633375;13544.187348759007;13717.382717777724;13777;13786;13790.931045344809;4756.8107973006745;4796.592289655348;4872.098661747588;4928.260760885896;4989.625778066659;10736.08743256832;10736.892631073746;10774.515146818412;10775.78448431027;10789.082927951054;5668.121441176624;5687.40282270364;5688.06146985751;5695.629784064023;5697.088465845445;4638.072385522842;4661.720296782204;5177.663451875616;5243.318368612056;5286.15421532553;4337.37108119321;4383;4479.7984090716;4495.056038231943;4563.703359281702;1375.40170026037;1410.9294535273195;1411.343725167802;1418.3759145975737;1430.7853821926794;973.352720440913;1009.2834087797746;1017.8880323164447;1019.3578045831078;1021.6628512590827;6165.660888651168;6302.338254483305;6340.429361357407;6558.344165583403;6651.66741662915;492.40419092898;493.2502596053978;493.91109600272296;497.0382310229645;507.50771751401084;4313.590208930209;4326.913461730811;4346.717463364871;4352.455943007124;4396.01089754809;12017.87982120185;12256.509739610316;12339.383030848421;12349.567765128177;12359.196652217757;11325;11818.054555635528;11844.407779610985;12168.14822962402;12231.633051008306;8816.823663526693;8915.73252802417;9002.954985225031;9010.053944335881;9012.0086790453;6158.969205153946;6493.870122597427;6546.279909209888;6571.277159512545;6626.436752875867;10986;11141.944290278821;11152.386618736047;11240.606578770032;11317.094632429551;10129.493525323851;10235.35143627036;11458.656240312805;11500.25248358854;11647.708807279885;7117.359437650636;8346.624401901807;8427.073021967457;8728.170823771732;8751.343649226461;9263.675771347971;9293.442393456144;9321.906780932104;9322.906770932475;9337.859932100888;8384.622691978753;8474.406791524534;8492.193241642035;8592.398532102692;8645.308375330083;8165.755027349189;8171.387145963958;8293.21214484623;8296.253337199681;8557.828843422973;7763.4953728007495;7790.844183116193;7829.099653539915;7837.7648670539975;7839.84320313602;5451.509364157245;5577.3028371453565;5666.886662266814;5670.121131224667;5807.76768929255;5434.293541839694;5616.5787565931905;5648.350439699487;5650.434956504317;5657.094864821609 22:06:43 INFO - PROCESS | 11662 | |4;prototype.html;337.39269315232593;343.8641736514077;352.054733041783;353.4821486522245;355.93237284915847;5285.418603953572;5376.166694162411;5442.809501667442;5495.670259784416;5524.143757717555;5297.8940421191555;5340;5391.73041347932;5414.512693857552;5438;4330.675199360061;4340.8697739067875;4341.500727416356;4349.67334962837;4358.084802191528;4201.844492764483;4314.331278651814;4322.286822674255;4332.696711230199;4335.6965012449145;4263.786810659471;4272.124214536028;4281.550437204095;4283.121959998209;4289.206496798087;4254.829921771507;4278.523909251303;4285.9785701071605;4286.957130428687;4308.676849236304;3749.4750734897134;3852.5954774748807;3858.5369755629504;3863.9227215455817;3878.5151856017997;5230.816921407771;5233.816816411408;5247.212918062283;5249.842504724865;5259.368875734898;4923.359963204796;4938.888750031214;4939.259111133323;4940.604751619898;4996.650234483587;5582.553395728372;5598.552115830724;5611.831645050655;5655.943440565583;5672.68800215988;4229.788510574459;4231.936520952188;4242.596953289432;4251.383549385322;4252.277112890816;5928.170056192136;5949.007082627325;5953.761849526014;5963.194968679216;5963.807238552306;4162.4796900387455;4344.695871288979;4347.934780978288;4356.847510337123;4380.386745855582;4400;4426.822927082945;4439.267520859086;4449.798554390297;4457.465104187453;4975.378077740283;4983.202687570044;4989.351384320088;4999.800007999639;5000.099962014411;5616.410276920946;5675.914861277084;5688.914666280009;5693.430656934273;5718.370979192286;1444.054144535331;1479.9482018129313;1495.2449013248458;1508.1780429665857;1516.7649014402784;1024.293237666008;1055.5883205549842;1065.8435597376933;1079.1420820447763;1080.9729756755987;5438.782448702087;5485.314335708036;5485.835424937259;5488.9725551371985;5507.366652834895;5707.600467967202;5708.886767080379;5738.913916291259;5745.563609097726;5749.3963133871175;5247.396549396868;5484.122540393518;5513.807016754395;5573.247611572386;5598.5926051798115;3089.6601373848857;3100.9921775422627;3109.673484284163;3110.346827166275;3114.3926934248057;5802.564807639444;6018.518518518596;6019.187409699722;6027.276726792813;6029.276486821609;5931.288245410578;5935.673537955454;5939.2575928009;5941.792037278675;5944.554158438134;6089.847753806191;6091.329953705089;6093.634381937098;6122.234720659918;6131.233595800525;5662.21640183341;5815.389384114607;5855.443732845373;5856.970715146397;5872.1778950947755;5783.479486846036;5804.593678442468;5812.709364531672;5825.155352473783;5836.912446313394;5848.327442344185;6006.309274433496;6044.909326360196;6045.939540604538;6107.389261073857;4200.642945349679;4232.195882782262;4239.664505680701;4265.253580623441;4274.401583778335;5647.6893770843;5710.3433105193435;5891.793787217427;5893.911591326219;5899.351071382059;5679.636887147138;5705.486506214461;5707.429257074259;5708.885822283448;5717.256756621613;5650.943490565206;5682;5683.460071293221;5689.317281926195;5694.601377903507;5504.53211477029;5585.1901474286815;5622;5629.746661400329;5642.71786410686;5693.715314234355;5701.885962280814;5721.799737009184;5729.541636668973;5733.283339582552;5416.835380393234;5578.581606379619;5585.525230355465;5605.355384130714;5619.578531610227 22:06:43 INFO - PROCESS | 11662 | |5;yui.html;814.4624547798454;828.6975254032272;829.954352510612;880.1242763450368;929.4934260827846;15638.218089095542;16444.67110657786;16590.917045414786;16699.83300166998;16756.748648770263;14342.42630294787;14361.640958976031;14382.640433989156;14403.063800852964;14404.351804168811;713.3009650542472;838.5388036579872;850.7532815483503;883.4566741453991;887.6981826179098;851.4040171879678;853.2789792625222;855.422589751915;855.7561095087901;863.365426411587;925.6343744221028;926.476540754475;927.4620719982428;927.9211267042309;929.3587424676986;762.1578156137472;898.6270697660495;914.3188324698109;924.2282693950559;926.0369216015335;681.8295426143465;681.9045333653293;682.0519477925687;682.5085938124543;682.5733916302311;15817.841821581753;15860.762088568681;15909;15910.840891591168;15930;15972.680546389123;16023.759643605355;16066.357345706158;16329.265183066791;17013.744793828104;13522.253042457014;13556.932215338958;13572.389242484112;13633.727325453436;14009.649758756012;4734.715917044989;4807.95192048082;4812.326274321597;4925.926111108336;5034.572061374787;69472.3052769471;70001.64999175022;70172.6491367545;82985;83708.58145709232;12210.450529726184;12241.877581224073;12414.689632759253;12491.375431228584;12551.937240313922;24089.277321680376;24200;24285.271441856774;24288.63567046496;24294.27117186487;9335.626574936927;9347.719568412958;9852.458114803754;9912.801743965081;9957.0540798624;4841.685290456109;5206.245094461357;5220.451852555427;5318.760655770499;5431.646942948696;3950.6444420001762;4110.260153172398;4140.047789008545;4140.171965606891;4145.79271036447;676.212416094053;688.1054628982403;688.6453476459627;689.2693744630708;717.114363760755;692.0000599134203;715.506300652548;787.7676085554775;805.9556724380216;814.9470284431493;10085.747856303651;10111.191104711605;10126.898731012743;10185.388876667424;10602.522886470128;775.217030798886;783.518136346143;794.4835856693196;812.975610731679;824.826786374856;4835.158310366499;5020.620047573177;5026.1204289249235;5039.622028347889;5041.446120043928;74479.62760186165;74767.25232747712;75662;79918;138427.30786346004;118393.40803295928;119341;120261;122001;122497;79874.6006269965;100396;102165.48917255514;102580;106115.46942265239;9164.541772911462;9285.4892980884;9500;9852.753681158028;9931.318543993226;53786.46213537815;55490.16764748613;56241.71879140578;56962.715186423804;56967.430325696216;80011.59994199991;80535.59732201301;81206;82266;83725.58137209275;11299;11515;11588.362640054878;11619.244749091282;12098.516059357527;12502.43739031764;13936.163830170222;14804.62988425298;14810.259487025822;14894.627634309229;13772;13941.651458713208;14537.92731036338;15676;15688.450904218755;14789.408423662933;15117.848821512083;15931.681366372375;16406.83593164053;16459.58851028734;14090.647733806736;14389.424423022961;15142.242887855784;15162.848371516144;15187.544373668808;11686;11885.76228475443;12340.259584424604;12499.812502812647;12885.677858053623;11717.414129293671;11963.94018029939;12125.939370303093;12205.389730513616;12357.505699771908 22:06:43 INFO - PROCESS | 11662 | __end_tp_report 22:06:43 INFO - PROCESS | 11662 | __start_cc_report 22:06:43 INFO - PROCESS | 11662 | _x_x_mozilla_cycle_collect,961 22:06:43 INFO - PROCESS | 11662 | __end_cc_report 22:06:43 INFO - PROCESS | 11662 | __startTimestamp1471669603342__endTimestamp 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | ------- Summary: start ------- 22:06:43 INFO - PROCESS | 11662 | Number of tests: 6 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#0] dojo.html Cycles:165 Average:5571.42 Median:5808.83 stddev:622.61 (10.7%) stddev-sans-first:596.91 22:06:43 INFO - PROCESS | 11662 | Values: 3234.2 3247.6 3261.6 3276.2 3301.0 5543.9 5638.5 5658.5 5661.7 5684.8 5592.5 5625.9 5632.2 5659.0 5685.7 5000.1 5111.6 5135.5 5153.6 5211.5 5014.0 5120.7 5163.7 5182.4 5182.9 5020.3 5065.0 5077.4 5083.0 5176.7 5180.1 5185.1 5187.7 5207.9 5227.0 5026.3 5038.5 5044.4 5076.1 5088.5 5519.3 5526.8 5552.6 5571.7 5597.6 6087.9 6097.1 6098.0 6103.1 6133.1 5859.7 5896.1 5924.3 5941.2 6095.0 5524.1 5543.6 5548.0 5549.3 5550.5 6058.3 6072.5 6074.9 6092.9 6103.0 5760.7 5808.8 5850.7 5857.6 5932.6 5941.5 5970.9 5977.3 5981.6 5981.7 5947.3 5954.4 5955.3 5956.4 5962.0 5719.3 5911.8 5948.6 5950.6 6033.5 4580.6 4609.2 4612.7 4626.3 4638.6 4116.1 4123.9 4133.4 4136.5 4148.7 5602.5 5723.6 5788.2 5793.2 5826.3 6085.2 6088.6 6091.4 6097.1 6132.1 5806.8 5809.7 5813.4 5819.4 5820.4 5060.9 5132.9 5152.5 5153.8 5234.0 6256.1 6258.1 6261.2 6261.6 6262.6 5978.5 6005.6 6032.3 6044.5 6047.7 6069.6 6073.0 6079.2 6141.8 6172.5 5793.3 5805.3 5840.9 5898.2 5927.1 5946.4 5964.6 5966.3 5974.9 6012.2 5765.3 5809.2 5837.6 5843.8 5858.2 5477.6 5683.8 5813.7 5820.1 5874.2 5868.5 5870.9 5879.6 5880.7 5909.1 5891.8 5949.0 5954.2 5992.9 5995.6 5613.6 5620.3 5634.6 5637.8 5784.5 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#1] ext.html Cycles:180 Average:52269.50 Median:3756.44 stddev:89939.00 (2394.3%) stddev-sans-first:90107.65 22:06:43 INFO - PROCESS | 11662 | Values: 605.0 617.5 621.9 634.4 646.4 34095.8 44902.0 45833.5 45906.8 45940.0 45551.3 45899.5 45939.1 46159.8 46196.5 8385.0 9199.6 9339.3 9339.8 10927.2 10714.4 11090.0 11094.9 11095.1 11098.7 11176.0 11216.8 11228.8 11232.8 11237.0 11069.5 11111.8 11117.3 11128.4 11145.2 10988.6 11009.3 11012.1 11040.9 11054.2 44291.8 44443.8 44484.3 44488.6 44654.3 3703.4 3740.1 3772.8 3795.3 3919.1 34003.5 34066.5 34303.0 34378.8 35760.5 4660.8 4702.6 4817.3 4848.9 4912.8 352992.2 356606.0 357238.0 357265.0 358624.0 61183.7 61403.0 61414.7 61615.0 61808.7 63117.1 63227.4 63309.0 63345.0 63476.0 18559.4 19388.3 19404.6 19966.3 19998.8 13634.3 13868.2 14698.2 14937.3 14983.8 20428.5 20506.2 20577.5 20676.2 20760.2 7418.6 7562.2 7726.8 7827.8 7864.4 1056.6 1129.2 1179.6 1190.1 1234.4 35147.5 35566.8 35638.0 35647.3 35929.6 1178.7 1191.9 1197.9 1219.5 1344.9 17543.2 18496.9 18572.3 18603.0 18780.9 162483.0 162867.0 163401.0 164425.0 165528.0 62420.7 62591.7 63144.0 63194.7 63343.0 1360.7 1444.7 1456.9 1499.4 1509.5 34018.5 36223.8 36456.3 36689.4 36930.3 52549.7 53155.0 53196.0 53248.2 53267.9 412640.0 416170.0 416210.0 417437.0 533073.0 45591.0 45805.8 46073.5 46152.5 46294.0 50018.7 50385.2 50732.7 50827.0 51031.5 42666.6 42676.4 42698.6 43144.8 44146.6 42772.8 43146.0 43663.1 43909.6 44282.3 42811.8 43424.7 43779.8 43834.0 43964.1 35099.4 37025.4 37786.0 38955.4 39151.4 29515.5 29599.6 29904.6 30014.4 30080.7 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#2] jquery.html Cycles:180 Average:30432.74 Median:2444.45 stddev:88823.41 (3633.7%) stddev-sans-first:89043.94 22:06:43 INFO - PROCESS | 11662 | Values: 392.5 403.6 421.5 424.7 430.8 15698.0 20137.6 20210.9 20265.4 20364.1 19826.7 20080.6 20092.3 20638.0 20819.5 5745.9 5841.2 5882.3 5890.2 5909.1 4340.3 5804.8 5835.4 6234.4 6468.7 6521.5 6538.4 6546.1 6546.6 6556.4 6457.9 6554.2 6590.6 6597.5 6597.8 5179.6 5429.4 5880.3 5892.7 5895.6 14224.9 14408.9 14563.9 14668.9 14680.0 2437.8 2451.1 2459.0 2525.4 2993.7 10535.7 10680.0 10693.7 10737.7 10822.2 1887.7 1937.5 1949.5 1971.1 2008.2 81096.6 81264.2 82562.6 83379.0 84722.2 10859.8 10904.4 10952.5 11173.7 11212.9 15526.6 15534.3 15600.4 15637.9 15655.5 8470.4 8549.4 8726.1 8895.0 8953.2 8859.0 8881.4 8882.0 8912.9 9058.4 4692.6 4710.5 4769.5 4800.7 4912.9 1976.6 1980.3 2021.5 2023.7 2032.6 1005.9 1060.8 1120.4 1129.4 1132.3 20276.4 20785.4 21115.6 21144.5 21322.1 1069.0 1071.3 1079.3 1081.0 1119.7 10770.6 11427.0 11669.5 11670.4 11727.5 466213.0 542405.0 548527.0 549183.0 583392.0 91415.5 92164.0 97527.5 97870.5 101526.0 476.7 499.2 501.2 501.2 503.0 11444.4 11839.9 11883.5 11954.1 12095.9 34480.1 38007.0 43620.3 44869.0 45704.3 55227.9 57658.4 61393.4 62929.0 62981.4 8208.6 8757.6 8777.9 8791.1 8827.3 9740.8 13501.6 13586.1 13620.1 14104.2 11306.2 12013.5 12139.0 12584.4 12704.9 12071.9 12428.5 12451.1 12507.7 12669.9 12126.6 12391.8 12501.2 12671.7 12685.6 8869.2 8870.8 8950.1 8990.4 9057.1 11501.4 11654.3 11794.4 12324.4 12368.9 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#3] mootools.html Cycles:180 Average:6287.63 Median:4709.27 stddev:3641.27 (77.3%) stddev-sans-first:3633.09 22:06:43 INFO - PROCESS | 11662 | Values: 1417.6 1548.8 1587.6 1738.5 1800.5 8431.4 9153.5 9264.5 9328.6 9729.0 9466.9 9554.9 9560.1 9572.4 9595.0 2523.6 2570.8 2575.3 2576.7 2584.5 2437.2 2493.3 2531.5 2536.8 2538.4 2461.4 2504.9 2504.9 2521.9 2523.4 2399.5 2405.4 2409.1 2455.8 2456.6 2345.3 2366.1 2372.1 2374.7 2423.2 9425.2 9476.7 9523.8 9526.2 9583.5 5649.4 5870.3 5879.2 5882.4 5882.5 3640.4 3698.1 3700.9 3756.6 3764.5 1216.8 1219.0 1235.2 1248.2 1254.8 13544.2 13717.4 13777.0 13786.0 13790.9 4756.8 4796.6 4872.1 4928.3 4989.6 10736.1 10736.9 10774.5 10775.8 10789.1 5668.1 5687.4 5688.1 5695.6 5697.1 4638.1 4661.7 5177.7 5243.3 5286.2 4337.4 4383.0 4479.8 4495.1 4563.7 1375.4 1410.9 1411.3 1418.4 1430.8 973.4 1009.3 1017.9 1019.4 1021.7 6165.7 6302.3 6340.4 6558.3 6651.7 492.4 493.3 493.9 497.0 507.5 4313.6 4326.9 4346.7 4352.5 4396.0 12017.9 12256.5 12339.4 12349.6 12359.2 11325.0 11818.1 11844.4 12168.1 12231.6 8816.8 8915.7 9003.0 9010.1 9012.0 6159.0 6493.9 6546.3 6571.3 6626.4 10986.0 11141.9 11152.4 11240.6 11317.1 10129.5 10235.4 11458.7 11500.3 11647.7 7117.4 8346.6 8427.1 8728.2 8751.3 9263.7 9293.4 9321.9 9322.9 9337.9 8384.6 8474.4 8492.2 8592.4 8645.3 8165.8 8171.4 8293.2 8296.3 8557.8 7763.5 7790.8 7829.1 7837.8 7839.8 5451.5 5577.3 5666.9 5670.1 5807.8 5434.3 5616.6 5648.4 5650.4 5657.1 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#4] prototype.html Cycles:180 Average:4831.82 Median:5463.47 stddev:1385.34 (25.4%) stddev-sans-first:1347.53 22:06:43 INFO - PROCESS | 11662 | Values: 337.4 343.9 352.1 353.5 355.9 5285.4 5376.2 5442.8 5495.7 5524.1 5297.9 5340.0 5391.7 5414.5 5438.0 4330.7 4340.9 4341.5 4349.7 4358.1 4201.8 4314.3 4322.3 4332.7 4335.7 4263.8 4272.1 4281.6 4283.1 4289.2 4254.8 4278.5 4286.0 4287.0 4308.7 3749.5 3852.6 3858.5 3863.9 3878.5 5230.8 5233.8 5247.2 5249.8 5259.4 4923.4 4938.9 4939.3 4940.6 4996.7 5582.6 5598.6 5611.8 5655.9 5672.7 4229.8 4231.9 4242.6 4251.4 4252.3 5928.2 5949.0 5953.8 5963.2 5963.8 4162.5 4344.7 4347.9 4356.8 4380.4 4400.0 4426.8 4439.3 4449.8 4457.5 4975.4 4983.2 4989.4 4999.8 5000.1 5616.4 5675.9 5688.9 5693.4 5718.4 1444.1 1479.9 1495.2 1508.2 1516.8 1024.3 1055.6 1065.8 1079.1 1081.0 5438.8 5485.3 5485.8 5489.0 5507.4 5707.6 5708.9 5738.9 5745.6 5749.4 5247.4 5484.1 5513.8 5573.2 5598.6 3089.7 3101.0 3109.7 3110.3 3114.4 5802.6 6018.5 6019.2 6027.3 6029.3 5931.3 5935.7 5939.3 5941.8 5944.6 6089.8 6091.3 6093.6 6122.2 6131.2 5662.2 5815.4 5855.4 5857.0 5872.2 5783.5 5804.6 5812.7 5825.2 5836.9 5848.3 6006.3 6044.9 6045.9 6107.4 4200.6 4232.2 4239.7 4265.3 4274.4 5647.7 5710.3 5891.8 5893.9 5899.4 5679.6 5705.5 5707.4 5708.9 5717.3 5650.9 5682.0 5683.5 5689.3 5694.6 5504.5 5585.2 5622.0 5629.7 5642.7 5693.7 5701.9 5721.8 5729.5 5733.3 5416.8 5578.6 5585.5 5605.4 5619.6 22:06:43 INFO - PROCESS | 11662 | 22:06:43 INFO - PROCESS | 11662 | [#5] yui.html Cycles:180 Average:21795.42 Median:4771.33 stddev:31156.95 (653.0%) stddev-sans-first:31204.52 22:06:43 INFO - PROCESS | 11662 | Values: 814.5 828.7 830.0 880.1 929.5 15638.2 16444.7 16590.9 16699.8 16756.7 14342.4 14361.6 14382.6 14403.1 14404.4 713.3 838.5 850.8 883.5 887.7 851.4 853.3 855.4 855.8 863.4 925.6 926.5 927.5 927.9 929.4 762.2 898.6 914.3 924.2 926.0 681.8 681.9 682.1 682.5 682.6 15817.8 15860.8 15909.0 15910.8 15930.0 15972.7 16023.8 16066.4 16329.3 17013.7 13522.3 13556.9 13572.4 13633.7 14009.6 4734.7 4808.0 4812.3 4925.9 5034.6 69472.3 70001.6 70172.6 82985.0 83708.6 12210.5 12241.9 12414.7 12491.4 12551.9 24089.3 24200.0 24285.3 24288.6 24294.3 9335.6 9347.7 9852.5 9912.8 9957.1 4841.7 5206.2 5220.5 5318.8 5431.6 3950.6 4110.3 4140.0 4140.2 4145.8 676.2 688.1 688.6 689.3 717.1 692.0 715.5 787.8 806.0 814.9 10085.7 10111.2 10126.9 10185.4 10602.5 775.2 783.5 794.5 813.0 824.8 4835.2 5020.6 5026.1 5039.6 5041.4 74479.6 74767.3 75662.0 79918.0 138427.3 118393.4 119341.0 120261.0 122001.0 122497.0 79874.6 100396.0 102165.5 102580.0 106115.5 9164.5 9285.5 9500.0 9852.8 9931.3 53786.5 55490.2 56241.7 56962.7 56967.4 80011.6 80535.6 81206.0 82266.0 83725.6 11299.0 11515.0 11588.4 11619.2 12098.5 12502.4 13936.2 14804.6 14810.3 14894.6 13772.0 13941.7 14537.9 15676.0 15688.5 14789.4 15117.8 15931.7 16406.8 16459.6 14090.6 14389.4 15142.2 15162.8 15187.5 11686.0 11885.8 12340.3 12499.8 12885.7 11717.4 11963.9 12125.9 12205.4 12357.5 22:06:43 INFO - PROCESS | 11662 | -------- Summary: end -------- 22:06:43 INFO - PROCESS | 11662 | 22:06:44 INFO - TEST-INFO | 11662: exit 0 22:06:44 INFO - TEST-OK | dromaeo_css | took 1113392ms 22:06:44 INFO - TEST-START | kraken 22:06:44 INFO - operating with platform_type : linux_ 22:06:44 INFO - Initialising browser for kraken test... 22:06:44 INFO - TEST-INFO | started process 11869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCV4ulg/profile http://localhost:43268/getInfo.html) 22:06:47 INFO - PROCESS | 11869 | __metrics Screen width/height:1600/1200 22:06:47 INFO - PROCESS | 11869 | colorDepth:24 22:06:47 INFO - PROCESS | 11869 | Browser inner width/height: 1024/697 22:06:47 INFO - PROCESS | 11869 | __metrics 22:06:48 INFO - PROCESS | 11869 | 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" 22:06:48 INFO - PROCESS | 11869 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:06:49 INFO - TEST-INFO | 11869: exit 0 22:06:49 INFO - Browser initialized. 22:06:49 INFO - Running cycle 1/1 for kraken test... 22:06:49 INFO - TEST-INFO | started process 11958 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCV4ulg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:01 INFO - PROCESS | 11958 | RSS: Main: 145137664 22:07:01 INFO - PROCESS | 11958 | 22:07:03 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43268/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:04 INFO - PROCESS | 11958 | RSS: Main: 191729664 22:07:04 INFO - PROCESS | 11958 | 22:07:07 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43268/tests/kraken/driver.html?testName=audio-dft) 22:07:07 INFO - PROCESS | 11958 | RSS: Main: 181907456 22:07:07 INFO - PROCESS | 11958 | 22:07:10 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43268/tests/kraken/driver.html?testName=audio-fft) 22:07:11 INFO - PROCESS | 11958 | RSS: Main: 269963264 22:07:11 INFO - PROCESS | 11958 | 22:07:13 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43268/tests/kraken/driver.html?testName=audio-oscillator) 22:07:13 INFO - PROCESS | 11958 | RSS: Main: 175517696 22:07:13 INFO - PROCESS | 11958 | 22:07:16 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:16 INFO - PROCESS | 11958 | RSS: Main: 177041408 22:07:16 INFO - PROCESS | 11958 | 22:07:19 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43268/tests/kraken/driver.html?testName=imaging-darkroom) 22:07:20 INFO - PROCESS | 11958 | RSS: Main: 212361216 22:07:20 INFO - PROCESS | 11958 | 22:07:23 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43268/tests/kraken/driver.html?testName=imaging-desaturate) 22:07:24 INFO - PROCESS | 11958 | RSS: Main: 211345408 22:07:24 INFO - PROCESS | 11958 | 22:07:26 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43268/tests/kraken/driver.html?testName=json-parse-financial) 22:07:27 INFO - PROCESS | 11958 | RSS: Main: 211779584 22:07:27 INFO - PROCESS | 11958 | 22:07:29 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:07:29 INFO - PROCESS | 11958 | RSS: Main: 176173056 22:07:29 INFO - PROCESS | 11958 | 22:07:31 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:07:31 INFO - PROCESS | 11958 | RSS: Main: 182693888 22:07:31 INFO - PROCESS | 11958 | 22:07:33 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:07:34 INFO - PROCESS | 11958 | RSS: Main: 186200064 22:07:34 INFO - PROCESS | 11958 | 22:07:37 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:07:37 INFO - PROCESS | 11958 | RSS: Main: 181178368 22:07:37 INFO - PROCESS | 11958 | 22:07:40 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:07:40 INFO - PROCESS | 11958 | RSS: Main: 184258560 22:07:40 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | Cycle 1(1): loaded http://localhost:43268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43268/tests/kraken/driver.html?testName=ai-astar) 22:07:42 INFO - PROCESS | 11958 | RSS: Main: 236105728 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | __start_tp_report 22:07:42 INFO - PROCESS | 11958 | _x_x_mozilla_page_load 22:07:42 INFO - PROCESS | 11958 | _x_x_mozilla_page_load_details 22:07:42 INFO - PROCESS | 11958 | |i|pagename|runs| 22:07:42 INFO - PROCESS | 11958 | |0;ai-astar;100;110;108;111;108;110;108;111;109;109 22:07:42 INFO - PROCESS | 11958 | |1;audio-beat-detection;147;115;115;179;115;117;137;117;154;116 22:07:42 INFO - PROCESS | 11958 | |2;audio-dft;161;159;159;160;161;162;162;161;160;161 22:07:42 INFO - PROCESS | 11958 | |3;audio-fft;74;76;74;112;76;75;74;75;99;75 22:07:42 INFO - PROCESS | 11958 | |4;audio-oscillator;82;82;118;82;82;84;114;84;85;125 22:07:42 INFO - PROCESS | 11958 | |5;imaging-gaussian-blur;116;116;117;117;117;117;116;117;117;116 22:07:42 INFO - PROCESS | 11958 | |6;imaging-darkroom;145;146;146;146;147;146;146;145;145;146 22:07:42 INFO - PROCESS | 11958 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;96 22:07:42 INFO - PROCESS | 11958 | |8;json-parse-financial;53;50;50;51;50;51;54;51;52;48 22:07:42 INFO - PROCESS | 11958 | |9;json-stringify-tinderbox;58;59;59;58;59;58;60;59;59;59 22:07:42 INFO - PROCESS | 11958 | |10;stanford-crypto-aes;72;72;86;73;75;72;71;73;71;75 22:07:42 INFO - PROCESS | 11958 | |11;stanford-crypto-ccm;120;125;124;126;121;123;118;117;152;120 22:07:42 INFO - PROCESS | 11958 | |12;stanford-crypto-pbkdf2;152;155;153;155;151;155;150;151;152;151 22:07:42 INFO - PROCESS | 11958 | |13;stanford-crypto-sha256-iterative;63;61;61;60;65;60;60;61;60;59 22:07:42 INFO - PROCESS | 11958 | __end_tp_report 22:07:42 INFO - PROCESS | 11958 | __start_cc_report 22:07:42 INFO - PROCESS | 11958 | _x_x_mozilla_cycle_collect,1635 22:07:42 INFO - PROCESS | 11958 | __end_cc_report 22:07:42 INFO - PROCESS | 11958 | __startTimestamp1471669662750__endTimestamp 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | ------- Summary: start ------- 22:07:42 INFO - PROCESS | 11958 | Number of tests: 14 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.50 stddev:3.17 (2.9%) stddev-sans-first:1.22 22:07:42 INFO - PROCESS | 11958 | Values: 100.0 110.0 108.0 111.0 108.0 110.0 108.0 111.0 109.0 109.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:127.00 stddev:22.38 (17.6%) stddev-sans-first:23.00 22:07:42 INFO - PROCESS | 11958 | Values: 147.0 115.0 115.0 179.0 115.0 117.0 137.0 117.0 154.0 116.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 22:07:42 INFO - PROCESS | 11958 | Values: 161.0 159.0 159.0 160.0 161.0 162.0 162.0 161.0 160.0 161.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:13.29 (17.7%) stddev-sans-first:13.85 22:07:42 INFO - PROCESS | 11958 | Values: 74.0 76.0 74.0 112.0 76.0 75.0 74.0 75.0 99.0 75.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:82.00 stddev:17.62 (21.5%) stddev-sans-first:18.16 22:07:42 INFO - PROCESS | 11958 | Values: 82.0 82.0 118.0 82.0 82.0 84.0 114.0 84.0 85.0 125.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:07:42 INFO - PROCESS | 11958 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 22:07:42 INFO - PROCESS | 11958 | Values: 145.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 145.0 146.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 22:07:42 INFO - PROCESS | 11958 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.70 (3.3%) stddev-sans-first:1.64 22:07:42 INFO - PROCESS | 11958 | Values: 53.0 50.0 50.0 51.0 50.0 51.0 54.0 51.0 52.0 48.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 22:07:42 INFO - PROCESS | 11958 | Values: 58.0 59.0 59.0 58.0 59.0 58.0 60.0 59.0 59.0 59.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.00 stddev:4.45 (6.1%) stddev-sans-first:4.66 22:07:42 INFO - PROCESS | 11958 | Values: 72.0 72.0 86.0 73.0 75.0 72.0 71.0 73.0 71.0 75.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:123.50 stddev:10.07 (8.2%) stddev-sans-first:10.54 22:07:42 INFO - PROCESS | 11958 | Values: 120.0 125.0 124.0 126.0 121.0 123.0 118.0 117.0 152.0 120.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:152.50 stddev:1.90 (1.2%) stddev-sans-first:2.01 22:07:42 INFO - PROCESS | 11958 | Values: 152.0 155.0 153.0 155.0 151.0 155.0 150.0 151.0 152.0 151.0 22:07:42 INFO - PROCESS | 11958 | 22:07:42 INFO - PROCESS | 11958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:1.76 (2.9%) stddev-sans-first:1.72 22:07:42 INFO - PROCESS | 11958 | Values: 63.0 61.0 61.0 60.0 65.0 60.0 60.0 61.0 60.0 59.0 22:07:42 INFO - PROCESS | 11958 | -------- Summary: end -------- 22:07:42 INFO - PROCESS | 11958 | 22:07:43 INFO - TEST-INFO | 11958: exit 0 22:07:43 INFO - TEST-OK | kraken | took 59855ms 22:07:43 INFO - SUITE-END | took 1173s 22:07:44 INFO - Completed test suite (00:19:33) 22:07:44 INFO - javascript benchmark 22:07:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3234.2399536109015, 3247.6265229498586, 3261.592300962379, 3276.2464633134364, 3301.0096970908735, 5543.889122217553, 5638.520725738312, 5658.519025882794, 5661.688607126616, 5684.801031963882, 5592.5246354059955, 5625.943740562593, 5632.155176723493, 5659.0, 5685.744141513632, 5000.07498612756, 5111.591072714184, 5135.486451354854, 5153.613478989091, 5211.478852114796, 5014.022265658198, 5120.692758434488, 5163.664361816489, 5182.352205974254, 5182.870428239286, 5020.272060551236, 5064.961682855024, 5077.41609714882, 5083.0, 5176.66351687141, 5180.093483640367, 5185.092608793484, 5187.688738675692, 5207.921881171786, 5227.0, 5026.321446604698, 5038.546530812245, 5044.445111037783, 5076.111680455905, 5088.465711100355, 5519.254900588409, 5526.806561770359, 5552.639078459887, 5571.693556854371, 5597.552195824324, 6087.8819223424425, 6097.146399504073, 6097.969510152465, 6103.138856883458, 6133.080037994292, 5859.707014649251, 5896.056630939029, 5924.259467566554, 5941.2276404067625, 6095.0006233636705, 5524.06090964537, 5543.6396634218645, 5548.029094908375, 5549.250851135126, 5550.528205102531, 6058.256287901417, 6072.535555179074, 6074.89002182083, 6092.939070609237, 6103.0, 5760.683162426107, 5808.82573522795, 5850.736716847752, 5857.619254748428, 5932.58471906971, 5941.465268125803, 5970.910436343458, 5977.342492325927, 5981.641101533922, 5981.730822113015, 5947.286325640865, 5954.434328738763, 5955.315138759098, 5956.434138756811, 5961.970190149022, 5719.3136823581435, 5911.7635294587735, 5948.613340132878, 5950.642961422329, 6033.5474839387225, 4580.587747102777, 4609.216433206363, 4612.654050946193, 4626.282926146453, 4638.5593368629925, 4116.094459218967, 4123.876283711494, 4133.379993001074, 4136.482939632546, 4148.730332528376, 5602.4957753802355, 5723.6279641823985, 5788.21859049023, 5793.2179155814265, 5826.271716035496, 6085.1785009023215, 6088.573799833969, 6091.4363154433695, 6097.085437184458, 6132.110843927606, 5806.796762113306, 5809.651420914759, 5813.389594092644, 5819.359870414251, 5820.447057529528, 5060.873478163076, 5132.948670513321, 5152.458991805881, 5153.819616313411, 5233.973830130825, 6256.092866534418, 6258.123862659231, 6261.186045814015, 6261.5616906816085, 6262.624242545462, 5978.521718262616, 6005.579609427298, 6032.33644299118, 6044.486218671462, 6047.667378294236, 6069.605475644069, 6073.0, 6079.209702738615, 6141.792871882774, 6172.475339596005, 5793.275840519935, 5805.274340707411, 5840.853978650568, 5898.174255604305, 5927.081302398135, 5946.43508866657, 5964.612300200473, 5966.254218222723, 5974.850628734142, 6012.1582978382185, 5765.279340082489, 5809.186713859978, 5837.591368604157, 5843.7954671587995, 5858.179854820414, 5477.589180811535, 5683.77264909399, 5813.680247586423, 5820.097884827858, 5874.236349274567, 5868.471837534642, 5870.911936321044, 5879.617824841372, 5880.735366908414, 5909.054551271776, 5891.764329426775, 5949.0481522956115, 5954.166416701579, 5992.850178745566, 5995.5803093783015, 5613.550915926798, 5620.297462817148, 5634.577406694432, 5637.830865074055, 5784.479396854304], "value": 5530.0655608039215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.9516038716903, 617.4689766800551, 621.9004959206525, 634.3909846547316, 646.4311405962752, 34095.7837740547, 44902.0, 45833.54166458335, 45906.770466147704, 45940.0, 45551.31673024899, 45899.54100459003, 45939.08121837561, 46159.76920115395, 46196.53803461965, 8385.0, 9199.586018629177, 9339.299552533586, 9339.813203735921, 10927.235093543455, 10714.357138571711, 11090.0, 11094.889051109467, 11095.11239100874, 11098.667039988813, 11176.0, 11216.831747523753, 11228.831567526493, 11232.775344493106, 11237.0, 11069.50187241572, 11111.777764444665, 11117.277376970553, 11128.443577821117, 11145.1641126915, 10988.560457581687, 11009.339439633648, 11012.119030477543, 11040.944795276053, 11054.170937179744, 44291.77854110741, 44443.77778111121, 44484.332735009004, 44488.55511444876, 44654.33018504725, 3703.370427027412, 3740.121071548211, 3772.7547709399078, 3795.2978698940783, 3919.1377896862646, 34003.48994765081, 34066.48900266498, 34303.0, 34378.76538025046, 35760.46359304612, 4660.813567457298, 4702.6473014524045, 4817.349657796188, 4848.878778030577, 4912.754362281871, 352992.2350388257, 356606.0, 357238.0, 357265.0, 358624.0, 61183.694081529306, 61403.0, 61414.692926535085, 61615.0, 61808.690956545826, 63117.05324420137, 63227.36772632215, 63309.0, 63345.0, 63476.0, 18559.35042273541, 19388.321408750628, 19404.611907761766, 19966.30117945865, 19998.800011999985, 13634.318284085954, 13868.167909925436, 14698.191599462132, 14937.25313734309, 14983.775243371358, 20428.489287767923, 20506.179752810018, 20577.53069631624, 20676.17295308201, 20760.169593216404, 7418.629068546551, 7562.2437756223935, 7726.806829829186, 7827.804304892423, 7864.449488535747, 1056.6090546497844, 1129.1813435259535, 1179.5694571481347, 1190.119311709329, 1234.4259919137464, 35147.4727879082, 35566.822165889, 35638.0, 35647.28705425929, 35929.64070359263, 1178.7406770510645, 1191.9046476281876, 1197.9401029948467, 1219.5243854896598, 1344.9398499225845, 17543.210555524776, 18496.907515462604, 18572.25710971573, 18603.0, 18780.906095469436, 162483.0, 162867.0, 163401.0, 164425.0, 165528.0, 62420.68789656023, 62591.68704156541, 63144.0, 63194.684026579576, 63343.0, 1360.7210521843197, 1444.6821699226152, 1456.8563677513102, 1499.3627708223744, 1509.5094094419358, 34018.489722653685, 36223.75316610554, 36456.27087458289, 36689.44965825461, 36930.26139477142, 52549.7372513135, 53155.0, 53196.0, 53248.2012769801, 53267.93464130773, 412640.0, 416170.0, 416210.0, 417437.0, 533073.0, 45591.0, 45805.77097114493, 46073.53926460692, 46152.53847461483, 46294.0, 50018.74990625024, 50385.244221337445, 50732.74633626808, 50827.0, 51031.48968510267, 42666.5733342675, 42676.359854601586, 42698.5730142707, 43144.78427607842, 44146.558534414246, 42772.786136069124, 43146.0, 43663.05452922284, 43909.56090439182, 44282.3357649642, 42811.78594107134, 43424.657534246326, 43779.78110109429, 43834.0, 43964.120717586105, 35099.44660566569, 37025.44461833128, 37786.0, 38955.415668765556, 39151.41272880852, 29515.543538340007, 29599.556006660347, 29904.551431728105, 30014.3997120052, 30080.699193008666], "value": 21384.16400580729, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4936831487381, 403.64680904208797, 421.50262690025767, 424.71331851000576, 430.82120919818277, 15698.0, 20137.59724805503, 20210.89894550525, 20265.39203823884, 20364.08361623727, 19826.702599460983, 20080.59838803223, 20092.296769613065, 20638.03938625832, 20819.479513012182, 5745.942540574603, 5841.182234487175, 5882.323532793719, 5890.175375447441, 5909.054551271776, 4340.305551111807, 5804.82585522435, 5835.44563266489, 6234.438900498954, 6468.74125034998, 6521.47828173745, 6538.444232240266, 6546.149000629912, 6546.607203567801, 6556.377144171296, 6457.8708425831455, 6554.180727409074, 6590.604563726191, 6597.538172327939, 6597.835054123638, 5179.559737422323, 5429.4299098594865, 5880.294364676223, 5892.734826932799, 5895.557833162487, 14224.85775142256, 14408.855911440856, 14563.927180364137, 14668.853311466855, 14680.04579702316, 2437.8094583420407, 2451.0930955546382, 2459.0163934426196, 2525.4317778499876, 2993.7006299370105, 10535.683929482127, 10680.0, 10693.732656683567, 10737.731556711145, 10822.18833587484, 1887.707405352159, 1937.4865660599953, 1949.541857663448, 1971.142552989454, 2008.1766475744873, 81096.59451702704, 81264.18735812625, 82562.58718706368, 83379.0, 84722.15277847266, 10859.83710244347, 10904.400257985886, 10952.4523773811, 11173.664790056311, 11212.887871121347, 15526.611834704223, 15534.300956456986, 15600.375984960474, 15637.921810390875, 15655.452059177875, 8470.449420787629, 8549.401541892008, 8726.08376120511, 8894.955525222462, 8953.238974687094, 8859.0, 8881.42270752412, 8882.0, 8912.8663070054, 9058.411203271766, 4692.648051396159, 4710.458297295786, 4769.523047695203, 4800.687955282965, 4912.901741965212, 1976.5651556657513, 1980.3464856597288, 2021.5047313408309, 2023.6559784836613, 2032.6239645665598, 1005.8742657167854, 1060.7931453366518, 1120.3557954176217, 1129.4465711801322, 1132.2923173016864, 20276.391708248775, 20785.376438706862, 21115.577688446454, 21144.471388215417, 21322.14711411557, 1068.9518971646294, 1071.3090056913404, 1079.2876701377054, 1080.9837852432063, 1119.7368618374676, 10770.623028193975, 11427.0, 11669.533218671158, 11670.416479176178, 11727.530898764124, 466213.0, 542405.0, 548527.0, 549183.0, 583392.0, 91415.54292228496, 92164.0, 97527.51236243916, 97870.51064744631, 101526.0, 476.65918868008845, 499.22121490474973, 501.17806796853915, 501.19057721779063, 503.0115822408984, 11444.42777861087, 11839.881601184223, 11883.465244064211, 11954.103442241692, 12095.939520302341, 34480.137996550286, 38007.0, 43620.34569481524, 44869.0, 45704.31443528416, 55227.895442091736, 57658.423415765305, 61393.386066138766, 62929.0, 62981.370186299384, 8208.630611622611, 8757.605907734294, 8777.91222087771, 8791.120887911413, 8827.293816494794, 9740.756481087747, 13501.59495215145, 13586.116902401312, 13620.114692544952, 14104.153750774987, 11306.152038596974, 12013.519459221534, 12139.0, 12584.370781460708, 12704.936475317565, 12071.939640301742, 12428.502859885502, 12451.128421010442, 12507.687307817014, 12669.936650317059, 12126.636200913634, 12391.75216495647, 12501.24992500453, 12671.746565068832, 12685.619431417072, 8869.157430044135, 8870.822583548423, 8950.060243674321, 8990.370674052754, 9057.139571740674, 11501.424928753697, 11654.30074195551, 11794.410279486163, 12324.383780811102, 12368.876311237133], "value": 9215.730258638423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.5534706567437, 1548.8141423029235, 1587.6189714468528, 1738.4784564630609, 1800.4688616858025, 8431.367647426445, 9153.450792952419, 9264.536773161348, 9328.626854925795, 9728.951355243214, 9466.905330946689, 9554.856677149848, 9560.139587437146, 9572.377795443292, 9594.952025239882, 2523.5836087045664, 2570.755778201077, 2575.33041409234, 2576.6650335456366, 2584.5347837389263, 2437.171361737009, 2493.339265094751, 2531.531666641677, 2536.797056235506, 2538.4034751833306, 2461.433870209853, 2504.887280072392, 2504.887280072401, 2521.936951576207, 2523.4196134888984, 2399.520095980802, 2405.374602603318, 2409.144753612469, 2455.7789798918184, 2456.6192240202795, 2345.2612427085414, 2366.1363602285214, 2372.146027430124, 2374.655674927126, 2423.1761201191503, 9425.151736343694, 9476.715698528986, 9523.809523809554, 9526.237900967975, 9583.520823958845, 5649.406812284692, 5870.295564532241, 5879.206307148523, 5882.35294117651, 5882.529397648221, 3640.3629364861044, 3698.0754811297174, 3700.85196592136, 3756.567994680592, 3764.5106136202116, 1216.7688139253514, 1219.0491416694992, 1235.1971218707822, 1248.1949142802841, 1254.7866862633375, 13544.187348759007, 13717.382717777724, 13777.0, 13786.0, 13790.931045344809, 4756.8107973006745, 4796.592289655348, 4872.098661747588, 4928.260760885896, 4989.625778066659, 10736.08743256832, 10736.892631073746, 10774.515146818412, 10775.78448431027, 10789.082927951054, 5668.121441176624, 5687.40282270364, 5688.06146985751, 5695.629784064023, 5697.088465845445, 4638.072385522842, 4661.720296782204, 5177.663451875616, 5243.318368612056, 5286.15421532553, 4337.37108119321, 4383.0, 4479.7984090716, 4495.056038231943, 4563.703359281702, 1375.40170026037, 1410.9294535273195, 1411.343725167802, 1418.3759145975737, 1430.7853821926794, 973.352720440913, 1009.2834087797746, 1017.8880323164447, 1019.3578045831078, 1021.6628512590827, 6165.660888651168, 6302.338254483305, 6340.429361357407, 6558.344165583403, 6651.66741662915, 492.40419092898, 493.2502596053978, 493.91109600272296, 497.0382310229645, 507.50771751401084, 4313.590208930209, 4326.913461730811, 4346.717463364871, 4352.455943007124, 4396.01089754809, 12017.87982120185, 12256.509739610316, 12339.383030848421, 12349.567765128177, 12359.196652217757, 11325.0, 11818.054555635528, 11844.407779610985, 12168.14822962402, 12231.633051008306, 8816.823663526693, 8915.73252802417, 9002.954985225031, 9010.053944335881, 9012.0086790453, 6158.969205153946, 6493.870122597427, 6546.279909209888, 6571.277159512545, 6626.436752875867, 10986.0, 11141.944290278821, 11152.386618736047, 11240.606578770032, 11317.094632429551, 10129.493525323851, 10235.35143627036, 11458.656240312805, 11500.25248358854, 11647.708807279885, 7117.359437650636, 8346.624401901807, 8427.073021967457, 8728.170823771732, 8751.343649226461, 9263.675771347971, 9293.442393456144, 9321.906780932104, 9322.906770932475, 9337.859932100888, 8384.622691978753, 8474.406791524534, 8492.193241642035, 8592.398532102692, 8645.308375330083, 8165.755027349189, 8171.387145963958, 8293.21214484623, 8296.253337199681, 8557.828843422973, 7763.4953728007495, 7790.844183116193, 7829.099653539915, 7837.7648670539975, 7839.84320313602, 5451.509364157245, 5577.3028371453565, 5666.886662266814, 5670.121131224667, 5807.76768929255, 5434.293541839694, 5616.5787565931905, 5648.350439699487, 5650.434956504317, 5657.094864821609], "value": 4923.808787882735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.39269315232593, 343.8641736514077, 352.054733041783, 353.4821486522245, 355.93237284915847, 5285.418603953572, 5376.166694162411, 5442.809501667442, 5495.670259784416, 5524.143757717555, 5297.8940421191555, 5340.0, 5391.73041347932, 5414.512693857552, 5438.0, 4330.675199360061, 4340.8697739067875, 4341.500727416356, 4349.67334962837, 4358.084802191528, 4201.844492764483, 4314.331278651814, 4322.286822674255, 4332.696711230199, 4335.6965012449145, 4263.786810659471, 4272.124214536028, 4281.550437204095, 4283.121959998209, 4289.206496798087, 4254.829921771507, 4278.523909251303, 4285.9785701071605, 4286.957130428687, 4308.676849236304, 3749.4750734897134, 3852.5954774748807, 3858.5369755629504, 3863.9227215455817, 3878.5151856017997, 5230.816921407771, 5233.816816411408, 5247.212918062283, 5249.842504724865, 5259.368875734898, 4923.359963204796, 4938.888750031214, 4939.259111133323, 4940.604751619898, 4996.650234483587, 5582.553395728372, 5598.552115830724, 5611.831645050655, 5655.943440565583, 5672.68800215988, 4229.788510574459, 4231.936520952188, 4242.596953289432, 4251.383549385322, 4252.277112890816, 5928.170056192136, 5949.007082627325, 5953.761849526014, 5963.194968679216, 5963.807238552306, 4162.4796900387455, 4344.695871288979, 4347.934780978288, 4356.847510337123, 4380.386745855582, 4400.0, 4426.822927082945, 4439.267520859086, 4449.798554390297, 4457.465104187453, 4975.378077740283, 4983.202687570044, 4989.351384320088, 4999.800007999639, 5000.099962014411, 5616.410276920946, 5675.914861277084, 5688.914666280009, 5693.430656934273, 5718.370979192286, 1444.054144535331, 1479.9482018129313, 1495.2449013248458, 1508.1780429665857, 1516.7649014402784, 1024.293237666008, 1055.5883205549842, 1065.8435597376933, 1079.1420820447763, 1080.9729756755987, 5438.782448702087, 5485.314335708036, 5485.835424937259, 5488.9725551371985, 5507.366652834895, 5707.600467967202, 5708.886767080379, 5738.913916291259, 5745.563609097726, 5749.3963133871175, 5247.396549396868, 5484.122540393518, 5513.807016754395, 5573.247611572386, 5598.5926051798115, 3089.6601373848857, 3100.9921775422627, 3109.673484284163, 3110.346827166275, 3114.3926934248057, 5802.564807639444, 6018.518518518596, 6019.187409699722, 6027.276726792813, 6029.276486821609, 5931.288245410578, 5935.673537955454, 5939.2575928009, 5941.792037278675, 5944.554158438134, 6089.847753806191, 6091.329953705089, 6093.634381937098, 6122.234720659918, 6131.233595800525, 5662.21640183341, 5815.389384114607, 5855.443732845373, 5856.970715146397, 5872.1778950947755, 5783.479486846036, 5804.593678442468, 5812.709364531672, 5825.155352473783, 5836.912446313394, 5848.327442344185, 6006.309274433496, 6044.909326360196, 6045.939540604538, 6107.389261073857, 4200.642945349679, 4232.195882782262, 4239.664505680701, 4265.253580623441, 4274.401583778335, 5647.6893770843, 5710.3433105193435, 5891.793787217427, 5893.911591326219, 5899.351071382059, 5679.636887147138, 5705.486506214461, 5707.429257074259, 5708.885822283448, 5717.256756621613, 5650.943490565206, 5682.0, 5683.460071293221, 5689.317281926195, 5694.601377903507, 5504.53211477029, 5585.1901474286815, 5622.0, 5629.746661400329, 5642.71786410686, 5693.715314234355, 5701.885962280814, 5721.799737009184, 5729.541636668973, 5733.283339582552, 5416.835380393234, 5578.581606379619, 5585.525230355465, 5605.355384130714, 5619.578531610227], "value": 4396.2538232682855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.4624547798454, 828.6975254032272, 829.954352510612, 880.1242763450368, 929.4934260827846, 15638.218089095542, 16444.67110657786, 16590.917045414786, 16699.83300166998, 16756.748648770263, 14342.42630294787, 14361.640958976031, 14382.640433989156, 14403.063800852964, 14404.351804168811, 713.3009650542472, 838.5388036579872, 850.7532815483503, 883.4566741453991, 887.6981826179098, 851.4040171879678, 853.2789792625222, 855.422589751915, 855.7561095087901, 863.365426411587, 925.6343744221028, 926.476540754475, 927.4620719982428, 927.9211267042309, 929.3587424676986, 762.1578156137472, 898.6270697660495, 914.3188324698109, 924.2282693950559, 926.0369216015335, 681.8295426143465, 681.9045333653293, 682.0519477925687, 682.5085938124543, 682.5733916302311, 15817.841821581753, 15860.762088568681, 15909.0, 15910.840891591168, 15930.0, 15972.680546389123, 16023.759643605355, 16066.357345706158, 16329.265183066791, 17013.744793828104, 13522.253042457014, 13556.932215338958, 13572.389242484112, 13633.727325453436, 14009.649758756012, 4734.715917044989, 4807.95192048082, 4812.326274321597, 4925.926111108336, 5034.572061374787, 69472.3052769471, 70001.64999175022, 70172.6491367545, 82985.0, 83708.58145709232, 12210.450529726184, 12241.877581224073, 12414.689632759253, 12491.375431228584, 12551.937240313922, 24089.277321680376, 24200.0, 24285.271441856774, 24288.63567046496, 24294.27117186487, 9335.626574936927, 9347.719568412958, 9852.458114803754, 9912.801743965081, 9957.0540798624, 4841.685290456109, 5206.245094461357, 5220.451852555427, 5318.760655770499, 5431.646942948696, 3950.6444420001762, 4110.260153172398, 4140.047789008545, 4140.171965606891, 4145.79271036447, 676.212416094053, 688.1054628982403, 688.6453476459627, 689.2693744630708, 717.114363760755, 692.0000599134203, 715.506300652548, 787.7676085554775, 805.9556724380216, 814.9470284431493, 10085.747856303651, 10111.191104711605, 10126.898731012743, 10185.388876667424, 10602.522886470128, 775.217030798886, 783.518136346143, 794.4835856693196, 812.975610731679, 824.826786374856, 4835.158310366499, 5020.620047573177, 5026.1204289249235, 5039.622028347889, 5041.446120043928, 74479.62760186165, 74767.25232747712, 75662.0, 79918.0, 138427.30786346004, 118393.40803295928, 119341.0, 120261.0, 122001.0, 122497.0, 79874.6006269965, 100396.0, 102165.48917255514, 102580.0, 106115.46942265239, 9164.541772911462, 9285.4892980884, 9500.0, 9852.753681158028, 9931.318543993226, 53786.46213537815, 55490.16764748613, 56241.71879140578, 56962.715186423804, 56967.430325696216, 80011.59994199991, 80535.59732201301, 81206.0, 82266.0, 83725.58137209275, 11299.0, 11515.0, 11588.362640054878, 11619.244749091282, 12098.516059357527, 12502.43739031764, 13936.163830170222, 14804.62988425298, 14810.259487025822, 14894.627634309229, 13772.0, 13941.651458713208, 14537.92731036338, 15676.0, 15688.450904218755, 14789.408423662933, 15117.848821512083, 15931.681366372375, 16406.83593164053, 16459.58851028734, 14090.647733806736, 14389.424423022961, 15142.242887855784, 15162.848371516144, 15187.544373668808, 11686.0, 11885.76228475443, 12340.259584424604, 12499.812502812647, 12885.677858053623, 11717.414129293671, 11963.94018029939, 12125.939370303093, 12205.389730513616, 12357.505699771908], "value": 8112.984537452333, "unit": "score"}], "name": "dromaeo_css", "value": 7591.48411858244, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 108.0, 111.0, 108.0, 110.0, 108.0, 111.0, 109.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 115.0, 179.0, 115.0, 117.0, 137.0, 117.0, 154.0, 116.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 159.0, 160.0, 161.0, 162.0, 162.0, 161.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 112.0, 76.0, 75.0, 74.0, 75.0, 99.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 118.0, 82.0, 82.0, 84.0, 114.0, 84.0, 85.0, 125.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 51.0, 50.0, 51.0, 54.0, 51.0, 52.0, 48.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 59.0, 58.0, 60.0, 59.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 86.0, 73.0, 75.0, 72.0, 71.0, 73.0, 71.0, 75.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 124.0, 126.0, 121.0, 123.0, 118.0, 117.0, 152.0, 120.0], "value": 124.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 153.0, 155.0, 151.0, 155.0, 150.0, 151.0, 152.0, 151.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 60.0, 65.0, 60.0, 60.0, 61.0, 60.0, 59.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1454.5, "alertThreshold": 2.0}]} 22:07:44 INFO - Return code: 0 22:07:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:07:44 INFO - # TBPL SUCCESS # 22:07:44 INFO - Running post-action listener: _resource_record_post_action 22:07:44 INFO - [mozharness: 2016-08-20 05:07:44.578348Z] Finished run-tests step (success) 22:07:44 INFO - Running post-run listener: _resource_record_post_run 22:07:44 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 286720; Write bytes: 329723904; Read time: 216; Write time: 184080 22:07:44 INFO - TinderboxPrint: CPU usage
12.9% 22:07:44 INFO - TinderboxPrint: I/O read bytes / time
286,720 / 216 22:07:44 INFO - TinderboxPrint: I/O write bytes / time
329,723,904 / 184,080 22:07:44 INFO - TinderboxPrint: CPU idle
8,272.4 (87.1%) 22:07:44 INFO - TinderboxPrint: CPU user
1,182.1 (12.4%) 22:07:44 INFO - TinderboxPrint: Swap in / out
0 / 0 22:07:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78262272; Read time: 0; Write time: 41544 22:07:44 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 286720; Write bytes: 250560512; Read time: 216; Write time: 142496 22:07:45 INFO - Running post-run listener: _upload_blobber_files 22:07:45 INFO - Blob upload gear active. 22:07:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:07:45 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 22:07:45 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'] 22:07:45 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 22:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 22:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:46 INFO - (blobuploader) - INFO - Done attempting. 22:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:47 INFO - (blobuploader) - INFO - Done attempting. 22:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:07:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:50 INFO - (blobuploader) - INFO - Done attempting. 22:07:50 INFO - (blobuploader) - INFO - Iteration through files over. 22:07:50 INFO - Return code: 0 22:07:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:07:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:07:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f236ade7126b87d53662a24955e9cc2779aab2580a89bf3fbd49e461368f0b9ded3892ce1bd821042e55450b64d366fcac207e984c3bf4bf156b7b10828e130d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7679685a4ad25fc9d06de4efc78e12c197a36d44cc837b8641e6f1a0a36098babfc474bf5abc9cbc60bf12d2084ac2cade319d4d3c7ff309aec12a0cfee4625d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad26da19175270ec5a109e12868713930459ed6eb3c725f9bd4a385e635d85477adc0f44bde208885e60b02f2cd2b124cfeedccb016c0e3270b8d27ebcb1e2fc"} 22:07:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:07:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:07:50 INFO - Contents: 22:07:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f236ade7126b87d53662a24955e9cc2779aab2580a89bf3fbd49e461368f0b9ded3892ce1bd821042e55450b64d366fcac207e984c3bf4bf156b7b10828e130d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7679685a4ad25fc9d06de4efc78e12c197a36d44cc837b8641e6f1a0a36098babfc474bf5abc9cbc60bf12d2084ac2cade319d4d3c7ff309aec12a0cfee4625d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad26da19175270ec5a109e12868713930459ed6eb3c725f9bd4a385e635d85477adc0f44bde208885e60b02f2cd2b124cfeedccb016c0e3270b8d27ebcb1e2fc"} 22:07:50 INFO - Running post-run listener: copy_logs_to_upload_dir 22:07:50 INFO - Copying logs to upload dir... 22:07:50 INFO - mkdir: /builds/slave/test/build/upload/logs 22:07:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.271174 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-19 22:07:50.372606) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:50.376431) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 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/f236ade7126b87d53662a24955e9cc2779aab2580a89bf3fbd49e461368f0b9ded3892ce1bd821042e55450b64d366fcac207e984c3bf4bf156b7b10828e130d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7679685a4ad25fc9d06de4efc78e12c197a36d44cc837b8641e6f1a0a36098babfc474bf5abc9cbc60bf12d2084ac2cade319d4d3c7ff309aec12a0cfee4625d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad26da19175270ec5a109e12868713930459ed6eb3c725f9bd4a385e635d85477adc0f44bde208885e60b02f2cd2b124cfeedccb016c0e3270b8d27ebcb1e2fc"} build_url:https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011236 build_url: 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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/f236ade7126b87d53662a24955e9cc2779aab2580a89bf3fbd49e461368f0b9ded3892ce1bd821042e55450b64d366fcac207e984c3bf4bf156b7b10828e130d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7679685a4ad25fc9d06de4efc78e12c197a36d44cc837b8641e6f1a0a36098babfc474bf5abc9cbc60bf12d2084ac2cade319d4d3c7ff309aec12a0cfee4625d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad26da19175270ec5a109e12868713930459ed6eb3c725f9bd4a385e635d85477adc0f44bde208885e60b02f2cd2b124cfeedccb016c0e3270b8d27ebcb1e2fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:50.405290) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:50.405658) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471667707.480444-602507168 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:50.484954) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 22:07:50.485318) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 22:07:50.485624) ========= ========= Total master_lag: 0.19 =========