builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1473394257.66 results: success (0) buildid: 20160908182520 builduid: 1f8ebc2ccc6540459145dee497460121 revision: 6cd56350a8953b0161a40064f0b332fbc93c6cb1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.664482) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.665187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.665491) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.708404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.708701) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 21:10:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.6M=0s 2016-09-08 21:10:58 (45.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121668 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.845087) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.845408) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018377 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:57.894267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 21:10:57.894594) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6cd56350a8953b0161a40064f0b332fbc93c6cb1 --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 6cd56350a8953b0161a40064f0b332fbc93c6cb1 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 21:10:58,391 truncating revision to first 12 chars 2016-09-08 21:10:58,391 Setting DEBUG logging. 2016-09-08 21:10:58,391 attempt 1/10 2016-09-08 21:10:58,392 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6cd56350a895?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 21:10:59,806 unpacking tar archive at: mozilla-aurora-6cd56350a895/testing/mozharness/ program finished with exit code 0 elapsedTime=1.853792 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 21:10:59.762218) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:59.762569) ========= script_repo_revision: 6cd56350a8953b0161a40064f0b332fbc93c6cb1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:59.763051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:59.763326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 21:10:59.774032) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-08 21:10:59.774737) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:11:00 INFO - MultiFileLogger online at 20160908 21:11:00 in /builds/slave/test 21:11:00 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:11:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:11:00 INFO - {'append_to_log': False, 21:11:00 INFO - 'base_work_dir': '/builds/slave/test', 21:11:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:11:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:11:00 INFO - 'branch': 'Mozilla-Aurora', 21:11:00 INFO - 'buildbot_json_path': 'buildprops.json', 21:11:00 INFO - 'config_files': ('talos/linux_config.py',), 21:11:00 INFO - 'default_actions': ('clobber', 21:11:00 INFO - 'read-buildbot-config', 21:11:00 INFO - 'download-and-extract', 21:11:00 INFO - 'populate-webroot', 21:11:00 INFO - 'create-virtualenv', 21:11:00 INFO - 'install', 21:11:00 INFO - 'run-tests'), 21:11:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:11:00 INFO - 'download_minidump_stackwalk': True, 21:11:00 INFO - 'download_symbols': 'ondemand', 21:11:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:11:00 INFO - 'tooltool.py': '/tools/tooltool.py', 21:11:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:11:00 INFO - '/tools/misc-python/virtualenv.py')}, 21:11:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:11:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:11:00 INFO - 'installer_path': 'installer.exe', 21:11:00 INFO - 'log_level': 'info', 21:11:00 INFO - 'log_name': 'talos', 21:11:00 INFO - 'log_to_console': True, 21:11:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:11:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:11:00 INFO - 'opt_config_files': (), 21:11:00 INFO - 'pip_index': False, 21:11:00 INFO - 'sps_profile': False, 21:11:00 INFO - 'sps_profile_interval': 0, 21:11:00 INFO - 'suite': 'dromaeojs', 21:11:00 INFO - 'system_bits': '32', 21:11:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:11:00 INFO - 'title': 'talos-linux64-ix-069', 21:11:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:11:00 INFO - 'use_talos_json': True, 21:11:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:11:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:11:00 INFO - 'work_dir': 'build'} 21:11:00 INFO - [mozharness: 2016-09-09 04:11:00.367033Z] Running clobber step. 21:11:00 INFO - Running pre-action listener: _resource_record_pre_action 21:11:00 INFO - Running main action method: clobber 21:11:00 INFO - rmtree: /builds/slave/test/build 21:11:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:11:00 INFO - Running post-action listener: _resource_record_post_action 21:11:00 INFO - [mozharness: 2016-09-09 04:11:00.818782Z] Finished clobber step (success) 21:11:00 INFO - [mozharness: 2016-09-09 04:11:00.818882Z] Running read-buildbot-config step. 21:11:00 INFO - Running pre-action listener: _resource_record_pre_action 21:11:00 INFO - Running main action method: read_buildbot_config 21:11:00 INFO - Using buildbot properties: 21:11:00 INFO - { 21:11:00 INFO - "project": "", 21:11:00 INFO - "product": "firefox", 21:11:00 INFO - "script_repo_revision": "production", 21:11:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:11:00 INFO - "repository": "", 21:11:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:11:00 INFO - "buildid": "20160908182520", 21:11:00 INFO - "pgo_build": "True", 21:11:00 INFO - "basedir": "/builds/slave/test", 21:11:00 INFO - "buildnumber": 510, 21:11:00 INFO - "slavename": "talos-linux64-ix-069", 21:11:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:11:00 INFO - "platform": "ubuntu64_hw", 21:11:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:11:00 INFO - "branch": "mozilla-aurora", 21:11:00 INFO - "repo_path": "releases/mozilla-aurora", 21:11:00 INFO - "revision": "6cd56350a8953b0161a40064f0b332fbc93c6cb1", 21:11:00 INFO - "stage_platform": "linux64-pgo", 21:11:00 INFO - "builduid": "1f8ebc2ccc6540459145dee497460121", 21:11:00 INFO - "slavebuilddir": "test" 21:11:00 INFO - } 21:11:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 21:11:00 INFO - Running post-action listener: _resource_record_post_action 21:11:00 INFO - [mozharness: 2016-09-09 04:11:00.821308Z] Finished read-buildbot-config step (success) 21:11:00 INFO - [mozharness: 2016-09-09 04:11:00.821397Z] Running download-and-extract step. 21:11:00 INFO - Running pre-action listener: _resource_record_pre_action 21:11:00 INFO - Running main action method: download_and_extract 21:11:00 INFO - mkdir: /builds/slave/test/build/tests 21:11:00 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:11:00 INFO - https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:11:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:11:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:11:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:11:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:11:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:11:00 INFO - retry: Failed, sleeping 30 seconds before retrying 21:11:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:11:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:11:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:11:30 INFO - retry: Failed, sleeping 60 seconds before retrying 21:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:12:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:12:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:30 INFO - Caught exception: HTTP Error 404: Not Found 21:12:30 INFO - Caught exception: HTTP Error 404: Not Found 21:12:30 INFO - Caught exception: HTTP Error 404: Not Found 21:12:30 INFO - trying https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:12:30 INFO - Downloading https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:31 INFO - Downloaded 1361 bytes. 21:12:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:12:31 INFO - Using the following test package requirements: 21:12:31 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:12:31 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:12:31 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:12:31 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'jsshell-linux-x86_64.zip'], 21:12:31 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:12:31 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:12:31 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:12:31 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:12:31 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:12:31 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:12:31 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:12:31 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:12:31 INFO - https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:12:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:12:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:12:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:12:31 INFO - retry: Failed, sleeping 30 seconds before retrying 21:13:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:13:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:13:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:13:01 INFO - retry: Failed, sleeping 60 seconds before retrying 21:14:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:14:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:01 INFO - Caught exception: HTTP Error 404: Not Found 21:14:01 INFO - Caught exception: HTTP Error 404: Not Found 21:14:01 INFO - Caught exception: HTTP Error 404: Not Found 21:14:01 INFO - trying https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:01 INFO - Downloading https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:02 INFO - Downloaded 21770334 bytes. 21:14:02 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:14:02 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:14:03 INFO - Return code: 0 21:14:03 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:14:03 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:14:03 INFO - https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:14:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:14:03 INFO - retry: Failed, sleeping 30 seconds before retrying 21:14:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:14:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:14:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:14:33 INFO - retry: Failed, sleeping 60 seconds before retrying 21:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:15:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:15:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:33 INFO - Caught exception: HTTP Error 404: Not Found 21:15:33 INFO - Caught exception: HTTP Error 404: Not Found 21:15:33 INFO - Caught exception: HTTP Error 404: Not Found 21:15:33 INFO - trying https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:15:33 INFO - Downloading https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:34 INFO - Downloaded 21770334 bytes. 21:15:34 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:15:34 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:15:36 INFO - Return code: 0 21:15:36 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:15:36 INFO - https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:15:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:15:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:15:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:15:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:15:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:15:36 INFO - retry: Failed, sleeping 30 seconds before retrying 21:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:16:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:16:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:16:06 INFO - retry: Failed, sleeping 60 seconds before retrying 21:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:17:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:17:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:06 INFO - Caught exception: HTTP Error 404: Not Found 21:17:06 INFO - Caught exception: HTTP Error 404: Not Found 21:17:06 INFO - Caught exception: HTTP Error 404: Not Found 21:17:06 INFO - trying https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:17:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:06 INFO - Downloaded 14413740 bytes. 21:17:06 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:17:06 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:17:07 INFO - Return code: 0 21:17:07 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:17:07 INFO - https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:17:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:17:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:17:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:17:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:17:07 INFO - retry: Failed, sleeping 30 seconds before retrying 21:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:17:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:17:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:17:37 INFO - retry: Failed, sleeping 60 seconds before retrying 21:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:18:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:18:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:18:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:18:37 INFO - Caught exception: HTTP Error 404: Not Found 21:18:37 INFO - Caught exception: HTTP Error 404: Not Found 21:18:37 INFO - Caught exception: HTTP Error 404: Not Found 21:18:37 INFO - trying https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:18:37 INFO - Downloading https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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:18:38 INFO - Downloaded 63914766 bytes. 21:18:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:18:38 INFO - mkdir: /builds/slave/test/properties 21:18:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:18:38 INFO - Writing to file /builds/slave/test/properties/build_url 21:18:38 INFO - Contents: 21:18:38 INFO - build_url:https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:18:39 INFO - Running post-action listener: _resource_record_post_action 21:18:39 INFO - Running post-action listener: set_extra_try_arguments 21:18:39 INFO - [mozharness: 2016-09-09 04:18:39.094192Z] Finished download-and-extract step (success) 21:18:39 INFO - [mozharness: 2016-09-09 04:18:39.094423Z] Running populate-webroot step. 21:18:39 INFO - Running pre-action listener: _resource_record_pre_action 21:18:39 INFO - Running main action method: populate_webroot 21:18:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:18:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:18:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:18:39 INFO - '--tppagecycles', 21:18:39 INFO - '7'], 21:18:39 INFO - 'tests': ['tsvgm']}}, 21:18:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:18:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:18:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:18:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:18:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:18:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:18:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['damp', 'tps']}, 21:18:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'tests': ['damp', 'tps']}, 21:18:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['dromaeo_dom']}, 21:18:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:18:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['basic_compositor_video']}, 21:18:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:18:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'other-e10s': {'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['a11yr', 21:18:39 INFO - 'ts_paint', 21:18:39 INFO - 'tpaint', 21:18:39 INFO - 'sessionrestore', 21:18:39 INFO - 'sessionrestore_no_auto_restore', 21:18:39 INFO - 'tabpaint']}, 21:18:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['tsvgx', 21:18:39 INFO - 'tsvgr_opacity', 21:18:39 INFO - 'tart', 21:18:39 INFO - 'tscrollx', 21:18:39 INFO - 'cart']}, 21:18:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:18:39 INFO - 'tsvgr_opacity', 21:18:39 INFO - 'tart', 21:18:39 INFO - 'tscrollx', 21:18:39 INFO - 'cart']}, 21:18:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'talos_options': ['--disable-e10s'], 21:18:39 INFO - 'tests': ['tp5o']}, 21:18:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'tests': ['tp5o']}, 21:18:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'talos_options': ['--disable-e10s', 21:18:39 INFO - '--xperf_path', 21:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:39 INFO - 'tests': ['tp5n']}, 21:18:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:39 INFO - 'talos_options': ['--xperf_path', 21:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:39 INFO - 'tests': ['tp5n']}}, 21:18:39 INFO - 'talos.zip': {'path': '', 21:18:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:18:39 INFO - Running post-action listener: _resource_record_post_action 21:18:39 INFO - [mozharness: 2016-09-09 04:18:39.116032Z] Finished populate-webroot step (success) 21:18:39 INFO - [mozharness: 2016-09-09 04:18:39.116120Z] Running create-virtualenv step. 21:18:39 INFO - Running pre-action listener: _resource_record_pre_action 21:18:39 INFO - Running main action method: create_virtualenv 21:18:39 INFO - Creating virtualenv /builds/slave/test/build/venv 21:18:39 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:18:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:18:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:18:39 INFO - Using real prefix '/usr' 21:18:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:18:39 INFO - Installing distribute.............................................................................................................................................................................................done. 21:18:41 INFO - Installing pip.................done. 21:18:41 INFO - Return code: 0 21:18:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:18:41 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:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:41 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:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:41 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:41 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:18:41 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:18:41 INFO - Using env: {'DISPLAY': ':0', 21:18:41 INFO - 'HOME': '/home/cltbld', 21:18:41 INFO - 'LANG': 'en_US.UTF-8', 21:18:41 INFO - 'LANGUAGE': 'en_US:en', 21:18:41 INFO - 'LOGNAME': 'cltbld', 21:18:41 INFO - 'MAIL': '/var/mail/cltbld', 21:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:41 INFO - 'MOZ_NO_REMOTE': '1', 21:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:41 INFO - 'NO_EM_RESTART': '1', 21:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:41 INFO - 'PWD': '/builds/slave/test', 21:18:41 INFO - 'SHELL': '/bin/bash', 21:18:41 INFO - 'SHLVL': '1', 21:18:41 INFO - 'TERM': 'linux', 21:18:41 INFO - 'TMOUT': '86400', 21:18:41 INFO - 'USER': 'cltbld', 21:18:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:41 INFO - '_': '/tools/buildbot/bin/python'} 21:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:41 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:18:41 INFO - Cleaning up... 21:18:41 INFO - Return code: 0 21:18:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:18:41 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:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:41 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:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:41 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:41 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:18:41 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:18:41 INFO - Using env: {'DISPLAY': ':0', 21:18:41 INFO - 'HOME': '/home/cltbld', 21:18:41 INFO - 'LANG': 'en_US.UTF-8', 21:18:41 INFO - 'LANGUAGE': 'en_US:en', 21:18:41 INFO - 'LOGNAME': 'cltbld', 21:18:41 INFO - 'MAIL': '/var/mail/cltbld', 21:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:41 INFO - 'MOZ_NO_REMOTE': '1', 21:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:41 INFO - 'NO_EM_RESTART': '1', 21:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:41 INFO - 'PWD': '/builds/slave/test', 21:18:41 INFO - 'SHELL': '/bin/bash', 21:18:41 INFO - 'SHLVL': '1', 21:18:41 INFO - 'TERM': 'linux', 21:18:41 INFO - 'TMOUT': '86400', 21:18:41 INFO - 'USER': 'cltbld', 21:18:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:41 INFO - '_': '/tools/buildbot/bin/python'} 21:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:42 INFO - Downloading/unpacking psutil>=3.1.1 21:18: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:18: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:18: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:18: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:18: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:18: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:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:18:44 INFO - Installing collected packages: psutil 21:18:44 INFO - Running setup.py install for psutil 21:18:44 INFO - building 'psutil._psutil_linux' extension 21:18:44 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:18:44 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:18:44 INFO - building 'psutil._psutil_posix' extension 21:18:44 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:18:44 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:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:18:44 INFO - Successfully installed psutil 21:18:44 INFO - Cleaning up... 21:18:44 INFO - Return code: 0 21:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:18:44 INFO - Using env: {'DISPLAY': ':0', 21:18:44 INFO - 'HOME': '/home/cltbld', 21:18:44 INFO - 'LANG': 'en_US.UTF-8', 21:18:44 INFO - 'LANGUAGE': 'en_US:en', 21:18:44 INFO - 'LOGNAME': 'cltbld', 21:18:44 INFO - 'MAIL': '/var/mail/cltbld', 21:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:44 INFO - 'MOZ_NO_REMOTE': '1', 21:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:44 INFO - 'NO_EM_RESTART': '1', 21:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:44 INFO - 'PWD': '/builds/slave/test', 21:18:44 INFO - 'SHELL': '/bin/bash', 21:18:44 INFO - 'SHLVL': '1', 21:18:44 INFO - 'TERM': 'linux', 21:18:44 INFO - 'TMOUT': '86400', 21:18:44 INFO - 'USER': 'cltbld', 21:18:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:44 INFO - '_': '/tools/buildbot/bin/python'} 21:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:18: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:18: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:18: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:18: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:18: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:18: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:18:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:18:46 INFO - Installing collected packages: mozsystemmonitor 21:18:46 INFO - Running setup.py install for mozsystemmonitor 21:18:46 INFO - Successfully installed mozsystemmonitor 21:18:46 INFO - Cleaning up... 21:18:46 INFO - Return code: 0 21:18:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:18: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:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18: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:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:46 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:46 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:18:46 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:18:46 INFO - Using env: {'DISPLAY': ':0', 21:18:46 INFO - 'HOME': '/home/cltbld', 21:18:46 INFO - 'LANG': 'en_US.UTF-8', 21:18:46 INFO - 'LANGUAGE': 'en_US:en', 21:18:46 INFO - 'LOGNAME': 'cltbld', 21:18:46 INFO - 'MAIL': '/var/mail/cltbld', 21:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:46 INFO - 'MOZ_NO_REMOTE': '1', 21:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:46 INFO - 'NO_EM_RESTART': '1', 21:18:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:46 INFO - 'PWD': '/builds/slave/test', 21:18:46 INFO - 'SHELL': '/bin/bash', 21:18:46 INFO - 'SHLVL': '1', 21:18:46 INFO - 'TERM': 'linux', 21:18:46 INFO - 'TMOUT': '86400', 21:18:46 INFO - 'USER': 'cltbld', 21:18:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:46 INFO - '_': '/tools/buildbot/bin/python'} 21:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:47 INFO - Downloading/unpacking jsonschema==2.5.1 21:18:47 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:18:47 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:18:47 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:18:47 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:18:47 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:18:47 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:18:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:18:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:49 INFO - Downloading functools32-3.2.3-2.tar.gz 21:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:18:49 INFO - warning: no files found matching '*.txt' 21:18:49 INFO - no previously-included directories found matching 'build' 21:18:49 INFO - no previously-included directories found matching 'dist' 21:18:49 INFO - no previously-included directories found matching '.git*' 21:18:49 INFO - Installing collected packages: jsonschema, functools32 21:18:49 INFO - Running setup.py install for functools32 21:18:49 INFO - warning: no files found matching '*.txt' 21:18:49 INFO - no previously-included directories found matching 'build' 21:18:49 INFO - no previously-included directories found matching 'dist' 21:18:49 INFO - no previously-included directories found matching '.git*' 21:18:49 INFO - Successfully installed jsonschema functools32 21:18:49 INFO - Cleaning up... 21:18:49 INFO - Return code: 0 21:18:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:18: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:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18: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:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:49 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:49 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:18:49 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:18:49 INFO - Using env: {'DISPLAY': ':0', 21:18:49 INFO - 'HOME': '/home/cltbld', 21:18:49 INFO - 'LANG': 'en_US.UTF-8', 21:18:49 INFO - 'LANGUAGE': 'en_US:en', 21:18:49 INFO - 'LOGNAME': 'cltbld', 21:18:49 INFO - 'MAIL': '/var/mail/cltbld', 21:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:49 INFO - 'MOZ_NO_REMOTE': '1', 21:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:49 INFO - 'NO_EM_RESTART': '1', 21:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:49 INFO - 'PWD': '/builds/slave/test', 21:18:49 INFO - 'SHELL': '/bin/bash', 21:18:49 INFO - 'SHLVL': '1', 21:18:49 INFO - 'TERM': 'linux', 21:18:49 INFO - 'TMOUT': '86400', 21:18:49 INFO - 'USER': 'cltbld', 21:18:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:49 INFO - '_': '/tools/buildbot/bin/python'} 21:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:18:49 INFO - Cleaning up... 21:18:49 INFO - Return code: 0 21:18:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:18: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:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18: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:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:49 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:49 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:18:49 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:18:49 INFO - Using env: {'DISPLAY': ':0', 21:18:49 INFO - 'HOME': '/home/cltbld', 21:18:49 INFO - 'LANG': 'en_US.UTF-8', 21:18:49 INFO - 'LANGUAGE': 'en_US:en', 21:18:49 INFO - 'LOGNAME': 'cltbld', 21:18:49 INFO - 'MAIL': '/var/mail/cltbld', 21:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:49 INFO - 'MOZ_NO_REMOTE': '1', 21:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:49 INFO - 'NO_EM_RESTART': '1', 21:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:49 INFO - 'PWD': '/builds/slave/test', 21:18:49 INFO - 'SHELL': '/bin/bash', 21:18:49 INFO - 'SHLVL': '1', 21:18:49 INFO - 'TERM': 'linux', 21:18:49 INFO - 'TMOUT': '86400', 21:18:49 INFO - 'USER': 'cltbld', 21:18:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:49 INFO - '_': '/tools/buildbot/bin/python'} 21:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:49 INFO - Downloading/unpacking blobuploader==1.2.4 21:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:49 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:18:51 INFO - Downloading blobuploader-1.2.4.tar.gz 21:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:18:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:18:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:52 INFO - Downloading docopt-0.6.1.tar.gz 21:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:18:52 INFO - Installing collected packages: blobuploader, requests, docopt 21:18:52 INFO - Running setup.py install for blobuploader 21:18:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:18:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:18:52 INFO - Running setup.py install for requests 21:18:53 INFO - Running setup.py install for docopt 21:18:53 INFO - Successfully installed blobuploader requests docopt 21:18:53 INFO - Cleaning up... 21:18:53 INFO - Return code: 0 21:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:18: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:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18: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:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:53 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:53 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:18:53 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:18:53 INFO - Using env: {'DISPLAY': ':0', 21:18:53 INFO - 'HOME': '/home/cltbld', 21:18:53 INFO - 'LANG': 'en_US.UTF-8', 21:18:53 INFO - 'LANGUAGE': 'en_US:en', 21:18:53 INFO - 'LOGNAME': 'cltbld', 21:18:53 INFO - 'MAIL': '/var/mail/cltbld', 21:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:53 INFO - 'MOZ_NO_REMOTE': '1', 21:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:53 INFO - 'NO_EM_RESTART': '1', 21:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:53 INFO - 'PWD': '/builds/slave/test', 21:18:53 INFO - 'SHELL': '/bin/bash', 21:18:53 INFO - 'SHLVL': '1', 21:18:53 INFO - 'TERM': 'linux', 21:18:53 INFO - 'TMOUT': '86400', 21:18:53 INFO - 'USER': 'cltbld', 21:18:53 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:53 INFO - '_': '/tools/buildbot/bin/python'} 21:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:18:53 INFO - Running setup.py (path:/tmp/pip-cmZm54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:18:53 INFO - Running setup.py (path:/tmp/pip-O1VeHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:18:53 INFO - Running setup.py (path:/tmp/pip-51SPuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:18:53 INFO - Running setup.py (path:/tmp/pip-ItPlZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:18:53 INFO - Running setup.py (path:/tmp/pip-GZnH4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:18:53 INFO - Running setup.py (path:/tmp/pip-U1HCSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:18:54 INFO - Running setup.py (path:/tmp/pip-hCbn38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:18:54 INFO - Running setup.py (path:/tmp/pip-QePfJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:18:54 INFO - Running setup.py (path:/tmp/pip-y_vV26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:18:54 INFO - Running setup.py (path:/tmp/pip-cmF5nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:18:54 INFO - Running setup.py (path:/tmp/pip-7IvGo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:18:54 INFO - Running setup.py (path:/tmp/pip-QjcJ1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:18:54 INFO - Running setup.py (path:/tmp/pip-ziuP8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:18:54 INFO - Running setup.py (path:/tmp/pip-frrv_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:54 INFO - Running setup.py (path:/tmp/pip-u67Ir4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:18:54 INFO - Running setup.py (path:/tmp/pip-8r1Urf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:18:54 INFO - Running setup.py (path:/tmp/pip-6YqI9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:18:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:18:54 INFO - Running setup.py install for manifestparser 21:18:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:18:54 INFO - Running setup.py install for mozcrash 21:18:55 INFO - Running setup.py install for mozdebug 21:18:55 INFO - Running setup.py install for mozdevice 21:18:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for mozfile 21:18:55 INFO - Running setup.py install for mozhttpd 21:18:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for mozinfo 21:18:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for mozInstall 21:18:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for mozleak 21:18:55 INFO - Running setup.py install for mozlog 21:18:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for moznetwork 21:18:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:18:55 INFO - Running setup.py install for mozprocess 21:18:56 INFO - Running setup.py install for mozprofile 21:18:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:18:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:18:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:18:56 INFO - Running setup.py install for mozrunner 21:18:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:18:56 INFO - Running setup.py install for mozscreenshot 21:18:56 INFO - Running setup.py install for moztest 21:18:56 INFO - Running setup.py install for mozversion 21:18:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:18:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:18:56 INFO - Cleaning up... 21:18:56 INFO - Return code: 0 21:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:18: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:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18: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:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:56 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:18:56 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:18:56 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:18:56 INFO - Using env: {'DISPLAY': ':0', 21:18:56 INFO - 'HOME': '/home/cltbld', 21:18:56 INFO - 'LANG': 'en_US.UTF-8', 21:18:56 INFO - 'LANGUAGE': 'en_US:en', 21:18:56 INFO - 'LOGNAME': 'cltbld', 21:18:56 INFO - 'MAIL': '/var/mail/cltbld', 21:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:56 INFO - 'MOZ_NO_REMOTE': '1', 21:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:18:56 INFO - 'NO_EM_RESTART': '1', 21:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:56 INFO - 'PWD': '/builds/slave/test', 21:18:56 INFO - 'SHELL': '/bin/bash', 21:18:56 INFO - 'SHLVL': '1', 21:18:56 INFO - 'TERM': 'linux', 21:18:56 INFO - 'TMOUT': '86400', 21:18:56 INFO - 'USER': 'cltbld', 21:18:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:56 INFO - '_': '/tools/buildbot/bin/python'} 21:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:18:56 INFO - Running setup.py (path:/tmp/pip-hClllP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:18:56 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:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:18:56 INFO - Running setup.py (path:/tmp/pip-l25tji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:18:56 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:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:18:56 INFO - Running setup.py (path:/tmp/pip-iYQAm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:18:57 INFO - Running setup.py (path:/tmp/pip-pPYW63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:18:57 INFO - Running setup.py (path:/tmp/pip-OIHL6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:18:57 INFO - Running setup.py (path:/tmp/pip-tbYCNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:18:57 INFO - Running setup.py (path:/tmp/pip-R0qKIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:18:57 INFO - Running setup.py (path:/tmp/pip-JEkJwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:18:57 INFO - Running setup.py (path:/tmp/pip-nSt8Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:18:57 INFO - Running setup.py (path:/tmp/pip-bpcalY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:18:57 INFO - Running setup.py (path:/tmp/pip-8Fj2gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:18:57 INFO - Running setup.py (path:/tmp/pip-VdlNwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:18:57 INFO - Running setup.py (path:/tmp/pip-WrhZ1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:18:57 INFO - Running setup.py (path:/tmp/pip-ZC2DgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:57 INFO - Running setup.py (path:/tmp/pip-q8ISWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:18:57 INFO - Running setup.py (path:/tmp/pip-aEWaad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:18:57 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:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:18:57 INFO - Running setup.py (path:/tmp/pip-4orexw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:18:58 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:18:58 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:18:58 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:18:58 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:18:58 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:18:58 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:18:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:18:58 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:18:58 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:18:58 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:18:58 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:18:58 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:18:58 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:19:00 INFO - Downloading blessings-1.6.tar.gz 21:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:19:00 INFO - Installing collected packages: blessings 21:19:00 INFO - Running setup.py install for blessings 21:19:00 INFO - Successfully installed blessings 21:19:00 INFO - Cleaning up... 21:19:00 INFO - Return code: 0 21:19:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:19:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:19:00 INFO - Reading from file tmpfile_stdout 21:19:00 INFO - Current package versions: 21:19:00 INFO - argparse == 1.2.1 21:19:00 INFO - blessings == 1.6 21:19:00 INFO - blobuploader == 1.2.4 21:19:00 INFO - docopt == 0.6.1 21:19:00 INFO - functools32 == 3.2.3-2 21:19:00 INFO - jsonschema == 2.5.1 21:19:00 INFO - manifestparser == 1.1 21:19:00 INFO - mozInstall == 1.12 21:19:00 INFO - mozcrash == 0.17 21:19:00 INFO - mozdebug == 0.1 21:19:00 INFO - mozdevice == 0.48 21:19:00 INFO - mozfile == 1.2 21:19:00 INFO - mozhttpd == 0.7 21:19:00 INFO - mozinfo == 0.9 21:19:00 INFO - mozleak == 0.1 21:19:00 INFO - mozlog == 3.3 21:19:00 INFO - moznetwork == 0.27 21:19:00 INFO - mozprocess == 0.23 21:19:00 INFO - mozprofile == 0.28 21:19:00 INFO - mozrunner == 6.12 21:19:00 INFO - mozscreenshot == 0.1 21:19:00 INFO - mozsystemmonitor == 0.3 21:19:00 INFO - moztest == 0.7 21:19:00 INFO - mozversion == 1.4 21:19:00 INFO - psutil == 3.1.1 21:19:00 INFO - requests == 1.2.3 21:19:00 INFO - wsgiref == 0.1.2 21:19:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:19:00 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:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:00 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:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:00 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:19:00 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:19:00 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:19:00 INFO - Using env: {'DISPLAY': ':0', 21:19:00 INFO - 'HOME': '/home/cltbld', 21:19:00 INFO - 'LANG': 'en_US.UTF-8', 21:19:00 INFO - 'LANGUAGE': 'en_US:en', 21:19:00 INFO - 'LOGNAME': 'cltbld', 21:19:00 INFO - 'MAIL': '/var/mail/cltbld', 21:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:00 INFO - 'MOZ_NO_REMOTE': '1', 21:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:19:00 INFO - 'NO_EM_RESTART': '1', 21:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:00 INFO - 'PWD': '/builds/slave/test', 21:19:00 INFO - 'SHELL': '/bin/bash', 21:19:00 INFO - 'SHLVL': '1', 21:19:00 INFO - 'TERM': 'linux', 21:19:00 INFO - 'TMOUT': '86400', 21:19:00 INFO - 'USER': 'cltbld', 21:19:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:00 INFO - '_': '/tools/buildbot/bin/python'} 21:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 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:19:00 INFO - Cleaning up... 21:19:00 INFO - Return code: 0 21:19:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:19:00 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:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:00 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:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:19:00 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 0x1561030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1822180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', '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:19:00 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:19:00 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:19:00 INFO - Using env: {'DISPLAY': ':0', 21:19:00 INFO - 'HOME': '/home/cltbld', 21:19:00 INFO - 'LANG': 'en_US.UTF-8', 21:19:00 INFO - 'LANGUAGE': 'en_US:en', 21:19:00 INFO - 'LOGNAME': 'cltbld', 21:19:00 INFO - 'MAIL': '/var/mail/cltbld', 21:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:00 INFO - 'MOZ_NO_REMOTE': '1', 21:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:19:00 INFO - 'NO_EM_RESTART': '1', 21:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:00 INFO - 'PWD': '/builds/slave/test', 21:19:00 INFO - 'SHELL': '/bin/bash', 21:19:00 INFO - 'SHLVL': '1', 21:19:00 INFO - 'TERM': 'linux', 21:19:00 INFO - 'TMOUT': '86400', 21:19:00 INFO - 'USER': 'cltbld', 21:19:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:00 INFO - '_': '/tools/buildbot/bin/python'} 21:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:19:01 INFO - Cleaning up... 21:19:01 INFO - Return code: 0 21:19:01 INFO - Running post-action listener: _resource_record_post_action 21:19:01 INFO - Running post-action listener: _start_resource_monitoring 21:19:01 INFO - Starting resource monitoring. 21:19:01 INFO - [mozharness: 2016-09-09 04:19:01.098372Z] Finished create-virtualenv step (success) 21:19:01 INFO - [mozharness: 2016-09-09 04:19:01.098804Z] Running install step. 21:19:01 INFO - Running pre-action listener: _resource_record_pre_action 21:19:01 INFO - Running main action method: install 21:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:19:01 INFO - Reading from file tmpfile_stdout 21:19:01 INFO - Detecting whether we're running mozinstall >=1.0... 21:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:19:01 INFO - Reading from file tmpfile_stdout 21:19:01 INFO - Output received: 21:19:01 INFO - Usage: mozinstall [options] installer 21:19:01 INFO - Options: 21:19:01 INFO - -h, --help show this help message and exit 21:19:01 INFO - -d DEST, --destination=DEST 21:19:01 INFO - Directory to install application into. [default: 21:19:01 INFO - "/builds/slave/test"] 21:19:01 INFO - --app=APP Application being installed. [default: firefox] 21:19:01 INFO - mkdir: /builds/slave/test/build/application 21:19:01 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:19:01 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:19:17 INFO - Reading from file tmpfile_stdout 21:19:17 INFO - Output received: 21:19:17 INFO - /builds/slave/test/build/application/firefox/firefox 21:19:17 INFO - Running post-action listener: _resource_record_post_action 21:19:17 INFO - [mozharness: 2016-09-09 04:19:17.423183Z] Finished install step (success) 21:19:17 INFO - [mozharness: 2016-09-09 04:19:17.423519Z] Running run-tests step. 21:19:17 INFO - Running pre-action listener: _resource_record_pre_action 21:19:17 INFO - Running main action method: run_tests 21:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:19:17 INFO - Python 2.7.3 21:19:17 INFO - Return code: 0 21:19:17 INFO - grabbing minidump binary from tooltool 21:19:17 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:19:17 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 0x18203b0>, '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 0x18212b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1821740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:19:17 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:19:17 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:19:17 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:19:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:19:17 INFO - Return code: 0 21:19:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:19:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:19:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:19:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:19:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:19:17 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/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:19:17 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/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:19:17 INFO - Using env: {'DISPLAY': ':0', 21:19:17 INFO - 'HOME': '/home/cltbld', 21:19:17 INFO - 'LANG': 'en_US.UTF-8', 21:19:17 INFO - 'LANGUAGE': 'en_US:en', 21:19:17 INFO - 'LOGNAME': 'cltbld', 21:19:17 INFO - 'MAIL': '/var/mail/cltbld', 21:19:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:19:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:17 INFO - 'MOZ_NO_REMOTE': '1', 21:19:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:19:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:19:17 INFO - 'NO_EM_RESTART': '1', 21:19:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:17 INFO - 'PWD': '/builds/slave/test', 21:19:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:19:17 INFO - 'SHELL': '/bin/bash', 21:19:17 INFO - 'SHLVL': '1', 21:19:17 INFO - 'TERM': 'linux', 21:19:17 INFO - 'TMOUT': '86400', 21:19:17 INFO - 'USER': 'cltbld', 21:19:17 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697', 21:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:17 INFO - '_': '/tools/buildbot/bin/python'} 21:19:17 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/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:19:17 INFO - mozversion application_buildid: 20160908182520 21:19:17 INFO - mozversion application_changeset: 6cd56350a8953b0161a40064f0b332fbc93c6cb1 21:19:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:19:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:19:17 INFO - mozversion application_name: Firefox 21:19:17 INFO - mozversion application_remotingname: firefox-dev 21:19:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:19:17 INFO - mozversion application_vendor: Mozilla 21:19:17 INFO - mozversion application_version: 50.0a2 21:19:17 INFO - mozversion platform_buildid: 20160908182520 21:19:17 INFO - mozversion platform_changeset: 6cd56350a8953b0161a40064f0b332fbc93c6cb1 21:19:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:19:17 INFO - mozversion platform_version: 50.0a2 21:19:17 INFO - using testdate: 1473394757 21:19:17 INFO - actual date: 1473394757 21:19:17 INFO - starting webserver on 'localhost:58085' 21:19:17 INFO - SUITE-START | Running 2 tests 21:19:17 INFO - TEST-START | dromaeo_css 21:19:17 INFO - operating with platform_type : linux_ 21:19:17 INFO - Initialising browser for dromaeo_css test... 21:19:17 INFO - TEST-INFO | started process 20468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ33Zrk/profile http://localhost:58085/getInfo.html) 21:19:22 INFO - PROCESS | 20468 | __metrics Screen width/height:1600/1200 21:19:22 INFO - PROCESS | 20468 | colorDepth:24 21:19:22 INFO - PROCESS | 20468 | Browser inner width/height: 1024/697 21:19:22 INFO - PROCESS | 20468 | __metrics 21:19:23 INFO - PROCESS | 20468 | 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:19:23 INFO - PROCESS | 20468 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:19:24 INFO - TEST-INFO | 20468: exit 0 21:19:24 INFO - Browser initialized. 21:19:24 INFO - Running cycle 1/1 for dromaeo_css test... 21:19:24 INFO - TEST-INFO | started process 20558 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ33Zrk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:35 INFO - PROCESS | 20558 | RSS: Main: 145690624 21:19:35 INFO - PROCESS | 20558 | 21:20:26 INFO - PROCESS | 20558 | 1473394826559 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:22:35 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58085/tests/dromaeo/cssquery-ext.html) 21:22:36 INFO - PROCESS | 20558 | RSS: Main: 220516352 21:22:36 INFO - PROCESS | 20558 | 21:25:38 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-ext.html (next: http://localhost:58085/tests/dromaeo/cssquery-jquery.html) 21:25:39 INFO - PROCESS | 20558 | RSS: Main: 237006848 21:25:39 INFO - PROCESS | 20558 | 21:28:41 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58085/tests/dromaeo/cssquery-mootools.html) 21:28:41 INFO - PROCESS | 20558 | RSS: Main: 177598464 21:28:41 INFO - PROCESS | 20558 | 21:31:43 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58085/tests/dromaeo/cssquery-prototype.html) 21:31:44 INFO - PROCESS | 20558 | RSS: Main: 174325760 21:31:44 INFO - PROCESS | 20558 | 21:34:46 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58085/tests/dromaeo/cssquery-yui.html) 21:34:47 INFO - PROCESS | 20558 | RSS: Main: 176193536 21:34:47 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | Cycle 1(1): loaded http://localhost:58085/tests/dromaeo/cssquery-yui.html (next: http://localhost:58085/tests/dromaeo/cssquery-dojo.html) 21:37:50 INFO - PROCESS | 20558 | RSS: Main: 266543104 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | __start_tp_report 21:37:50 INFO - PROCESS | 20558 | _x_x_mozilla_page_load 21:37:50 INFO - PROCESS | 20558 | _x_x_mozilla_page_load_details 21:37:50 INFO - PROCESS | 20558 | |i|pagename|runs| 21:37:50 INFO - PROCESS | 20558 | |0;dojo.html;3168.033749706339;3173.5557022016924;3187.123541026219;3212.775105742599;3229.6931791479815;5321.654092483986;5425.891482170359;5432.592555558329;5439.129739241722;5453.481919217673;5425.348958125031;5442.455754424555;5452;5459.181122831577;5462.836114916549;4800.183968725325;4823.5176482351835;4917.655764096506;4944.60443164546;4991.376077990251;4861.489543597907;4942.18453955097;4955.479674634147;4957.182064959277;4959.528844759742;4901.289313049606;4925.926111108336;4934.654574179809;4940.580050695695;4957.107720610306;5039.949600504021;5057.519535644108;5062.265971434122;5063.240513922904;5082.339295891547;4529.637628989706;4840.540148685869;4874.488178741217;4964.478729733362;4998.525140111684;5311.787528498855;5332.413434522199;5352.625316227865;5364.356277246717;5369.221462887859;5860.355360910296;5870;5876.29484461863;5878.235829342201;5879.735411906432;5830.067189249742;5872.471477567039;5874.676892770896;5892.823215303505;5920.111983202511;5503.889922201533;5516.91724624131;5527.695976721278;5531.115021596525;5548.4451554845;5804.593678442468;6047.485963693135;6052.869103838222;6066.75732970685;6078.027515597571;5633.239512665819;5701.087825947829;5707.456740943608;5773.971130144407;5817.185594016841;5763.88472230553;5768.163616275617;5770.769169233267;5773.8556536086935;5779.566532510079;5656.095024796013;5729.627574207663;5752.6836024019085;5755.539556835527;5776.537876969833;5708.31500219968;5758.3665796762325;5772.971135144381;5789.2763404574425;5801.071828507503;4470.776461176928;4476.955230447719;4479.574440428154;4480.036792089716;4512.548745125461;3965.1871366369824;4025.81883815229;4030.858919937788;4032.1129351542613;4071.7353372030725;5483.095289277225;5505.366882808528;5509.393966663663;5514.420985796433;5516.034693928546;5843;5852.502537284294;5861.648301101947;5864.530837532987;5864.736086876101;5342.305500284938;5515.145152501288;5552;5571.192177134293;5583.720813959286;5006.349174607278;5016.648834581544;5029.572486338702;5065.164247899055;5072.112380333427;6035.336113027563;6039.063945088518;6047.0627052806885;6048.123022161762;6055.1220073090035;5623.8594035149445;5786.161006653927;5799.188113664006;5802.738876750472;5806.535477161732;5907.556933230111;5913.793017244376;5966;6006.669633170218;6012.368701286477;5659.349174844946;5672.829815105553;5701.572382071278;5706.657600543981;5707.543396528351;5715.714214289185;5779.988502012047;5792.247007888947;5794.565407594363;5798.884022319615;5578.497935185853;5641;5663.1788390682295;5682.460166284196;5758.337791153904;5694.914576281442;5698.031334673198;5698.116791897262;5699.316082070178;5701.572382071278;5680.886382272249;5685.20407142992;5687.20379146928;5692.288463942007;5697.914531281951;5586.13414920688;5725.055365864752;5747.109198074305;5784.50831679312;5863.17915491823;5653.604247702621;5663.830085097454;5668.149777533403;5671.319441667027;5677.1768093627015 21:37:50 INFO - PROCESS | 20558 | |1;ext.html;608.6287364707528;629.7008920762637;639.1371648274827;652.3248437866808;663.489113382695;35341;47026;47888;48253.51746482538;52553.211701824504;48994.51005489944;49401.25898111531;49418.25872611914;49506.50493495064;49631.503684963136;8597.097304783001;9284.118008789153;9478.90521094787;9500.334976551645;10981.450927453636;10842.837357439645;11297.717557061056;11306.208565400426;11318.264312819681;11321.773564528705;11338.773224535546;11383.146264030189;11388.886111138907;11391.886081139206;11398.601048963288;11455;11496.540138394537;11508.136889733303;11520.596779112691;11521.366324852088;11057.170712196617;11116.833247501294;11120.49957751895;11134.38760868152;11135.66593002203;46349.30476042862;46594.30108548375;46674.29988550174;46773.29840052402;46822.531774682495;3848.28806670764;3854.6530812227033;4015.31739604268;4249.2563801334645;4606.170889239936;35199.1200219994;35275;35283;35308.11729706778;35443;4637.744924029177;4665.626749860003;4699.271612900006;4803.951960480385;4826;362557;363785;365788;365863;367081;65787.34212657812;65865;65999.34000660028;66091;66303.33696662972;40354;41152.794236028625;41191.7940410302;69445;69732.3026769736;17721.822781772018;17875.553111172063;17887.642247154985;17891.73162402565;17911.910440447977;12548.12163148589;12797.616071517868;12798.040146989013;13174.209547427185;13356.5325213619;20462;21386.57226855454;21414.678779818314;21418.57162856734;21568;6386.712597933104;7310.451716121206;7381.926180738231;7726.304632583095;7745.341645960157;1000.8999100089933;1088.2926098036182;1135.182668478694;1148.8391625172483;1216.2520050169082;32599.674003260137;34514.137146571535;35279.823600881835;35321.64678353235;35374;1110.6945589962663;1171.1567671276666;1197.1320792425422;1238.133306685324;1243.160866415166;18277.379805065033;18480.168392422373;18535.629287414176;18540.44378668642;18723.625527489647;629919;635763;637052;637612;638900;65037.349626504074;65601.67199163974;65659.67170164119;65703.34296657068;65707.34292657013;1338.2104558310643;1386.8405133409585;1414.2716501001992;1458.3728996531381;1459.2266098967352;33117.83441082779;35999.28001439904;36036.63963360333;36189.27621447609;36222;54858;55033;55255.44744552664;55467;55568.44431555794;448967;450801;451953;452131;452182;30945.381092378477;31535.52696709597;31693.524597131523;31912.202194945312;33098.33803323872;52877.471225287256;52897.206541901134;52967;53187;53797.731011344695;44371.33442998422;45277.54722452865;45488;45926.54073459223;47415.762921185174;44565.55434445614;45872.54127458816;45885.082298354515;46785.532144678116;47451.52548474609;43208.56791432171;43686;44161.33757993697;44918;44953;38762.6123738759;40531;40682.59317406788;40757.388639171026;40983.79508102441;30143.246418838826;30365.69634303629;30697.693023069485;31073.378532429677;31300.53049204218 21:37:50 INFO - PROCESS | 20558 | |2;jquery.html;395.0912900329242;412.9896752581185;420.30859236555864;420.94106825044497;432.9393884856121;15264.69470610587;19946;20375.28686495973;20380;21060.473488162803;20356.389308320773;20421.08105135269;20435.284765033226;20468.795312046874;20683.482912927186;5843.2695913010875;5899.351071382144;6019.819405417845;6047.7278522466595;6063.029915213567;5735.225744524499;5865.120231965218;6034.758609655609;6048.667323297217;6058.697065146747;5926.259217597801;5955.285365756115;5981.611195272293;5985.610935289193;6006.8498287542725;5797.681127537984;5955.731992060368;6006.639601623895;6007.219061521996;6011.278646562397;5291.3385826771655;5293.967676303122;5295.152775555931;5299.761510732026;5305.575553955674;24293.149739759025;24660.506789864103;24729;24793.256202313958;25025.874870625714;2747.9450410991667;2778.3887544740123;2942.6615939167054;3029.5152775555807;3250.1712063423674;10679.733006674818;10808.621698240524;11109.44452777358;14632.195229262386;14995.550133496012;1911.4934542346289;1975.6937674660307;1990.4227773945538;2013.8690985086068;2106.9789302106938;61479.69260153715;75163;86589.56705216496;87009.56495217547;87436;11147.888521114848;11222.887771122347;11263.549458021751;11557.826632600518;11569.942150289195;15444.22778861075;15499.690006199813;15541.145237011846;15547;15567.065976041247;8099.109097999215;8236.094029656733;8261.297789687824;8409.453385529921;8473.661053557911;7991.160928102582;8030.959845200737;8040.276375126266;8174.713885014115;8179.550124743197;4678.345031695565;4789.640776941744;4808.951910480921;4825.252085926687;4854.951450485521;1985.6425843348336;1993.7707163676073;2010.1959216313298;2029.3810387831559;2078.418042947977;939.8214339275515;1018.8522664213648;1050.369778133114;1051.3534176481562;1064.914806815453;20252.291169808985;20919.26782562603;20947.601003789376;21000.474988125417;21175.57648847045;1008.7629407089328;1030.7423144213947;1055.7518983038979;1083.6369816111533;1095.769888323461;10823.512941917632;11566.42167891602;11667.533298668128;11679.357635330138;11685.240459370114;556745;565321;566974;572345;572884;93457;96795.51602241944;97086;97582;98535.50732246293;968.2737946540095;1002.8295189817749;1007.1539906478594;1024.2420608749471;1033.1528146919604;17530.91234543819;17727.468175954742;17897.552561186076;18205.54486137857;18310.450686479428;44427.33358999682;45185;45253.773731131136;45299.54700452953;45718.314225287315;59923;62502.687486562274;64094.67952660207;64780.02829957649;65060.34939650732;8817.779555510908;8928.285737141143;9053.864192036994;9118.726438206864;9324.440533568008;13079.346032698137;13129.409176587267;13511.864881351454;13668.52160174389;13894.861051389356;11559.248648837798;11784.41077946082;11893.345865977455;11958.162928595262;12426.75146497083;11106.833397498882;11204.327740335606;11685.123615729024;11795.764084718428;11826.704332391759;11460.426978651201;11488.425578720864;11879.58421455245;12540.122191446511;12676.809847852106;8652.480851148952;8912.242459391024;9180.491704976961;9339.57971891253;9429.962704102407;11361.54553817871;11444.542218311508;11953.820692689791;12013.939930300292;12234.694132646755 21:37:50 INFO - PROCESS | 20558 | |3;mootools.html;1378.173096142314;1564.2647955460932;1573.441428292956;1731.688296106701;1851.6296740651874;8693.30453563715;9039.774005649853;9048.547572621359;9402.153806157445;9415.482148481831;9251.722448326544;9373.390729602572;9373.76565585859;9381.859272110923;9384.906150938488;2413.613821788514;2430.598951173063;2436.683231179944;2441.7558244175616;2442.584760590695;2361.1735892437596;2399.748026457223;2403.3751224681623;2409.33743220614;2409.8674572898485;2377.120465427794;2380.773826486481;2387.868667223302;2405.7714517120935;2407.590709579376;2315.131825565413;2338.8830558472096;2343.789058984691;2350.059976009601;2361.563110824503;2315.6873822034145;2331.0326214620914;2339.087755775249;2340.6137987232087;2341.5433990371885;9186.219171370441;9272.721818345393;9299.628014879398;9341.813163736688;9348.485833279166;5398.541124004464;5615.073512870372;5619.522340601013;5623.437656234385;5625.943740562624;3400.4559270516597;3422.0760394693543;3460.9307813843584;3525.1363415962996;3538.929221415583;1141.5262665993603;1167.6847251242118;1172.4313707851686;1191.684203686024;1216;13097.541586044537;13134.540291089766;13210.009249306244;13270.336483175803;13281.667958301023;4765.928511072337;4784.7033423956;4802.327674125555;4871.610271178297;4936.605071594336;10343.689689309333;10345.586176553004;10361.585536578452;10363.170946324275;10364.689059328231;5639.3232812062815;5642.520385767256;5653.660780353192;5654.293213348332;5658.377578466366;4640;4753.714777113384;4977.726225057657;5071.54356107952;5323.494268044529;4001.899952501211;4045.91908161841;4113.382992551141;4130.132672138884;4152.833886644561;1136.1649187846926;1143.5654451308449;1145.833854091152;1171.4260012593768;1183.905287577009;1058.5606973106228;1059.3114475590928;1061.1669839176363;1069.2728944317782;1081.6971248050559;6369.872602547923;6559.934400655932;6582.769603063965;6674.2658307586125;6707.6981535831;461.6998950682084;472.41184725017575;1081.2269227502215;1120.9159313051555;1143.8112711402691;4450.643948484114;4616.376789133424;4706.576408123286;4715.292706094114;4752.405949256343;10948.726281843017;11537.423128843691;11599.710007249887;11608.477618507188;11671.88328116708;11246.7188320291;11371;11491.310521368745;11517.942410287895;11523.48144333507;8836.248918841842;8951.865722014305;8975.37172397939;8995.820083598292;9003.549822508849;6615.5038372123245;6615.801525954036;6649.634270114997;6688.765893193715;6754.459643228628;10802.459877005962;10926.453677316262;11037.834432483358;11086.556537738401;11091.057260132979;10997.780044399227;11031.282966607145;11219.158563107963;11273.943630281796;11276.887231127585;8076.353891688534;8270.710525131592;8445;8519.403641745019;8692.78268043304;9260.629574816932;9308.394954327947;9333.906660933575;9342.252619790537;9342.673006444742;7922.920770792219;7930.444868859125;7956.323712484272;7999.880001799861;8113.5131892086665;8215.342772578299;8299.875501867356;8341.20758527939;8346.833063338821;8511.233988941025;7268.818279543054;7328.926710732824;7378.225286344857;7381.224971378143;7408.148062972827;5533.418991005887;5566.387697353207;5717.799877004285;5750.856228594319;5880.029795083763;5799.333076696065;5946.583739138218;5983.790567330123;6001.189839371629;6038.426349496791 21:37:50 INFO - PROCESS | 20558 | |4;prototype.html;343.98624055037794;349.41996286164965;350.50928699820247;353.91329124364535;356.5186997553305;5514.310711161105;5533.924778208172;5542.224088627585;5547.833564993051;5551.444855514446;5448.645838020526;5480.177973304007;5516.558675305976;5516.834494965157;5518.806841760539;3862.343401621717;4106.589341065899;4145.91708165838;4299.9250187453135;4331.566843315659;4265.9786701066605;4287.764172970486;4299.3550967354995;4305.935410968838;4307.67692423068;4275.273203555403;4288.078063216408;4301.612854843063;4334.653227741789;4349.086691794727;4043.6158564936286;4130.297849365615;4243.724157929724;4249.235137675218;4330.631896288819;3793.146542028049;3793.886183414502;3796.3994663655485;3803.7147213958788;3827.349350610375;5297.17893726473;5315.734213289359;5351.839444816662;5389.757460914268;5414.8917021659345;4746;4857.368542089505;4886.633502487328;4901.990184024568;4943.838198023462;5651.9717401413145;5655.208270842086;5659.830205093854;5677.744501497401;5681.289838770153;4153.003279212967;4153.397757325171;4165.10450253194;4179.644730197937;4180.414741936132;5600.635958662674;5719.628224165416;5749.971250143764;5921.437463440966;5994.670293133876;4180.47744031996;4189.622933935961;4299.161663475655;4339.804708788049;4344.674149438804;4404.493483249467;4407.471103467605;4423.800928958204;4424.181526417623;4427.981564240243;4888;4968.975155124202;4969.130402179604;4970.552650261421;5004.3494345734825;5729;5736.541076713853;5739.770409183586;5745.052066408995;5749.396313387034;1497.9627706319397;1514.0158896717003;1514.4699355225582;1524.6264665156887;1526.770984352356;952.9809403811885;980.1472718734603;989.5547003848297;989.9703008909744;1011.206203130539;4909.109992652854;4926.556609905126;4945.505449455098;4966.591203266413;4974.850754477371;5138.614603904647;5155.7626169719;5179.031967852267;5183.678162068648;5221.765020574083;4698.530146985274;4916.1564413344995;4944.60443164546;4965.3573908395765;4980.501949805063;2801.537746271883;2810.4660114578164;2828.0384669212567;2853.215365774387;2860.884255140497;5431.891362172734;5462.180672899097;5486.533644640251;5488.4785945335125;5514.884090488861;5081.568066714297;5245.085543776494;5268.92096618551;5271.920921186109;5282.947170528322;5304.761285742074;5332.786688532416;5334.3865455473115;5335.146376579729;5359.410464848941;5162.535371816554;5242.737863106906;5252.9289800404995;5273.050850846885;5291.3121294231505;4741.976290118528;5108.168873344991;5127.256547800623;5133.307003554472;5165.133350990236;5381.865453363541;5881.17663527098;5986.910196347146;6095.908561371672;6106.267247930276;4282.935755963601;4308.073764140725;4312.288472402144;4337.349397590387;4337.7831108443825;5798.884022319446;5967.850803729942;5972.313183983723;5976.402359763989;5982.850428739316;5732.742026608729;5734.5125664319;5744.339400968942;5757.827265182052;5759.827205183851;5752.942470575241;5757.107648314518;5788.160716695971;5790.420957904176;5819.330776960704;5488.890222195613;5725.742341594721;5738.5696072795545;5755.036156379384;5832.504237139721;5858.765649373977;5859.970700146472;5860.38465961068;5885.323187833454;5891.558133139946;5495.477929596842;5691.174779656843;5727.4845263926445;5733.140028995684;5733.799317023884 21:37:50 INFO - PROCESS | 20558 | |5;yui.html;810.9351251899847;843.8227972125853;883.2271762208068;922.4834092907968;954.5799848066853;16047;16643.750343744825;16676.249568769403;16874.07192604408;16936.576585585368;15590.45433409831;15775.447859324926;15801.762973555406;15994.040357578553;16036.43872464464;725.6625669063884;859.3941271403645;873.3144481581944;880.6697488441835;883.8497455432592;880.1770344727668;880.2254016465502;882.1883866842488;882.2500874256895;882.4176043811086;945.9385139965915;947.4504787223428;947.8672985781996;948.506776476232;950.1686024728363;764.0296823034743;911.6080085563206;921.7234829551107;929.1637526226383;929.9677358132434;681.8908974564096;682.3517658224706;683.3747121383927;683.4054372695737;684.1584850633749;15866.04803711781;15983.600409989727;15998;16015.51953441387;16052.839471605252;14788.852111478855;15197.772033419507;15240.23798810055;15291.617709557238;15338.156401397919;12241.693957651041;12260.693482663004;13460.865391346157;13589.796153057712;13679.042467027311;4526.660500462479;4579.63667089143;4598.563136502027;4631.397918270637;4639.930401043987;79888.60055699742;80906.99999999942;83109.58445207795;83324;83331.16668833295;11113.888861111285;11150.386728729998;11376.488058037408;11431.14266430021;11503.597374091867;19713.112909919088;19858.1063852127;19887.303944362167;19915.900420498096;20350.491237718878;8355.415120941598;8440.15598440151;8451.535165565952;8462.87305690415;8580;4739.265413860857;4771;4779.187538118528;4792.568668819823;4978.925316120261;3314.287428202948;3431.879884204041;3433.5021421893684;3434.1929646533044;3435.6220815710253;599.6432122886963;600.129811772931;607.3926073926074;609.3906093906094;626.5927147354256;638.7572722365471;682.6143229075557;683.2450142592364;691.6576294734139;720.114259460866;9229.353945223904;9627.375667182207;9644;10042.863457784588;10060.144887684482;706.6925887239067;717.2325611595543;718.0019772516207;734.5042096584784;750.7560042986051;4448.243798554189;4566.725996440225;4786.545278198566;4922.310876477296;5008.824691135793;73067.63466182635;115676.42161789305;119953;125386;134695.32652336676;115822.42088789502;118190.40904795592;119511.40244298722;119653.40173299251;121909.39045304716;86760;86765.56617217;95696.52151739197;98295.50852245693;100411.49794250981;9334.953325233331;9406.294527910572;9576.186024187744;9579.473128977974;10023.198144148597;56387.43612563986;57412;57731.42268577428;58353.5411614713;58409;51449.742751286;67978;69206.61586768337;69304.65347673229;71521.28478715355;14467.855321447072;14483.420663173776;14571.198584077554;14735.336909838868;14937.850621493646;14323.641908952359;14462.421503140178;14545.781813272597;14754.557363279118;15632.374705012127;10826.188035897181;13716.17702937787;13719.588412347646;14035.298235088409;14261.928690356483;13542.932285338511;13795.586132416043;14066.859331406555;14107.153570785786;14176.858231417555;12912.354382281037;13255.204687718768;13271.66820829487;14215.218163001135;14667.559973200821;10914.890851091388;10942.124630029419;11054;11469.369184694922;11534.538618455168;10225.846612300673;10838.241323107306;11502.367369794742;11657.41712914334;11919.404029798648 21:37:50 INFO - PROCESS | 20558 | __end_tp_report 21:37:50 INFO - PROCESS | 20558 | __start_cc_report 21:37:50 INFO - PROCESS | 20558 | _x_x_mozilla_cycle_collect,1031 21:37:50 INFO - PROCESS | 20558 | __end_cc_report 21:37:50 INFO - PROCESS | 20558 | __startTimestamp1473395870446__endTimestamp 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | ------- Summary: start ------- 21:37:50 INFO - PROCESS | 20558 | Number of tests: 6 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#0] dojo.html Cycles:165 Average:5408.22 Median:5659.35 stddev:605.22 (10.7%) stddev-sans-first:581.00 21:37:50 INFO - PROCESS | 20558 | Values: 3168.0 3173.6 3187.1 3212.8 3229.7 5321.7 5425.9 5432.6 5439.1 5453.5 5425.3 5442.5 5452.0 5459.2 5462.8 4800.2 4823.5 4917.7 4944.6 4991.4 4861.5 4942.2 4955.5 4957.2 4959.5 4901.3 4925.9 4934.7 4940.6 4957.1 5039.9 5057.5 5062.3 5063.2 5082.3 4529.6 4840.5 4874.5 4964.5 4998.5 5311.8 5332.4 5352.6 5364.4 5369.2 5860.4 5870.0 5876.3 5878.2 5879.7 5830.1 5872.5 5874.7 5892.8 5920.1 5503.9 5516.9 5527.7 5531.1 5548.4 5804.6 6047.5 6052.9 6066.8 6078.0 5633.2 5701.1 5707.5 5774.0 5817.2 5763.9 5768.2 5770.8 5773.9 5779.6 5656.1 5729.6 5752.7 5755.5 5776.5 5708.3 5758.4 5773.0 5789.3 5801.1 4470.8 4477.0 4479.6 4480.0 4512.5 3965.2 4025.8 4030.9 4032.1 4071.7 5483.1 5505.4 5509.4 5514.4 5516.0 5843.0 5852.5 5861.6 5864.5 5864.7 5342.3 5515.1 5552.0 5571.2 5583.7 5006.3 5016.6 5029.6 5065.2 5072.1 6035.3 6039.1 6047.1 6048.1 6055.1 5623.9 5786.2 5799.2 5802.7 5806.5 5907.6 5913.8 5966.0 6006.7 6012.4 5659.3 5672.8 5701.6 5706.7 5707.5 5715.7 5780.0 5792.2 5794.6 5798.9 5578.5 5641.0 5663.2 5682.5 5758.3 5694.9 5698.0 5698.1 5699.3 5701.6 5680.9 5685.2 5687.2 5692.3 5697.9 5586.1 5725.1 5747.1 5784.5 5863.2 5653.6 5663.8 5668.1 5671.3 5677.2 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#1] ext.html Cycles:180 Average:66021.98 Median:22184.66 stddev:132014.90 (595.1%) stddev-sans-first:132293.88 21:37:50 INFO - PROCESS | 20558 | Values: 608.6 629.7 639.1 652.3 663.5 35341.0 47026.0 47888.0 48253.5 52553.2 48994.5 49401.3 49418.3 49506.5 49631.5 8597.1 9284.1 9478.9 9500.3 10981.5 10842.8 11297.7 11306.2 11318.3 11321.8 11338.8 11383.1 11388.9 11391.9 11398.6 11455.0 11496.5 11508.1 11520.6 11521.4 11057.2 11116.8 11120.5 11134.4 11135.7 46349.3 46594.3 46674.3 46773.3 46822.5 3848.3 3854.7 4015.3 4249.3 4606.2 35199.1 35275.0 35283.0 35308.1 35443.0 4637.7 4665.6 4699.3 4804.0 4826.0 362557.0 363785.0 365788.0 365863.0 367081.0 65787.3 65865.0 65999.3 66091.0 66303.3 40354.0 41152.8 41191.8 69445.0 69732.3 17721.8 17875.6 17887.6 17891.7 17911.9 12548.1 12797.6 12798.0 13174.2 13356.5 20462.0 21386.6 21414.7 21418.6 21568.0 6386.7 7310.5 7381.9 7726.3 7745.3 1000.9 1088.3 1135.2 1148.8 1216.3 32599.7 34514.1 35279.8 35321.6 35374.0 1110.7 1171.2 1197.1 1238.1 1243.2 18277.4 18480.2 18535.6 18540.4 18723.6 629919.0 635763.0 637052.0 637612.0 638900.0 65037.3 65601.7 65659.7 65703.3 65707.3 1338.2 1386.8 1414.3 1458.4 1459.2 33117.8 35999.3 36036.6 36189.3 36222.0 54858.0 55033.0 55255.4 55467.0 55568.4 448967.0 450801.0 451953.0 452131.0 452182.0 30945.4 31535.5 31693.5 31912.2 33098.3 52877.5 52897.2 52967.0 53187.0 53797.7 44371.3 45277.5 45488.0 45926.5 47415.8 44565.6 45872.5 45885.1 46785.5 47451.5 43208.6 43686.0 44161.3 44918.0 44953.0 38762.6 40531.0 40682.6 40757.4 40983.8 30143.2 30365.7 30697.7 31073.4 31300.5 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#2] jquery.html Cycles:180 Average:31815.58 Median:24761.13 stddev:93178.84 (376.3%) stddev-sans-first:93410.37 21:37:50 INFO - PROCESS | 20558 | Values: 395.1 413.0 420.3 420.9 432.9 15264.7 19946.0 20375.3 20380.0 21060.5 20356.4 20421.1 20435.3 20468.8 20683.5 5843.3 5899.4 6019.8 6047.7 6063.0 5735.2 5865.1 6034.8 6048.7 6058.7 5926.3 5955.3 5981.6 5985.6 6006.8 5797.7 5955.7 6006.6 6007.2 6011.3 5291.3 5294.0 5295.2 5299.8 5305.6 24293.1 24660.5 24729.0 24793.3 25025.9 2747.9 2778.4 2942.7 3029.5 3250.2 10679.7 10808.6 11109.4 14632.2 14995.6 1911.5 1975.7 1990.4 2013.9 2107.0 61479.7 75163.0 86589.6 87009.6 87436.0 11147.9 11222.9 11263.5 11557.8 11569.9 15444.2 15499.7 15541.1 15547.0 15567.1 8099.1 8236.1 8261.3 8409.5 8473.7 7991.2 8031.0 8040.3 8174.7 8179.6 4678.3 4789.6 4809.0 4825.3 4855.0 1985.6 1993.8 2010.2 2029.4 2078.4 939.8 1018.9 1050.4 1051.4 1064.9 20252.3 20919.3 20947.6 21000.5 21175.6 1008.8 1030.7 1055.8 1083.6 1095.8 10823.5 11566.4 11667.5 11679.4 11685.2 556745.0 565321.0 566974.0 572345.0 572884.0 93457.0 96795.5 97086.0 97582.0 98535.5 968.3 1002.8 1007.2 1024.2 1033.2 17530.9 17727.5 17897.6 18205.5 18310.5 44427.3 45185.0 45253.8 45299.5 45718.3 59923.0 62502.7 64094.7 64780.0 65060.3 8817.8 8928.3 9053.9 9118.7 9324.4 13079.3 13129.4 13511.9 13668.5 13894.9 11559.2 11784.4 11893.3 11958.2 12426.8 11106.8 11204.3 11685.1 11795.8 11826.7 11460.4 11488.4 11879.6 12540.1 12676.8 8652.5 8912.2 9180.5 9339.6 9430.0 11361.5 11444.5 11953.8 12013.9 12234.7 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#3] mootools.html Cycles:180 Average:6183.32 Median:4628.19 stddev:3535.05 (76.4%) stddev-sans-first:3526.52 21:37:50 INFO - PROCESS | 20558 | Values: 1378.2 1564.3 1573.4 1731.7 1851.6 8693.3 9039.8 9048.5 9402.2 9415.5 9251.7 9373.4 9373.8 9381.9 9384.9 2413.6 2430.6 2436.7 2441.8 2442.6 2361.2 2399.7 2403.4 2409.3 2409.9 2377.1 2380.8 2387.9 2405.8 2407.6 2315.1 2338.9 2343.8 2350.1 2361.6 2315.7 2331.0 2339.1 2340.6 2341.5 9186.2 9272.7 9299.6 9341.8 9348.5 5398.5 5615.1 5619.5 5623.4 5625.9 3400.5 3422.1 3460.9 3525.1 3538.9 1141.5 1167.7 1172.4 1191.7 1216.0 13097.5 13134.5 13210.0 13270.3 13281.7 4765.9 4784.7 4802.3 4871.6 4936.6 10343.7 10345.6 10361.6 10363.2 10364.7 5639.3 5642.5 5653.7 5654.3 5658.4 4640.0 4753.7 4977.7 5071.5 5323.5 4001.9 4045.9 4113.4 4130.1 4152.8 1136.2 1143.6 1145.8 1171.4 1183.9 1058.6 1059.3 1061.2 1069.3 1081.7 6369.9 6559.9 6582.8 6674.3 6707.7 461.7 472.4 1081.2 1120.9 1143.8 4450.6 4616.4 4706.6 4715.3 4752.4 10948.7 11537.4 11599.7 11608.5 11671.9 11246.7 11371.0 11491.3 11517.9 11523.5 8836.2 8951.9 8975.4 8995.8 9003.5 6615.5 6615.8 6649.6 6688.8 6754.5 10802.5 10926.5 11037.8 11086.6 11091.1 10997.8 11031.3 11219.2 11273.9 11276.9 8076.4 8270.7 8445.0 8519.4 8692.8 9260.6 9308.4 9333.9 9342.3 9342.7 7922.9 7930.4 7956.3 7999.9 8113.5 8215.3 8299.9 8341.2 8346.8 8511.2 7268.8 7328.9 7378.2 7381.2 7408.1 5533.4 5566.4 5717.8 5750.9 5880.0 5799.3 5946.6 5983.8 6001.2 6038.4 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#4] prototype.html Cycles:180 Average:4694.42 Median:5181.36 stddev:1343.45 (25.9%) stddev-sans-first:1306.93 21:37:50 INFO - PROCESS | 20558 | Values: 344.0 349.4 350.5 353.9 356.5 5514.3 5533.9 5542.2 5547.8 5551.4 5448.6 5480.2 5516.6 5516.8 5518.8 3862.3 4106.6 4145.9 4299.9 4331.6 4266.0 4287.8 4299.4 4305.9 4307.7 4275.3 4288.1 4301.6 4334.7 4349.1 4043.6 4130.3 4243.7 4249.2 4330.6 3793.1 3793.9 3796.4 3803.7 3827.3 5297.2 5315.7 5351.8 5389.8 5414.9 4746.0 4857.4 4886.6 4902.0 4943.8 5652.0 5655.2 5659.8 5677.7 5681.3 4153.0 4153.4 4165.1 4179.6 4180.4 5600.6 5719.6 5750.0 5921.4 5994.7 4180.5 4189.6 4299.2 4339.8 4344.7 4404.5 4407.5 4423.8 4424.2 4428.0 4888.0 4969.0 4969.1 4970.6 5004.3 5729.0 5736.5 5739.8 5745.1 5749.4 1498.0 1514.0 1514.5 1524.6 1526.8 953.0 980.1 989.6 990.0 1011.2 4909.1 4926.6 4945.5 4966.6 4974.9 5138.6 5155.8 5179.0 5183.7 5221.8 4698.5 4916.2 4944.6 4965.4 4980.5 2801.5 2810.5 2828.0 2853.2 2860.9 5431.9 5462.2 5486.5 5488.5 5514.9 5081.6 5245.1 5268.9 5271.9 5282.9 5304.8 5332.8 5334.4 5335.1 5359.4 5162.5 5242.7 5252.9 5273.1 5291.3 4742.0 5108.2 5127.3 5133.3 5165.1 5381.9 5881.2 5986.9 6095.9 6106.3 4282.9 4308.1 4312.3 4337.3 4337.8 5798.9 5967.9 5972.3 5976.4 5982.9 5732.7 5734.5 5744.3 5757.8 5759.8 5752.9 5757.1 5788.2 5790.4 5819.3 5488.9 5725.7 5738.6 5755.0 5832.5 5858.8 5860.0 5860.4 5885.3 5891.6 5495.5 5691.2 5727.5 5733.1 5733.8 21:37:50 INFO - PROCESS | 20558 | 21:37:50 INFO - PROCESS | 20558 | [#5] yui.html Cycles:180 Average:21809.50 Median:4573.18 stddev:32145.19 (702.9%) stddev-sans-first:32196.70 21:37:50 INFO - PROCESS | 20558 | Values: 810.9 843.8 883.2 922.5 954.6 16047.0 16643.8 16676.2 16874.1 16936.6 15590.5 15775.4 15801.8 15994.0 16036.4 725.7 859.4 873.3 880.7 883.8 880.2 880.2 882.2 882.3 882.4 945.9 947.5 947.9 948.5 950.2 764.0 911.6 921.7 929.2 930.0 681.9 682.4 683.4 683.4 684.2 15866.0 15983.6 15998.0 16015.5 16052.8 14788.9 15197.8 15240.2 15291.6 15338.2 12241.7 12260.7 13460.9 13589.8 13679.0 4526.7 4579.6 4598.6 4631.4 4639.9 79888.6 80907.0 83109.6 83324.0 83331.2 11113.9 11150.4 11376.5 11431.1 11503.6 19713.1 19858.1 19887.3 19915.9 20350.5 8355.4 8440.2 8451.5 8462.9 8580.0 4739.3 4771.0 4779.2 4792.6 4978.9 3314.3 3431.9 3433.5 3434.2 3435.6 599.6 600.1 607.4 609.4 626.6 638.8 682.6 683.2 691.7 720.1 9229.4 9627.4 9644.0 10042.9 10060.1 706.7 717.2 718.0 734.5 750.8 4448.2 4566.7 4786.5 4922.3 5008.8 73067.6 115676.4 119953.0 125386.0 134695.3 115822.4 118190.4 119511.4 119653.4 121909.4 86760.0 86765.6 95696.5 98295.5 100411.5 9335.0 9406.3 9576.2 9579.5 10023.2 56387.4 57412.0 57731.4 58353.5 58409.0 51449.7 67978.0 69206.6 69304.7 71521.3 14467.9 14483.4 14571.2 14735.3 14937.9 14323.6 14462.4 14545.8 14754.6 15632.4 10826.2 13716.2 13719.6 14035.3 14261.9 13542.9 13795.6 14066.9 14107.2 14176.9 12912.4 13255.2 13271.7 14215.2 14667.6 10914.9 10942.1 11054.0 11469.4 11534.5 10225.8 10838.2 11502.4 11657.4 11919.4 21:37:50 INFO - PROCESS | 20558 | -------- Summary: end -------- 21:37:50 INFO - PROCESS | 20558 | 21:37:51 INFO - TEST-INFO | 20558: exit 0 21:37:51 INFO - TEST-OK | dromaeo_css | took 1113510ms 21:37:51 INFO - TEST-START | kraken 21:37:51 INFO - operating with platform_type : linux_ 21:37:51 INFO - Initialising browser for kraken test... 21:37:51 INFO - TEST-INFO | started process 20756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprZRk4K/profile http://localhost:58085/getInfo.html) 21:37:55 INFO - PROCESS | 20756 | __metrics Screen width/height:1600/1200 21:37:55 INFO - PROCESS | 20756 | colorDepth:24 21:37:55 INFO - PROCESS | 20756 | Browser inner width/height: 1024/697 21:37:55 INFO - PROCESS | 20756 | __metrics 21:37:56 INFO - PROCESS | 20756 | 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:37:56 INFO - PROCESS | 20756 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:37:57 INFO - TEST-INFO | 20756: exit 0 21:37:57 INFO - Browser initialized. 21:37:57 INFO - Running cycle 1/1 for kraken test... 21:37:57 INFO - TEST-INFO | started process 20844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprZRk4K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:38:09 INFO - PROCESS | 20844 | RSS: Main: 145203200 21:38:09 INFO - PROCESS | 20844 | 21:38:11 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-beat-detection) 21:38:12 INFO - PROCESS | 20844 | RSS: Main: 200847360 21:38:12 INFO - PROCESS | 20844 | 21:38:15 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-dft) 21:38:15 INFO - PROCESS | 20844 | RSS: Main: 185081856 21:38:15 INFO - PROCESS | 20844 | 21:38:18 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-fft) 21:38:19 INFO - PROCESS | 20844 | RSS: Main: 275947520 21:38:19 INFO - PROCESS | 20844 | 21:38:21 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58085/tests/kraken/driver.html?testName=audio-oscillator) 21:38:21 INFO - PROCESS | 20844 | RSS: Main: 181706752 21:38:21 INFO - PROCESS | 20844 | 21:38:23 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:38:24 INFO - PROCESS | 20844 | RSS: Main: 180547584 21:38:24 INFO - PROCESS | 20844 | 21:38:27 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-darkroom) 21:38:28 INFO - PROCESS | 20844 | RSS: Main: 216170496 21:38:28 INFO - PROCESS | 20844 | 21:38:31 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58085/tests/kraken/driver.html?testName=imaging-desaturate) 21:38:32 INFO - PROCESS | 20844 | RSS: Main: 215343104 21:38:32 INFO - PROCESS | 20844 | 21:38:34 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58085/tests/kraken/driver.html?testName=json-parse-financial) 21:38:35 INFO - PROCESS | 20844 | RSS: Main: 214990848 21:38:35 INFO - PROCESS | 20844 | 21:38:36 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58085/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:38:37 INFO - PROCESS | 20844 | RSS: Main: 176558080 21:38:37 INFO - PROCESS | 20844 | 21:38:39 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:38:39 INFO - PROCESS | 20844 | RSS: Main: 184537088 21:38:39 INFO - PROCESS | 20844 | 21:38:41 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:38:42 INFO - PROCESS | 20844 | RSS: Main: 185102336 21:38:42 INFO - PROCESS | 20844 | 21:38:44 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:38:45 INFO - PROCESS | 20844 | RSS: Main: 186785792 21:38:45 INFO - PROCESS | 20844 | 21:38:48 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:38:48 INFO - PROCESS | 20844 | RSS: Main: 184557568 21:38:48 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | Cycle 1(1): loaded http://localhost:58085/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58085/tests/kraken/driver.html?testName=ai-astar) 21:38:50 INFO - PROCESS | 20844 | RSS: Main: 236036096 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | __start_tp_report 21:38:50 INFO - PROCESS | 20844 | _x_x_mozilla_page_load 21:38:50 INFO - PROCESS | 20844 | _x_x_mozilla_page_load_details 21:38:50 INFO - PROCESS | 20844 | |i|pagename|runs| 21:38:50 INFO - PROCESS | 20844 | |0;ai-astar;111;110;120;111;110;109;110;111;109;109 21:38:50 INFO - PROCESS | 20844 | |1;audio-beat-detection;143;119;145;183;120;141;140;121;178;114 21:38:50 INFO - PROCESS | 20844 | |2;audio-dft;160;161;161;161;159;160;159;159;163;159 21:38:50 INFO - PROCESS | 20844 | |3;audio-fft;75;75;74;167;74;75;74;75;169;77 21:38:50 INFO - PROCESS | 20844 | |4;audio-oscillator;84;84;157;83;84;84;145;83;83;152 21:38:50 INFO - PROCESS | 20844 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;116;116 21:38:50 INFO - PROCESS | 20844 | |6;imaging-darkroom;146;145;145;145;146;145;146;145;145;145 21:38:50 INFO - PROCESS | 20844 | |7;imaging-desaturate;95;95;95;94;95;94;95;95;94;94 21:38:50 INFO - PROCESS | 20844 | |8;json-parse-financial;50;49;50;50;50;51;52;50;49;49 21:38:50 INFO - PROCESS | 20844 | |9;json-stringify-tinderbox;58;59;61;59;60;60;59;60;59;59 21:38:50 INFO - PROCESS | 20844 | |10;stanford-crypto-aes;74;72;71;71;72;72;72;80;71;73 21:38:50 INFO - PROCESS | 20844 | |11;stanford-crypto-ccm;118;122;120;120;132;117;115;130;149;117 21:38:50 INFO - PROCESS | 20844 | |12;stanford-crypto-pbkdf2;153;149;152;154;150;153;147;153;147;153 21:38:50 INFO - PROCESS | 20844 | |13;stanford-crypto-sha256-iterative;66;60;61;64;61;61;61;61;60;61 21:38:50 INFO - PROCESS | 20844 | __end_tp_report 21:38:50 INFO - PROCESS | 20844 | __start_cc_report 21:38:50 INFO - PROCESS | 20844 | _x_x_mozilla_cycle_collect,1684 21:38:50 INFO - PROCESS | 20844 | __end_cc_report 21:38:50 INFO - PROCESS | 20844 | __startTimestamp1473395930602__endTimestamp 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | ------- Summary: start ------- 21:38:50 INFO - PROCESS | 20844 | Number of tests: 14 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.50 stddev:3.27 (3.0%) stddev-sans-first:3.46 21:38:50 INFO - PROCESS | 20844 | Values: 111.0 110.0 120.0 111.0 110.0 109.0 110.0 111.0 109.0 109.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:142.00 stddev:24.05 (16.9%) stddev-sans-first:25.49 21:38:50 INFO - PROCESS | 20844 | Values: 143.0 119.0 145.0 183.0 120.0 141.0 140.0 121.0 178.0 114.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:1.32 (0.8%) stddev-sans-first:1.39 21:38:50 INFO - PROCESS | 20844 | Values: 160.0 161.0 161.0 161.0 159.0 160.0 159.0 159.0 163.0 159.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#3] audio-fft Cycles:10 Average:93.50 Median:75.00 stddev:39.28 (52.4%) stddev-sans-first:41.09 21:38:50 INFO - PROCESS | 20844 | Values: 75.0 75.0 74.0 167.0 74.0 75.0 74.0 75.0 169.0 77.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#4] audio-oscillator Cycles:10 Average:103.90 Median:83.50 stddev:32.86 (39.4%) stddev-sans-first:34.05 21:38:50 INFO - PROCESS | 20844 | Values: 84.0 84.0 157.0 83.0 84.0 84.0 145.0 83.0 83.0 152.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 21:38:50 INFO - PROCESS | 20844 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 21:38:50 INFO - PROCESS | 20844 | Values: 146.0 145.0 145.0 145.0 146.0 145.0 146.0 145.0 145.0 145.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:38:50 INFO - PROCESS | 20844 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 94.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:0.94 (1.9%) stddev-sans-first:1.00 21:38:50 INFO - PROCESS | 20844 | Values: 50.0 49.0 50.0 50.0 50.0 51.0 52.0 50.0 49.0 49.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.73 21:38:50 INFO - PROCESS | 20844 | Values: 58.0 59.0 61.0 59.0 60.0 60.0 59.0 60.0 59.0 59.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:72.00 stddev:2.70 (3.7%) stddev-sans-first:2.83 21:38:50 INFO - PROCESS | 20844 | Values: 74.0 72.0 71.0 71.0 72.0 72.0 72.0 80.0 71.0 73.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:121.00 stddev:10.41 (8.6%) stddev-sans-first:10.82 21:38:50 INFO - PROCESS | 20844 | Values: 118.0 122.0 120.0 120.0 132.0 117.0 115.0 130.0 149.0 117.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:153.00 stddev:2.64 (1.7%) stddev-sans-first:2.71 21:38:50 INFO - PROCESS | 20844 | Values: 153.0 149.0 152.0 154.0 150.0 153.0 147.0 153.0 147.0 153.0 21:38:50 INFO - PROCESS | 20844 | 21:38:50 INFO - PROCESS | 20844 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.00 stddev:1.90 (3.1%) stddev-sans-first:1.17 21:38:50 INFO - PROCESS | 20844 | Values: 66.0 60.0 61.0 64.0 61.0 61.0 61.0 61.0 60.0 61.0 21:38:50 INFO - PROCESS | 20844 | -------- Summary: end -------- 21:38:50 INFO - PROCESS | 20844 | 21:38:51 INFO - TEST-INFO | 20844: exit 0 21:38:51 INFO - TEST-OK | kraken | took 60369ms 21:38:51 INFO - SUITE-END | took 1173s 21:38:51 INFO - Completed test suite (00:19:34) 21:38:51 INFO - javascript benchmark 21:38:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.033749706339, 3173.5557022016924, 3187.123541026219, 3212.775105742599, 3229.6931791479815, 5321.654092483986, 5425.891482170359, 5432.592555558329, 5439.129739241722, 5453.481919217673, 5425.348958125031, 5442.455754424555, 5452.0, 5459.181122831577, 5462.836114916549, 4800.183968725325, 4823.5176482351835, 4917.655764096506, 4944.60443164546, 4991.376077990251, 4861.489543597907, 4942.18453955097, 4955.479674634147, 4957.182064959277, 4959.528844759742, 4901.289313049606, 4925.926111108336, 4934.654574179809, 4940.580050695695, 4957.107720610306, 5039.949600504021, 5057.519535644108, 5062.265971434122, 5063.240513922904, 5082.339295891547, 4529.637628989706, 4840.540148685869, 4874.488178741217, 4964.478729733362, 4998.525140111684, 5311.787528498855, 5332.413434522199, 5352.625316227865, 5364.356277246717, 5369.221462887859, 5860.355360910296, 5870.0, 5876.29484461863, 5878.235829342201, 5879.735411906432, 5830.067189249742, 5872.471477567039, 5874.676892770896, 5892.823215303505, 5920.111983202511, 5503.889922201533, 5516.91724624131, 5527.695976721278, 5531.115021596525, 5548.4451554845, 5804.593678442468, 6047.485963693135, 6052.869103838222, 6066.75732970685, 6078.027515597571, 5633.239512665819, 5701.087825947829, 5707.456740943608, 5773.971130144407, 5817.185594016841, 5763.88472230553, 5768.163616275617, 5770.769169233267, 5773.8556536086935, 5779.566532510079, 5656.095024796013, 5729.627574207663, 5752.6836024019085, 5755.539556835527, 5776.537876969833, 5708.31500219968, 5758.3665796762325, 5772.971135144381, 5789.2763404574425, 5801.071828507503, 4470.776461176928, 4476.955230447719, 4479.574440428154, 4480.036792089716, 4512.548745125461, 3965.1871366369824, 4025.81883815229, 4030.858919937788, 4032.1129351542613, 4071.7353372030725, 5483.095289277225, 5505.366882808528, 5509.393966663663, 5514.420985796433, 5516.034693928546, 5843.0, 5852.502537284294, 5861.648301101947, 5864.530837532987, 5864.736086876101, 5342.305500284938, 5515.145152501288, 5552.0, 5571.192177134293, 5583.720813959286, 5006.349174607278, 5016.648834581544, 5029.572486338702, 5065.164247899055, 5072.112380333427, 6035.336113027563, 6039.063945088518, 6047.0627052806885, 6048.123022161762, 6055.1220073090035, 5623.8594035149445, 5786.161006653927, 5799.188113664006, 5802.738876750472, 5806.535477161732, 5907.556933230111, 5913.793017244376, 5966.0, 6006.669633170218, 6012.368701286477, 5659.349174844946, 5672.829815105553, 5701.572382071278, 5706.657600543981, 5707.543396528351, 5715.714214289185, 5779.988502012047, 5792.247007888947, 5794.565407594363, 5798.884022319615, 5578.497935185853, 5641.0, 5663.1788390682295, 5682.460166284196, 5758.337791153904, 5694.914576281442, 5698.031334673198, 5698.116791897262, 5699.316082070178, 5701.572382071278, 5680.886382272249, 5685.20407142992, 5687.20379146928, 5692.288463942007, 5697.914531281951, 5586.13414920688, 5725.055365864752, 5747.109198074305, 5784.50831679312, 5863.17915491823, 5653.604247702621, 5663.830085097454, 5668.149777533403, 5671.319441667027, 5677.1768093627015], "value": 5368.243852722833, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.6287364707528, 629.7008920762637, 639.1371648274827, 652.3248437866808, 663.489113382695, 35341.0, 47026.0, 47888.0, 48253.51746482538, 52553.211701824504, 48994.51005489944, 49401.25898111531, 49418.25872611914, 49506.50493495064, 49631.503684963136, 8597.097304783001, 9284.118008789153, 9478.90521094787, 9500.334976551645, 10981.450927453636, 10842.837357439645, 11297.717557061056, 11306.208565400426, 11318.264312819681, 11321.773564528705, 11338.773224535546, 11383.146264030189, 11388.886111138907, 11391.886081139206, 11398.601048963288, 11455.0, 11496.540138394537, 11508.136889733303, 11520.596779112691, 11521.366324852088, 11057.170712196617, 11116.833247501294, 11120.49957751895, 11134.38760868152, 11135.66593002203, 46349.30476042862, 46594.30108548375, 46674.29988550174, 46773.29840052402, 46822.531774682495, 3848.28806670764, 3854.6530812227033, 4015.31739604268, 4249.2563801334645, 4606.170889239936, 35199.1200219994, 35275.0, 35283.0, 35308.11729706778, 35443.0, 4637.744924029177, 4665.626749860003, 4699.271612900006, 4803.951960480385, 4826.0, 362557.0, 363785.0, 365788.0, 365863.0, 367081.0, 65787.34212657812, 65865.0, 65999.34000660028, 66091.0, 66303.33696662972, 40354.0, 41152.794236028625, 41191.7940410302, 69445.0, 69732.3026769736, 17721.822781772018, 17875.553111172063, 17887.642247154985, 17891.73162402565, 17911.910440447977, 12548.12163148589, 12797.616071517868, 12798.040146989013, 13174.209547427185, 13356.5325213619, 20462.0, 21386.57226855454, 21414.678779818314, 21418.57162856734, 21568.0, 6386.712597933104, 7310.451716121206, 7381.926180738231, 7726.304632583095, 7745.341645960157, 1000.8999100089933, 1088.2926098036182, 1135.182668478694, 1148.8391625172483, 1216.2520050169082, 32599.674003260137, 34514.137146571535, 35279.823600881835, 35321.64678353235, 35374.0, 1110.6945589962663, 1171.1567671276666, 1197.1320792425422, 1238.133306685324, 1243.160866415166, 18277.379805065033, 18480.168392422373, 18535.629287414176, 18540.44378668642, 18723.625527489647, 629919.0, 635763.0, 637052.0, 637612.0, 638900.0, 65037.349626504074, 65601.67199163974, 65659.67170164119, 65703.34296657068, 65707.34292657013, 1338.2104558310643, 1386.8405133409585, 1414.2716501001992, 1458.3728996531381, 1459.2266098967352, 33117.83441082779, 35999.28001439904, 36036.63963360333, 36189.27621447609, 36222.0, 54858.0, 55033.0, 55255.44744552664, 55467.0, 55568.44431555794, 448967.0, 450801.0, 451953.0, 452131.0, 452182.0, 30945.381092378477, 31535.52696709597, 31693.524597131523, 31912.202194945312, 33098.33803323872, 52877.471225287256, 52897.206541901134, 52967.0, 53187.0, 53797.731011344695, 44371.33442998422, 45277.54722452865, 45488.0, 45926.54073459223, 47415.762921185174, 44565.55434445614, 45872.54127458816, 45885.082298354515, 46785.532144678116, 47451.52548474609, 43208.56791432171, 43686.0, 44161.33757993697, 44918.0, 44953.0, 38762.6123738759, 40531.0, 40682.59317406788, 40757.388639171026, 40983.79508102441, 30143.246418838826, 30365.69634303629, 30697.693023069485, 31073.378532429677, 31300.53049204218], "value": 22185.765122916044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.0912900329242, 412.9896752581185, 420.30859236555864, 420.94106825044497, 432.9393884856121, 15264.69470610587, 19946.0, 20375.28686495973, 20380.0, 21060.473488162803, 20356.389308320773, 20421.08105135269, 20435.284765033226, 20468.795312046874, 20683.482912927186, 5843.2695913010875, 5899.351071382144, 6019.819405417845, 6047.7278522466595, 6063.029915213567, 5735.225744524499, 5865.120231965218, 6034.758609655609, 6048.667323297217, 6058.697065146747, 5926.259217597801, 5955.285365756115, 5981.611195272293, 5985.610935289193, 6006.8498287542725, 5797.681127537984, 5955.731992060368, 6006.639601623895, 6007.219061521996, 6011.278646562397, 5291.3385826771655, 5293.967676303122, 5295.152775555931, 5299.761510732026, 5305.575553955674, 24293.149739759025, 24660.506789864103, 24729.0, 24793.256202313958, 25025.874870625714, 2747.9450410991667, 2778.3887544740123, 2942.6615939167054, 3029.5152775555807, 3250.1712063423674, 10679.733006674818, 10808.621698240524, 11109.44452777358, 14632.195229262386, 14995.550133496012, 1911.4934542346289, 1975.6937674660307, 1990.4227773945538, 2013.8690985086068, 2106.9789302106938, 61479.69260153715, 75163.0, 86589.56705216496, 87009.56495217547, 87436.0, 11147.888521114848, 11222.887771122347, 11263.549458021751, 11557.826632600518, 11569.942150289195, 15444.22778861075, 15499.690006199813, 15541.145237011846, 15547.0, 15567.065976041247, 8099.109097999215, 8236.094029656733, 8261.297789687824, 8409.453385529921, 8473.661053557911, 7991.160928102582, 8030.959845200737, 8040.276375126266, 8174.713885014115, 8179.550124743197, 4678.345031695565, 4789.640776941744, 4808.951910480921, 4825.252085926687, 4854.951450485521, 1985.6425843348336, 1993.7707163676073, 2010.1959216313298, 2029.3810387831559, 2078.418042947977, 939.8214339275515, 1018.8522664213648, 1050.369778133114, 1051.3534176481562, 1064.914806815453, 20252.291169808985, 20919.26782562603, 20947.601003789376, 21000.474988125417, 21175.57648847045, 1008.7629407089328, 1030.7423144213947, 1055.7518983038979, 1083.6369816111533, 1095.769888323461, 10823.512941917632, 11566.42167891602, 11667.533298668128, 11679.357635330138, 11685.240459370114, 556745.0, 565321.0, 566974.0, 572345.0, 572884.0, 93457.0, 96795.51602241944, 97086.0, 97582.0, 98535.50732246293, 968.2737946540095, 1002.8295189817749, 1007.1539906478594, 1024.2420608749471, 1033.1528146919604, 17530.91234543819, 17727.468175954742, 17897.552561186076, 18205.54486137857, 18310.450686479428, 44427.33358999682, 45185.0, 45253.773731131136, 45299.54700452953, 45718.314225287315, 59923.0, 62502.687486562274, 64094.67952660207, 64780.02829957649, 65060.34939650732, 8817.779555510908, 8928.285737141143, 9053.864192036994, 9118.726438206864, 9324.440533568008, 13079.346032698137, 13129.409176587267, 13511.864881351454, 13668.52160174389, 13894.861051389356, 11559.248648837798, 11784.41077946082, 11893.345865977455, 11958.162928595262, 12426.75146497083, 11106.833397498882, 11204.327740335606, 11685.123615729024, 11795.764084718428, 11826.704332391759, 11460.426978651201, 11488.425578720864, 11879.58421455245, 12540.122191446511, 12676.809847852106, 8652.480851148952, 8912.242459391024, 9180.491704976961, 9339.57971891253, 9429.962704102407, 11361.54553817871, 11444.542218311508, 11953.820692689791, 12013.939930300292, 12234.694132646755], "value": 9662.534677316933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.173096142314, 1564.2647955460932, 1573.441428292956, 1731.688296106701, 1851.6296740651874, 8693.30453563715, 9039.774005649853, 9048.547572621359, 9402.153806157445, 9415.482148481831, 9251.722448326544, 9373.390729602572, 9373.76565585859, 9381.859272110923, 9384.906150938488, 2413.613821788514, 2430.598951173063, 2436.683231179944, 2441.7558244175616, 2442.584760590695, 2361.1735892437596, 2399.748026457223, 2403.3751224681623, 2409.33743220614, 2409.8674572898485, 2377.120465427794, 2380.773826486481, 2387.868667223302, 2405.7714517120935, 2407.590709579376, 2315.131825565413, 2338.8830558472096, 2343.789058984691, 2350.059976009601, 2361.563110824503, 2315.6873822034145, 2331.0326214620914, 2339.087755775249, 2340.6137987232087, 2341.5433990371885, 9186.219171370441, 9272.721818345393, 9299.628014879398, 9341.813163736688, 9348.485833279166, 5398.541124004464, 5615.073512870372, 5619.522340601013, 5623.437656234385, 5625.943740562624, 3400.4559270516597, 3422.0760394693543, 3460.9307813843584, 3525.1363415962996, 3538.929221415583, 1141.5262665993603, 1167.6847251242118, 1172.4313707851686, 1191.684203686024, 1216.0, 13097.541586044537, 13134.540291089766, 13210.009249306244, 13270.336483175803, 13281.667958301023, 4765.928511072337, 4784.7033423956, 4802.327674125555, 4871.610271178297, 4936.605071594336, 10343.689689309333, 10345.586176553004, 10361.585536578452, 10363.170946324275, 10364.689059328231, 5639.3232812062815, 5642.520385767256, 5653.660780353192, 5654.293213348332, 5658.377578466366, 4640.0, 4753.714777113384, 4977.726225057657, 5071.54356107952, 5323.494268044529, 4001.899952501211, 4045.91908161841, 4113.382992551141, 4130.132672138884, 4152.833886644561, 1136.1649187846926, 1143.5654451308449, 1145.833854091152, 1171.4260012593768, 1183.905287577009, 1058.5606973106228, 1059.3114475590928, 1061.1669839176363, 1069.2728944317782, 1081.6971248050559, 6369.872602547923, 6559.934400655932, 6582.769603063965, 6674.2658307586125, 6707.6981535831, 461.6998950682084, 472.41184725017575, 1081.2269227502215, 1120.9159313051555, 1143.8112711402691, 4450.643948484114, 4616.376789133424, 4706.576408123286, 4715.292706094114, 4752.405949256343, 10948.726281843017, 11537.423128843691, 11599.710007249887, 11608.477618507188, 11671.88328116708, 11246.7188320291, 11371.0, 11491.310521368745, 11517.942410287895, 11523.48144333507, 8836.248918841842, 8951.865722014305, 8975.37172397939, 8995.820083598292, 9003.549822508849, 6615.5038372123245, 6615.801525954036, 6649.634270114997, 6688.765893193715, 6754.459643228628, 10802.459877005962, 10926.453677316262, 11037.834432483358, 11086.556537738401, 11091.057260132979, 10997.780044399227, 11031.282966607145, 11219.158563107963, 11273.943630281796, 11276.887231127585, 8076.353891688534, 8270.710525131592, 8445.0, 8519.403641745019, 8692.78268043304, 9260.629574816932, 9308.394954327947, 9333.906660933575, 9342.252619790537, 9342.673006444742, 7922.920770792219, 7930.444868859125, 7956.323712484272, 7999.880001799861, 8113.5131892086665, 8215.342772578299, 8299.875501867356, 8341.20758527939, 8346.833063338821, 8511.233988941025, 7268.818279543054, 7328.926710732824, 7378.225286344857, 7381.224971378143, 7408.148062972827, 5533.418991005887, 5566.387697353207, 5717.799877004285, 5750.856228594319, 5880.029795083763, 5799.333076696065, 5946.583739138218, 5983.790567330123, 6001.189839371629, 6038.426349496791], "value": 4895.351488800676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.98624055037794, 349.41996286164965, 350.50928699820247, 353.91329124364535, 356.5186997553305, 5514.310711161105, 5533.924778208172, 5542.224088627585, 5547.833564993051, 5551.444855514446, 5448.645838020526, 5480.177973304007, 5516.558675305976, 5516.834494965157, 5518.806841760539, 3862.343401621717, 4106.589341065899, 4145.91708165838, 4299.9250187453135, 4331.566843315659, 4265.9786701066605, 4287.764172970486, 4299.3550967354995, 4305.935410968838, 4307.67692423068, 4275.273203555403, 4288.078063216408, 4301.612854843063, 4334.653227741789, 4349.086691794727, 4043.6158564936286, 4130.297849365615, 4243.724157929724, 4249.235137675218, 4330.631896288819, 3793.146542028049, 3793.886183414502, 3796.3994663655485, 3803.7147213958788, 3827.349350610375, 5297.17893726473, 5315.734213289359, 5351.839444816662, 5389.757460914268, 5414.8917021659345, 4746.0, 4857.368542089505, 4886.633502487328, 4901.990184024568, 4943.838198023462, 5651.9717401413145, 5655.208270842086, 5659.830205093854, 5677.744501497401, 5681.289838770153, 4153.003279212967, 4153.397757325171, 4165.10450253194, 4179.644730197937, 4180.414741936132, 5600.635958662674, 5719.628224165416, 5749.971250143764, 5921.437463440966, 5994.670293133876, 4180.47744031996, 4189.622933935961, 4299.161663475655, 4339.804708788049, 4344.674149438804, 4404.493483249467, 4407.471103467605, 4423.800928958204, 4424.181526417623, 4427.981564240243, 4888.0, 4968.975155124202, 4969.130402179604, 4970.552650261421, 5004.3494345734825, 5729.0, 5736.541076713853, 5739.770409183586, 5745.052066408995, 5749.396313387034, 1497.9627706319397, 1514.0158896717003, 1514.4699355225582, 1524.6264665156887, 1526.770984352356, 952.9809403811885, 980.1472718734603, 989.5547003848297, 989.9703008909744, 1011.206203130539, 4909.109992652854, 4926.556609905126, 4945.505449455098, 4966.591203266413, 4974.850754477371, 5138.614603904647, 5155.7626169719, 5179.031967852267, 5183.678162068648, 5221.765020574083, 4698.530146985274, 4916.1564413344995, 4944.60443164546, 4965.3573908395765, 4980.501949805063, 2801.537746271883, 2810.4660114578164, 2828.0384669212567, 2853.215365774387, 2860.884255140497, 5431.891362172734, 5462.180672899097, 5486.533644640251, 5488.4785945335125, 5514.884090488861, 5081.568066714297, 5245.085543776494, 5268.92096618551, 5271.920921186109, 5282.947170528322, 5304.761285742074, 5332.786688532416, 5334.3865455473115, 5335.146376579729, 5359.410464848941, 5162.535371816554, 5242.737863106906, 5252.9289800404995, 5273.050850846885, 5291.3121294231505, 4741.976290118528, 5108.168873344991, 5127.256547800623, 5133.307003554472, 5165.133350990236, 5381.865453363541, 5881.17663527098, 5986.910196347146, 6095.908561371672, 6106.267247930276, 4282.935755963601, 4308.073764140725, 4312.288472402144, 4337.349397590387, 4337.7831108443825, 5798.884022319446, 5967.850803729942, 5972.313183983723, 5976.402359763989, 5982.850428739316, 5732.742026608729, 5734.5125664319, 5744.339400968942, 5757.827265182052, 5759.827205183851, 5752.942470575241, 5757.107648314518, 5788.160716695971, 5790.420957904176, 5819.330776960704, 5488.890222195613, 5725.742341594721, 5738.5696072795545, 5755.036156379384, 5832.504237139721, 5858.765649373977, 5859.970700146472, 5860.38465961068, 5885.323187833454, 5891.558133139946, 5495.477929596842, 5691.174779656843, 5727.4845263926445, 5733.140028995684, 5733.799317023884], "value": 4274.500417645424, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.9351251899847, 843.8227972125853, 883.2271762208068, 922.4834092907968, 954.5799848066853, 16047.0, 16643.750343744825, 16676.249568769403, 16874.07192604408, 16936.576585585368, 15590.45433409831, 15775.447859324926, 15801.762973555406, 15994.040357578553, 16036.43872464464, 725.6625669063884, 859.3941271403645, 873.3144481581944, 880.6697488441835, 883.8497455432592, 880.1770344727668, 880.2254016465502, 882.1883866842488, 882.2500874256895, 882.4176043811086, 945.9385139965915, 947.4504787223428, 947.8672985781996, 948.506776476232, 950.1686024728363, 764.0296823034743, 911.6080085563206, 921.7234829551107, 929.1637526226383, 929.9677358132434, 681.8908974564096, 682.3517658224706, 683.3747121383927, 683.4054372695737, 684.1584850633749, 15866.04803711781, 15983.600409989727, 15998.0, 16015.51953441387, 16052.839471605252, 14788.852111478855, 15197.772033419507, 15240.23798810055, 15291.617709557238, 15338.156401397919, 12241.693957651041, 12260.693482663004, 13460.865391346157, 13589.796153057712, 13679.042467027311, 4526.660500462479, 4579.63667089143, 4598.563136502027, 4631.397918270637, 4639.930401043987, 79888.60055699742, 80906.99999999942, 83109.58445207795, 83324.0, 83331.16668833295, 11113.888861111285, 11150.386728729998, 11376.488058037408, 11431.14266430021, 11503.597374091867, 19713.112909919088, 19858.1063852127, 19887.303944362167, 19915.900420498096, 20350.491237718878, 8355.415120941598, 8440.15598440151, 8451.535165565952, 8462.87305690415, 8580.0, 4739.265413860857, 4771.0, 4779.187538118528, 4792.568668819823, 4978.925316120261, 3314.287428202948, 3431.879884204041, 3433.5021421893684, 3434.1929646533044, 3435.6220815710253, 599.6432122886963, 600.129811772931, 607.3926073926074, 609.3906093906094, 626.5927147354256, 638.7572722365471, 682.6143229075557, 683.2450142592364, 691.6576294734139, 720.114259460866, 9229.353945223904, 9627.375667182207, 9644.0, 10042.863457784588, 10060.144887684482, 706.6925887239067, 717.2325611595543, 718.0019772516207, 734.5042096584784, 750.7560042986051, 4448.243798554189, 4566.725996440225, 4786.545278198566, 4922.310876477296, 5008.824691135793, 73067.63466182635, 115676.42161789305, 119953.0, 125386.0, 134695.32652336676, 115822.42088789502, 118190.40904795592, 119511.40244298722, 119653.40173299251, 121909.39045304716, 86760.0, 86765.56617217, 95696.52151739197, 98295.50852245693, 100411.49794250981, 9334.953325233331, 9406.294527910572, 9576.186024187744, 9579.473128977974, 10023.198144148597, 56387.43612563986, 57412.0, 57731.42268577428, 58353.5411614713, 58409.0, 51449.742751286, 67978.0, 69206.61586768337, 69304.65347673229, 71521.28478715355, 14467.855321447072, 14483.420663173776, 14571.198584077554, 14735.336909838868, 14937.850621493646, 14323.641908952359, 14462.421503140178, 14545.781813272597, 14754.557363279118, 15632.374705012127, 10826.188035897181, 13716.17702937787, 13719.588412347646, 14035.298235088409, 14261.928690356483, 13542.932285338511, 13795.586132416043, 14066.859331406555, 14107.153570785786, 14176.858231417555, 12912.354382281037, 13255.204687718768, 13271.66820829487, 14215.218163001135, 14667.559973200821, 10914.890851091388, 10942.124630029419, 11054.0, 11469.369184694922, 11534.538618455168, 10225.846612300673, 10838.241323107306, 11502.367369794742, 11657.41712914334, 11919.404029798648], "value": 7853.027556858044, "unit": "score"}], "name": "dromaeo_css", "value": 7576.323819463841, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 120.0, 111.0, 110.0, 109.0, 110.0, 111.0, 109.0, 109.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 119.0, 145.0, 183.0, 120.0, 141.0, 140.0, 121.0, 178.0, 114.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 159.0, 160.0, 159.0, 159.0, 163.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 167.0, 74.0, 75.0, 74.0, 75.0, 169.0, 77.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 157.0, 83.0, 84.0, 84.0, 145.0, 83.0, 83.0, 152.0], "value": 103.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 50.0, 50.0, 51.0, 52.0, 50.0, 49.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 61.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 71.0, 71.0, 72.0, 72.0, 72.0, 80.0, 71.0, 73.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 120.0, 120.0, 132.0, 117.0, 115.0, 130.0, 149.0, 117.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 152.0, 154.0, 150.0, 153.0, 147.0, 153.0, 147.0, 153.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 60.0, 61.0, 64.0, 61.0, 61.0, 61.0, 61.0, 60.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1484.4999999999998, "alertThreshold": 2.0}]} 21:38:51 INFO - Return code: 0 21:38:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:38:51 INFO - # TBPL SUCCESS # 21:38:51 INFO - Running post-action listener: _resource_record_post_action 21:38:51 INFO - [mozharness: 2016-09-09 04:38:51.912292Z] Finished run-tests step (success) 21:38:51 INFO - Running post-run listener: _resource_record_post_run 21:38:51 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 326336512; Read time: 24; Write time: 437376 21:38:51 INFO - TinderboxPrint: CPU usage
13.0% 21:38:51 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 21:38:51 INFO - TinderboxPrint: I/O write bytes / time
326,336,512 / 437,376 21:38:51 INFO - TinderboxPrint: CPU idle
8,262.1 (87.0%) 21:38:51 INFO - TinderboxPrint: CPU user
1,187.9 (12.5%) 21:38:51 INFO - TinderboxPrint: Swap in / out
0 / 0 21:38:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77205504; Read time: 0; Write time: 185876 21:38:52 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 246820864; Read time: 16; Write time: 251336 21:38:52 INFO - Running post-run listener: _upload_blobber_files 21:38:52 INFO - Blob upload gear active. 21:38:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:38:52 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 21:38:52 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'] 21:38:52 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 21:38:52 INFO - (blobuploader) - INFO - Open directory for files ... 21:38:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:38:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:38:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:38:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:38:53 INFO - (blobuploader) - INFO - Done attempting. 21:38:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:38:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:38:57 INFO - (blobuploader) - INFO - Done attempting. 21:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:38:57 INFO - (blobuploader) - INFO - Done attempting. 21:38:57 INFO - (blobuploader) - INFO - Iteration through files over. 21:38:57 INFO - Return code: 0 21:38:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:38:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:38:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/588ee26608161e4ef1820ee5f70750d45181b29a997cab3242c00693de6d05e1c3e3b2ac7534bd7b250d73cfc919e41447032b08aa94d89eed014c64ca9a1edb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e7a9771139640a0eadb49fecc235884b3e56c0715731c52bd06ce7389a9b5538d3046cff67373c63d35584b5d7f879a194e1559cbc47b92b25b6645026e5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e31970ff02114e04ed7281abd8852d717f9479e97b09e75e3c6cf8107bfe932f37a57378376fa8b89b230ddf1fab73001b84777d3220cded5ccdecd9297100bd"} 21:38:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:38:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:38:57 INFO - Contents: 21:38:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/588ee26608161e4ef1820ee5f70750d45181b29a997cab3242c00693de6d05e1c3e3b2ac7534bd7b250d73cfc919e41447032b08aa94d89eed014c64ca9a1edb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e7a9771139640a0eadb49fecc235884b3e56c0715731c52bd06ce7389a9b5538d3046cff67373c63d35584b5d7f879a194e1559cbc47b92b25b6645026e5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e31970ff02114e04ed7281abd8852d717f9479e97b09e75e3c6cf8107bfe932f37a57378376fa8b89b230ddf1fab73001b84777d3220cded5ccdecd9297100bd"} 21:38:57 INFO - Running post-run listener: copy_logs_to_upload_dir 21:38:57 INFO - Copying logs to upload dir... 21:38:57 INFO - mkdir: /builds/slave/test/build/upload/logs 21:38:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.762584 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-08 21:38:57.527033) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 21:38:57.531180) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 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/588ee26608161e4ef1820ee5f70750d45181b29a997cab3242c00693de6d05e1c3e3b2ac7534bd7b250d73cfc919e41447032b08aa94d89eed014c64ca9a1edb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e7a9771139640a0eadb49fecc235884b3e56c0715731c52bd06ce7389a9b5538d3046cff67373c63d35584b5d7f879a194e1559cbc47b92b25b6645026e5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e31970ff02114e04ed7281abd8852d717f9479e97b09e75e3c6cf8107bfe932f37a57378376fa8b89b230ddf1fab73001b84777d3220cded5ccdecd9297100bd"} build_url:https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/cgk5zWjVQcqOdGqLDz95ww/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/588ee26608161e4ef1820ee5f70750d45181b29a997cab3242c00693de6d05e1c3e3b2ac7534bd7b250d73cfc919e41447032b08aa94d89eed014c64ca9a1edb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e7a9771139640a0eadb49fecc235884b3e56c0715731c52bd06ce7389a9b5538d3046cff67373c63d35584b5d7f879a194e1559cbc47b92b25b6645026e5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e31970ff02114e04ed7281abd8852d717f9479e97b09e75e3c6cf8107bfe932f37a57378376fa8b89b230ddf1fab73001b84777d3220cded5ccdecd9297100bd"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 21:38:57.584369) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:38:57.584774) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1473388670.592848-1756463697 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004620 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 21:38:57.634669) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 21:38:57.635082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 21:38:57.635392) ========= ========= Total master_lag: 0.14 =========