builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1471362769.53 results: success (0) buildid: 20160816071917 builduid: 72e192aeee604bb298eb38708725c94e revision: 8b47af8a1ad296295a0f9bfb61d6280265a5f109 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.531076) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.531452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.531722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.567528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.567805) ========= 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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 08:52:49-- 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% 47.1M=0s 2016-08-16 08:52:49 (47.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097599 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.685792) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.686067) ========= 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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020401 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.737048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:49.737377) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8b47af8a1ad296295a0f9bfb61d6280265a5f109 --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 8b47af8a1ad296295a0f9bfb61d6280265a5f109 --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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 08:52:49,864 truncating revision to first 12 chars 2016-08-16 08:52:49,864 Setting DEBUG logging. 2016-08-16 08:52:49,864 attempt 1/10 2016-08-16 08:52:49,865 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8b47af8a1ad2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 08:52:50,162 unpacking tar archive at: mozilla-aurora-8b47af8a1ad2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.711447 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:50.462711) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:50.462979) ========= script_repo_revision: 8b47af8a1ad296295a0f9bfb61d6280265a5f109 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:50.463341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:50.463601) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 08:52:50.473042) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-16 08:52:50.473315) ========= /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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:52:50 INFO - MultiFileLogger online at 20160816 08:52:50 in /builds/slave/test 08:52:50 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 08:52:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:50 INFO - {'append_to_log': False, 08:52:50 INFO - 'base_work_dir': '/builds/slave/test', 08:52:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:52:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:50 INFO - 'branch': 'Mozilla-Aurora', 08:52:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:50 INFO - 'config_files': ('talos/linux_config.py',), 08:52:50 INFO - 'default_actions': ('clobber', 08:52:50 INFO - 'read-buildbot-config', 08:52:50 INFO - 'download-and-extract', 08:52:50 INFO - 'populate-webroot', 08:52:50 INFO - 'create-virtualenv', 08:52:50 INFO - 'install', 08:52:50 INFO - 'run-tests'), 08:52:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:50 INFO - 'download_minidump_stackwalk': True, 08:52:50 INFO - 'download_symbols': 'ondemand', 08:52:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:50 INFO - 'installer_path': 'installer.exe', 08:52:50 INFO - 'log_level': 'info', 08:52:50 INFO - 'log_name': 'talos', 08:52:50 INFO - 'log_to_console': True, 08:52:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:52:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:52:50 INFO - 'opt_config_files': (), 08:52:50 INFO - 'pip_index': False, 08:52:50 INFO - 'sps_profile': False, 08:52:50 INFO - 'sps_profile_interval': 0, 08:52:50 INFO - 'suite': 'dromaeojs', 08:52:50 INFO - 'system_bits': '32', 08:52:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:50 INFO - 'title': 'talos-linux64-ix-011', 08:52:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:50 INFO - 'use_talos_json': True, 08:52:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:50 INFO - 'work_dir': 'build'} 08:52:50 INFO - [mozharness: 2016-08-16 15:52:50.694667Z] Running clobber step. 08:52:50 INFO - Running pre-action listener: _resource_record_pre_action 08:52:50 INFO - Running main action method: clobber 08:52:50 INFO - rmtree: /builds/slave/test/build 08:52:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:51 INFO - Running post-action listener: _resource_record_post_action 08:52:51 INFO - [mozharness: 2016-08-16 15:52:51.141631Z] Finished clobber step (success) 08:52:51 INFO - [mozharness: 2016-08-16 15:52:51.141731Z] Running read-buildbot-config step. 08:52:51 INFO - Running pre-action listener: _resource_record_pre_action 08:52:51 INFO - Running main action method: read_buildbot_config 08:52:51 INFO - Using buildbot properties: 08:52:51 INFO - { 08:52:51 INFO - "project": "", 08:52:51 INFO - "product": "firefox", 08:52:51 INFO - "script_repo_revision": "production", 08:52:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:52:51 INFO - "repository": "", 08:52:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:52:51 INFO - "buildid": "20160816071917", 08:52:51 INFO - "pgo_build": "True", 08:52:51 INFO - "basedir": "/builds/slave/test", 08:52:51 INFO - "buildnumber": 471, 08:52:51 INFO - "slavename": "talos-linux64-ix-011", 08:52:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:52:51 INFO - "platform": "ubuntu64_hw", 08:52:51 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:52:51 INFO - "branch": "mozilla-aurora", 08:52:51 INFO - "repo_path": "releases/mozilla-aurora", 08:52:51 INFO - "revision": "8b47af8a1ad296295a0f9bfb61d6280265a5f109", 08:52:51 INFO - "stage_platform": "linux64-pgo", 08:52:51 INFO - "builduid": "72e192aeee604bb298eb38708725c94e", 08:52:51 INFO - "slavebuilddir": "test" 08:52:51 INFO - } 08:52:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 08:52:51 INFO - Running post-action listener: _resource_record_post_action 08:52:51 INFO - [mozharness: 2016-08-16 15:52:51.144164Z] Finished read-buildbot-config step (success) 08:52:51 INFO - [mozharness: 2016-08-16 15:52:51.144253Z] Running download-and-extract step. 08:52:51 INFO - Running pre-action listener: _resource_record_pre_action 08:52:51 INFO - Running main action method: download_and_extract 08:52:51 INFO - mkdir: /builds/slave/test/build/tests 08:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:51 INFO - https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:52:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:52:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:52:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:52:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:52:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:52:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:52:51 INFO - retry: Failed, sleeping 30 seconds before retrying 08:53:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:53:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:53:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:53:21 INFO - retry: Failed, sleeping 60 seconds before retrying 08:54:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:54:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:54:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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! 08:54:21 INFO - Caught exception: HTTP Error 404: Not Found 08:54:21 INFO - Caught exception: HTTP Error 404: Not Found 08:54:21 INFO - Caught exception: HTTP Error 404: Not Found 08:54:21 INFO - trying https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:54:21 INFO - Downloading https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:21 INFO - Downloaded 1361 bytes. 08:54:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:54:21 INFO - Using the following test package requirements: 08:54:21 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:54:21 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:54:21 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:54:21 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'jsshell-linux-x86_64.zip'], 08:54:21 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:54:21 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:54:21 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:54:21 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:54:21 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:54:21 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:54:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:54:21 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:54:21 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')]} 08:54:21 INFO - https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:54:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:54:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:54:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:54:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:54:21 INFO - retry: Failed, sleeping 30 seconds before retrying 08:54:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:54:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:54:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:54:51 INFO - retry: Failed, sleeping 60 seconds before retrying 08:55:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:55:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:55:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:55:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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! 08:55:51 INFO - Caught exception: HTTP Error 404: Not Found 08:55:51 INFO - Caught exception: HTTP Error 404: Not Found 08:55:51 INFO - Caught exception: HTTP Error 404: Not Found 08:55:51 INFO - trying https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:55:51 INFO - Downloading https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:55:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:55:52 INFO - Downloaded 21766574 bytes. 08:55:52 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'] 08:55:52 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 08:55:53 INFO - Return code: 0 08:55:53 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 08:55: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')]} 08:55:53 INFO - https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:55:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:55:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:55:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:55:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:55:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:55:53 INFO - retry: Failed, sleeping 30 seconds before retrying 08:56:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:56:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:56:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:56:23 INFO - retry: Failed, sleeping 60 seconds before retrying 08:57:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:57:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:57:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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! 08:57:24 INFO - Caught exception: HTTP Error 404: Not Found 08:57:24 INFO - Caught exception: HTTP Error 404: Not Found 08:57:24 INFO - Caught exception: HTTP Error 404: Not Found 08:57:24 INFO - trying https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:57:24 INFO - Downloading https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:24 INFO - Downloaded 21766574 bytes. 08:57:24 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'] 08:57:24 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 08:57:26 INFO - Return code: 0 08:57:26 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')]} 08:57:26 INFO - https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:57:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:57:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:57:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:57:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:57:26 INFO - retry: Failed, sleeping 30 seconds before retrying 08:57:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:57:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:57:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:57:56 INFO - retry: Failed, sleeping 60 seconds before retrying 08:58:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:58:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:58:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:58:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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! 08:58:56 INFO - Caught exception: HTTP Error 404: Not Found 08:58:56 INFO - Caught exception: HTTP Error 404: Not Found 08:58:56 INFO - Caught exception: HTTP Error 404: Not Found 08:58:56 INFO - trying https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:58:56 INFO - Downloading https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:58:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:58:57 INFO - Downloaded 14413723 bytes. 08:58:57 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'] 08:58:57 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 08:58:58 INFO - Return code: 0 08:58:58 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')]} 08:58:58 INFO - https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:58:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:58:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:58:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:58:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:58:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:58:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:58:58 INFO - retry: Failed, sleeping 30 seconds before retrying 08:59:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 08:59:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:59:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:59:28 INFO - retry: Failed, sleeping 60 seconds before retrying 09:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 09:00:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:00:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:00:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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! 09:00:28 INFO - Caught exception: HTTP Error 404: Not Found 09:00:28 INFO - Caught exception: HTTP Error 404: Not Found 09:00:28 INFO - Caught exception: HTTP Error 404: Not Found 09:00:28 INFO - trying https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:00:28 INFO - Downloading https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 09:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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 09:00:29 INFO - Downloaded 63780556 bytes. 09:00:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:00:29 INFO - mkdir: /builds/slave/test/properties 09:00:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:29 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:29 INFO - Contents: 09:00:29 INFO - build_url:https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:00:30 INFO - Running post-action listener: _resource_record_post_action 09:00:30 INFO - Running post-action listener: set_extra_try_arguments 09:00:30 INFO - [mozharness: 2016-08-16 16:00:30.030202Z] Finished download-and-extract step (success) 09:00:30 INFO - [mozharness: 2016-08-16 16:00:30.030435Z] Running populate-webroot step. 09:00:30 INFO - Running pre-action listener: _resource_record_pre_action 09:00:30 INFO - Running main action method: populate_webroot 09:00:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:30 INFO - '--tppagecycles', 09:00:30 INFO - '7'], 09:00:30 INFO - 'tests': ['tsvgm']}}, 09:00:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['damp', 'tps']}, 09:00:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'tests': ['damp', 'tps']}, 09:00:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['dromaeo_dom']}, 09:00:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['basic_compositor_video']}, 09:00:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['a11yr', 09:00:30 INFO - 'ts_paint', 09:00:30 INFO - 'tpaint', 09:00:30 INFO - 'sessionrestore', 09:00:30 INFO - 'sessionrestore_no_auto_restore', 09:00:30 INFO - 'tabpaint']}, 09:00:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['tsvgx', 09:00:30 INFO - 'tsvgr_opacity', 09:00:30 INFO - 'tart', 09:00:30 INFO - 'tscrollx', 09:00:30 INFO - 'cart']}, 09:00:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:30 INFO - 'tsvgr_opacity', 09:00:30 INFO - 'tart', 09:00:30 INFO - 'tscrollx', 09:00:30 INFO - 'cart']}, 09:00:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'talos_options': ['--disable-e10s'], 09:00:30 INFO - 'tests': ['tp5o']}, 09:00:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'tests': ['tp5o']}, 09:00:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'talos_options': ['--disable-e10s', 09:00:30 INFO - '--xperf_path', 09:00:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:30 INFO - 'tests': ['tp5n']}, 09:00:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:30 INFO - 'talos_options': ['--xperf_path', 09:00:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:30 INFO - 'tests': ['tp5n']}}, 09:00:30 INFO - 'talos.zip': {'path': '', 09:00:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:30 INFO - Running post-action listener: _resource_record_post_action 09:00:30 INFO - [mozharness: 2016-08-16 16:00:30.050505Z] Finished populate-webroot step (success) 09:00:30 INFO - [mozharness: 2016-08-16 16:00:30.050594Z] Running create-virtualenv step. 09:00:30 INFO - Running pre-action listener: _resource_record_pre_action 09:00:30 INFO - Running main action method: create_virtualenv 09:00:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:30 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 09:00:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:30 INFO - Using real prefix '/usr' 09:00:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:32 INFO - Installing pip.................done. 09:00:32 INFO - Return code: 0 09:00:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:32 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:32 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 09:00:32 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 09:00:32 INFO - Using env: {'DISPLAY': ':0', 09:00:32 INFO - 'HOME': '/home/cltbld', 09:00:32 INFO - 'LANG': 'en_US.UTF-8', 09:00:32 INFO - 'LANGUAGE': 'en_US:en', 09:00:32 INFO - 'LOGNAME': 'cltbld', 09:00:32 INFO - 'MAIL': '/var/mail/cltbld', 09:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:32 INFO - 'MOZ_NO_REMOTE': '1', 09:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:32 INFO - 'NO_EM_RESTART': '1', 09:00:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:32 INFO - 'PWD': '/builds/slave/test', 09:00:32 INFO - 'SHELL': '/bin/bash', 09:00:32 INFO - 'SHLVL': '1', 09:00:32 INFO - 'TERM': 'linux', 09:00:32 INFO - 'TMOUT': '86400', 09:00:32 INFO - 'USER': 'cltbld', 09:00:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:32 INFO - '_': '/tools/buildbot/bin/python'} 09:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:32 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 09:00:32 INFO - Cleaning up... 09:00:32 INFO - Return code: 0 09:00:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:32 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:32 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 09:00:32 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 09:00:32 INFO - Using env: {'DISPLAY': ':0', 09:00:32 INFO - 'HOME': '/home/cltbld', 09:00:32 INFO - 'LANG': 'en_US.UTF-8', 09:00:32 INFO - 'LANGUAGE': 'en_US:en', 09:00:32 INFO - 'LOGNAME': 'cltbld', 09:00:32 INFO - 'MAIL': '/var/mail/cltbld', 09:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:32 INFO - 'MOZ_NO_REMOTE': '1', 09:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:32 INFO - 'NO_EM_RESTART': '1', 09:00:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:32 INFO - 'PWD': '/builds/slave/test', 09:00:32 INFO - 'SHELL': '/bin/bash', 09:00:32 INFO - 'SHLVL': '1', 09:00:32 INFO - 'TERM': 'linux', 09:00:32 INFO - 'TMOUT': '86400', 09:00:32 INFO - 'USER': 'cltbld', 09:00:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:32 INFO - '_': '/tools/buildbot/bin/python'} 09:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:33 INFO - Downloading/unpacking psutil>=3.1.1 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:33 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 09:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:35 INFO - Installing collected packages: psutil 09:00:35 INFO - Running setup.py install for psutil 09:00:35 INFO - building 'psutil._psutil_linux' extension 09:00:35 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 09:00:35 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 09:00:35 INFO - building 'psutil._psutil_posix' extension 09:00:35 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 09:00:35 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 09:00:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:35 INFO - Successfully installed psutil 09:00:35 INFO - Cleaning up... 09:00:35 INFO - Return code: 0 09:00:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:35 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:35 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 09:00:35 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 09:00:35 INFO - Using env: {'DISPLAY': ':0', 09:00:35 INFO - 'HOME': '/home/cltbld', 09:00:35 INFO - 'LANG': 'en_US.UTF-8', 09:00:35 INFO - 'LANGUAGE': 'en_US:en', 09:00:35 INFO - 'LOGNAME': 'cltbld', 09:00:35 INFO - 'MAIL': '/var/mail/cltbld', 09:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:35 INFO - 'MOZ_NO_REMOTE': '1', 09:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:35 INFO - 'NO_EM_RESTART': '1', 09:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:35 INFO - 'PWD': '/builds/slave/test', 09:00:35 INFO - 'SHELL': '/bin/bash', 09:00:35 INFO - 'SHLVL': '1', 09:00:35 INFO - 'TERM': 'linux', 09:00:35 INFO - 'TMOUT': '86400', 09:00:35 INFO - 'USER': 'cltbld', 09:00:35 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:35 INFO - '_': '/tools/buildbot/bin/python'} 09:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:35 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 09:00:35 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 09:00:35 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 09:00:35 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 09:00:35 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 09:00:35 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 09:00:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:37 INFO - Installing collected packages: mozsystemmonitor 09:00:37 INFO - Running setup.py install for mozsystemmonitor 09:00:37 INFO - Successfully installed mozsystemmonitor 09:00:37 INFO - Cleaning up... 09:00:37 INFO - Return code: 0 09:00:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:00:37 INFO - Using env: {'DISPLAY': ':0', 09:00:37 INFO - 'HOME': '/home/cltbld', 09:00:37 INFO - 'LANG': 'en_US.UTF-8', 09:00:37 INFO - 'LANGUAGE': 'en_US:en', 09:00:37 INFO - 'LOGNAME': 'cltbld', 09:00:37 INFO - 'MAIL': '/var/mail/cltbld', 09:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:37 INFO - 'MOZ_NO_REMOTE': '1', 09:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:37 INFO - 'NO_EM_RESTART': '1', 09:00:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:37 INFO - 'PWD': '/builds/slave/test', 09:00:37 INFO - 'SHELL': '/bin/bash', 09:00:37 INFO - 'SHLVL': '1', 09:00:37 INFO - 'TERM': 'linux', 09:00:37 INFO - 'TMOUT': '86400', 09:00:37 INFO - 'USER': 'cltbld', 09:00:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:37 INFO - '_': '/tools/buildbot/bin/python'} 09:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:38 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 09:00:38 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 09:00:38 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 09:00:38 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 09:00:38 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 09:00:38 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 09:00:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:40 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:40 INFO - warning: no files found matching '*.txt' 09:00:40 INFO - no previously-included directories found matching 'build' 09:00:40 INFO - no previously-included directories found matching 'dist' 09:00:40 INFO - no previously-included directories found matching '.git*' 09:00:40 INFO - Installing collected packages: jsonschema, functools32 09:00:40 INFO - Running setup.py install for functools32 09:00:40 INFO - warning: no files found matching '*.txt' 09:00:40 INFO - no previously-included directories found matching 'build' 09:00:40 INFO - no previously-included directories found matching 'dist' 09:00:40 INFO - no previously-included directories found matching '.git*' 09:00:40 INFO - Successfully installed jsonschema functools32 09:00:40 INFO - Cleaning up... 09:00:40 INFO - Return code: 0 09:00:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:40 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')]} 09:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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')]} 09:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:40 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 09:00:40 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 09:00:40 INFO - Using env: {'DISPLAY': ':0', 09:00:40 INFO - 'HOME': '/home/cltbld', 09:00:40 INFO - 'LANG': 'en_US.UTF-8', 09:00:40 INFO - 'LANGUAGE': 'en_US:en', 09:00:40 INFO - 'LOGNAME': 'cltbld', 09:00:40 INFO - 'MAIL': '/var/mail/cltbld', 09:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:40 INFO - 'MOZ_NO_REMOTE': '1', 09:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:40 INFO - 'NO_EM_RESTART': '1', 09:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:40 INFO - 'PWD': '/builds/slave/test', 09:00:40 INFO - 'SHELL': '/bin/bash', 09:00:40 INFO - 'SHLVL': '1', 09:00:40 INFO - 'TERM': 'linux', 09:00:40 INFO - 'TMOUT': '86400', 09:00:40 INFO - 'USER': 'cltbld', 09:00:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:40 INFO - '_': '/tools/buildbot/bin/python'} 09:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:40 INFO - Cleaning up... 09:00:40 INFO - Return code: 0 09:00:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:40 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')]} 09:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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')]} 09:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:40 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 09:00:40 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 09:00:40 INFO - Using env: {'DISPLAY': ':0', 09:00:40 INFO - 'HOME': '/home/cltbld', 09:00:40 INFO - 'LANG': 'en_US.UTF-8', 09:00:40 INFO - 'LANGUAGE': 'en_US:en', 09:00:40 INFO - 'LOGNAME': 'cltbld', 09:00:40 INFO - 'MAIL': '/var/mail/cltbld', 09:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:40 INFO - 'MOZ_NO_REMOTE': '1', 09:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:40 INFO - 'NO_EM_RESTART': '1', 09:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:40 INFO - 'PWD': '/builds/slave/test', 09:00:40 INFO - 'SHELL': '/bin/bash', 09:00:40 INFO - 'SHLVL': '1', 09:00:40 INFO - 'TERM': 'linux', 09:00:40 INFO - 'TMOUT': '86400', 09:00:40 INFO - 'USER': 'cltbld', 09:00:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:40 INFO - '_': '/tools/buildbot/bin/python'} 09:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:40 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 09:00:40 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 09:00:40 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 09:00:40 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 09:00:40 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 09:00:40 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 09:00:42 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:43 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 09:00:43 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 09:00:43 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 09:00:43 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 09:00:43 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 09:00:43 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 09:00:43 INFO - Downloading docopt-0.6.1.tar.gz 09:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:43 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:43 INFO - Running setup.py install for blobuploader 09:00:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:43 INFO - Running setup.py install for requests 09:00:43 INFO - Running setup.py install for docopt 09:00:43 INFO - Successfully installed blobuploader requests docopt 09:00:43 INFO - Cleaning up... 09:00:44 INFO - Return code: 0 09:00:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:44 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:44 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 09:00:44 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 09:00:44 INFO - Using env: {'DISPLAY': ':0', 09:00:44 INFO - 'HOME': '/home/cltbld', 09:00:44 INFO - 'LANG': 'en_US.UTF-8', 09:00:44 INFO - 'LANGUAGE': 'en_US:en', 09:00:44 INFO - 'LOGNAME': 'cltbld', 09:00:44 INFO - 'MAIL': '/var/mail/cltbld', 09:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:44 INFO - 'MOZ_NO_REMOTE': '1', 09:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:44 INFO - 'NO_EM_RESTART': '1', 09:00:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:44 INFO - 'PWD': '/builds/slave/test', 09:00:44 INFO - 'SHELL': '/bin/bash', 09:00:44 INFO - 'SHLVL': '1', 09:00:44 INFO - 'TERM': 'linux', 09:00:44 INFO - 'TMOUT': '86400', 09:00:44 INFO - 'USER': 'cltbld', 09:00:44 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:44 INFO - '_': '/tools/buildbot/bin/python'} 09:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:44 INFO - Running setup.py (path:/tmp/pip-kRqw3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:44 INFO - Running setup.py (path:/tmp/pip-Zxcjjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:44 INFO - Running setup.py (path:/tmp/pip-gO6_sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:44 INFO - Running setup.py (path:/tmp/pip-zbcvLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:44 INFO - Running setup.py (path:/tmp/pip-rZpq85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:44 INFO - Running setup.py (path:/tmp/pip-ofsluZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:44 INFO - Running setup.py (path:/tmp/pip-pe3RLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:44 INFO - Running setup.py (path:/tmp/pip-C6y4wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:44 INFO - Running setup.py (path:/tmp/pip-blyuus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:44 INFO - Running setup.py (path:/tmp/pip-0u1wHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:44 INFO - Running setup.py (path:/tmp/pip-KksJ4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:45 INFO - Running setup.py (path:/tmp/pip-3OK05b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:45 INFO - Running setup.py (path:/tmp/pip-pHKEBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:45 INFO - Running setup.py (path:/tmp/pip-FIJrqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:45 INFO - Running setup.py (path:/tmp/pip-sI4oOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:45 INFO - Running setup.py (path:/tmp/pip-gGbv7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:45 INFO - Running setup.py (path:/tmp/pip-fsiTob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:45 INFO - Running setup.py install for manifestparser 09:00:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:45 INFO - Running setup.py install for mozcrash 09:00:45 INFO - Running setup.py install for mozdebug 09:00:45 INFO - Running setup.py install for mozdevice 09:00:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:45 INFO - Running setup.py install for mozfile 09:00:46 INFO - Running setup.py install for mozhttpd 09:00:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozinfo 09:00:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozInstall 09:00:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozleak 09:00:46 INFO - Running setup.py install for mozlog 09:00:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for moznetwork 09:00:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozprocess 09:00:46 INFO - Running setup.py install for mozprofile 09:00:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozrunner 09:00:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:46 INFO - Running setup.py install for mozscreenshot 09:00:47 INFO - Running setup.py install for moztest 09:00:47 INFO - Running setup.py install for mozversion 09:00:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:47 INFO - Cleaning up... 09:00:47 INFO - Return code: 0 09:00:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:47 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:47 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 09:00:47 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 09:00:47 INFO - Using env: {'DISPLAY': ':0', 09:00:47 INFO - 'HOME': '/home/cltbld', 09:00:47 INFO - 'LANG': 'en_US.UTF-8', 09:00:47 INFO - 'LANGUAGE': 'en_US:en', 09:00:47 INFO - 'LOGNAME': 'cltbld', 09:00:47 INFO - 'MAIL': '/var/mail/cltbld', 09:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:47 INFO - 'MOZ_NO_REMOTE': '1', 09:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:47 INFO - 'NO_EM_RESTART': '1', 09:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:47 INFO - 'PWD': '/builds/slave/test', 09:00:47 INFO - 'SHELL': '/bin/bash', 09:00:47 INFO - 'SHLVL': '1', 09:00:47 INFO - 'TERM': 'linux', 09:00:47 INFO - 'TMOUT': '86400', 09:00:47 INFO - 'USER': 'cltbld', 09:00:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:47 INFO - '_': '/tools/buildbot/bin/python'} 09:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:47 INFO - Running setup.py (path:/tmp/pip-zEMUyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:47 INFO - Running setup.py (path:/tmp/pip-KanEfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:47 INFO - Running setup.py (path:/tmp/pip-aNknCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:47 INFO - Running setup.py (path:/tmp/pip-qPB5u5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:47 INFO - Running setup.py (path:/tmp/pip-st5IAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:47 INFO - Running setup.py (path:/tmp/pip-yNiNBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:47 INFO - Running setup.py (path:/tmp/pip-jAfr9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:47 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)) 09:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:48 INFO - Running setup.py (path:/tmp/pip-bFMdqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:48 INFO - Running setup.py (path:/tmp/pip-QKItG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:48 INFO - Running setup.py (path:/tmp/pip-2EZMNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:48 INFO - Running setup.py (path:/tmp/pip-z8HBaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:48 INFO - Running setup.py (path:/tmp/pip-tryUXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:48 INFO - Running setup.py (path:/tmp/pip-fIeEQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:48 INFO - Running setup.py (path:/tmp/pip-Ph8nOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:48 INFO - Running setup.py (path:/tmp/pip-ig7LUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:48 INFO - Running setup.py (path:/tmp/pip-x_SUyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:48 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)) 09:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:48 INFO - Running setup.py (path:/tmp/pip-JhXO5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:48 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)) 09:00:48 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)) 09:00:48 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)) 09:00:48 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)) 09:00:48 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)) 09:00:48 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)) 09:00:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:48 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 09:00:48 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 09:00:48 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 09:00:48 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 09:00:48 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 09:00:48 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 09:00:50 INFO - Downloading blessings-1.6.tar.gz 09:00:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:50 INFO - Installing collected packages: blessings 09:00:50 INFO - Running setup.py install for blessings 09:00:50 INFO - Successfully installed blessings 09:00:50 INFO - Cleaning up... 09:00:50 INFO - Return code: 0 09:00:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:51 INFO - Reading from file tmpfile_stdout 09:00:51 INFO - Current package versions: 09:00:51 INFO - argparse == 1.2.1 09:00:51 INFO - blessings == 1.6 09:00:51 INFO - blobuploader == 1.2.4 09:00:51 INFO - docopt == 0.6.1 09:00:51 INFO - functools32 == 3.2.3-2 09:00:51 INFO - jsonschema == 2.5.1 09:00:51 INFO - manifestparser == 1.1 09:00:51 INFO - mozInstall == 1.12 09:00:51 INFO - mozcrash == 0.17 09:00:51 INFO - mozdebug == 0.1 09:00:51 INFO - mozdevice == 0.48 09:00:51 INFO - mozfile == 1.2 09:00:51 INFO - mozhttpd == 0.7 09:00:51 INFO - mozinfo == 0.9 09:00:51 INFO - mozleak == 0.1 09:00:51 INFO - mozlog == 3.3 09:00:51 INFO - moznetwork == 0.27 09:00:51 INFO - mozprocess == 0.23 09:00:51 INFO - mozprofile == 0.28 09:00:51 INFO - mozrunner == 6.12 09:00:51 INFO - mozscreenshot == 0.1 09:00:51 INFO - mozsystemmonitor == 0.3 09:00:51 INFO - moztest == 0.7 09:00:51 INFO - mozversion == 1.4 09:00:51 INFO - psutil == 3.1.1 09:00:51 INFO - requests == 1.2.3 09:00:51 INFO - wsgiref == 0.1.2 09:00:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:51 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:51 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 09:00:51 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 09:00:51 INFO - Using env: {'DISPLAY': ':0', 09:00:51 INFO - 'HOME': '/home/cltbld', 09:00:51 INFO - 'LANG': 'en_US.UTF-8', 09:00:51 INFO - 'LANGUAGE': 'en_US:en', 09:00:51 INFO - 'LOGNAME': 'cltbld', 09:00:51 INFO - 'MAIL': '/var/mail/cltbld', 09:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:51 INFO - 'MOZ_NO_REMOTE': '1', 09:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:51 INFO - 'NO_EM_RESTART': '1', 09:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:51 INFO - 'PWD': '/builds/slave/test', 09:00:51 INFO - 'SHELL': '/bin/bash', 09:00:51 INFO - 'SHLVL': '1', 09:00:51 INFO - 'TERM': 'linux', 09:00:51 INFO - 'TMOUT': '86400', 09:00:51 INFO - 'USER': 'cltbld', 09:00:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:51 INFO - '_': '/tools/buildbot/bin/python'} 09:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 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)) 09:00:51 INFO - Cleaning up... 09:00:51 INFO - Return code: 0 09:00:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:51 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 0xb88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe49180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, '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': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', '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 09:00:51 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 09:00:51 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 09:00:51 INFO - Using env: {'DISPLAY': ':0', 09:00:51 INFO - 'HOME': '/home/cltbld', 09:00:51 INFO - 'LANG': 'en_US.UTF-8', 09:00:51 INFO - 'LANGUAGE': 'en_US:en', 09:00:51 INFO - 'LOGNAME': 'cltbld', 09:00:51 INFO - 'MAIL': '/var/mail/cltbld', 09:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:51 INFO - 'MOZ_NO_REMOTE': '1', 09:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:51 INFO - 'NO_EM_RESTART': '1', 09:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:51 INFO - 'PWD': '/builds/slave/test', 09:00:51 INFO - 'SHELL': '/bin/bash', 09:00:51 INFO - 'SHLVL': '1', 09:00:51 INFO - 'TERM': 'linux', 09:00:51 INFO - 'TMOUT': '86400', 09:00:51 INFO - 'USER': 'cltbld', 09:00:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:51 INFO - '_': '/tools/buildbot/bin/python'} 09:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:51 INFO - Cleaning up... 09:00:51 INFO - Return code: 0 09:00:51 INFO - Running post-action listener: _resource_record_post_action 09:00:51 INFO - Running post-action listener: _start_resource_monitoring 09:00:51 INFO - Starting resource monitoring. 09:00:51 INFO - [mozharness: 2016-08-16 16:00:51.523121Z] Finished create-virtualenv step (success) 09:00:51 INFO - [mozharness: 2016-08-16 16:00:51.523607Z] Running install step. 09:00:51 INFO - Running pre-action listener: _resource_record_pre_action 09:00:51 INFO - Running main action method: install 09:00:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:51 INFO - Reading from file tmpfile_stdout 09:00:51 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:51 INFO - Reading from file tmpfile_stdout 09:00:51 INFO - Output received: 09:00:51 INFO - Usage: mozinstall [options] installer 09:00:51 INFO - Options: 09:00:51 INFO - -h, --help show this help message and exit 09:00:51 INFO - -d DEST, --destination=DEST 09:00:51 INFO - Directory to install application into. [default: 09:00:51 INFO - "/builds/slave/test"] 09:00:51 INFO - --app=APP Application being installed. [default: firefox] 09:00:51 INFO - mkdir: /builds/slave/test/build/application 09:00:51 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'] 09:00:51 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 09:01:09 INFO - Reading from file tmpfile_stdout 09:01:09 INFO - Output received: 09:01:09 INFO - /builds/slave/test/build/application/firefox/firefox 09:01:09 INFO - Running post-action listener: _resource_record_post_action 09:01:09 INFO - [mozharness: 2016-08-16 16:01:09.172542Z] Finished install step (success) 09:01:09 INFO - [mozharness: 2016-08-16 16:01:09.172820Z] Running run-tests step. 09:01:09 INFO - Running pre-action listener: _resource_record_pre_action 09:01:09 INFO - Running main action method: run_tests 09:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:09 INFO - Python 2.7.3 09:01:09 INFO - Return code: 0 09:01:09 INFO - grabbing minidump binary from tooltool 09:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe473b0>, '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 0xe482b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:01:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:01:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:01:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:09 INFO - Return code: 0 09:01:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:01:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:01:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 09:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 09:01:09 INFO - Using env: {'DISPLAY': ':0', 09:01:09 INFO - 'HOME': '/home/cltbld', 09:01:09 INFO - 'LANG': 'en_US.UTF-8', 09:01:09 INFO - 'LANGUAGE': 'en_US:en', 09:01:09 INFO - 'LOGNAME': 'cltbld', 09:01:09 INFO - 'MAIL': '/var/mail/cltbld', 09:01:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:09 INFO - 'MOZ_NO_REMOTE': '1', 09:01:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:09 INFO - 'NO_EM_RESTART': '1', 09:01:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:09 INFO - 'PWD': '/builds/slave/test', 09:01:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:09 INFO - 'SHELL': '/bin/bash', 09:01:09 INFO - 'SHLVL': '1', 09:01:09 INFO - 'TERM': 'linux', 09:01:09 INFO - 'TMOUT': '86400', 09:01:09 INFO - 'USER': 'cltbld', 09:01:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688', 09:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:09 INFO - '_': '/tools/buildbot/bin/python'} 09:01:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 09:01:09 INFO - mozversion application_buildid: 20160816071917 09:01:09 INFO - mozversion application_changeset: 8b47af8a1ad296295a0f9bfb61d6280265a5f109 09:01:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:01:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:09 INFO - mozversion application_name: Firefox 09:01:09 INFO - mozversion application_remotingname: firefox-dev 09:01:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:09 INFO - mozversion application_vendor: Mozilla 09:01:09 INFO - mozversion application_version: 50.0a2 09:01:09 INFO - mozversion platform_buildid: 20160816071917 09:01:09 INFO - mozversion platform_changeset: 8b47af8a1ad296295a0f9bfb61d6280265a5f109 09:01:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:09 INFO - mozversion platform_version: 50.0a2 09:01:09 INFO - using testdate: 1471363269 09:01:09 INFO - actual date: 1471363269 09:01:09 INFO - starting webserver on 'localhost:35573' 09:01:09 INFO - SUITE-START | Running 2 tests 09:01:09 INFO - TEST-START | dromaeo_css 09:01:09 INFO - operating with platform_type : linux_ 09:01:09 INFO - Initialising browser for dromaeo_css test... 09:01:09 INFO - TEST-INFO | started process 6710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpieTSUz/profile http://localhost:35573/getInfo.html) 09:01:14 INFO - PROCESS | 6710 | __metrics Screen width/height:1600/1200 09:01:14 INFO - PROCESS | 6710 | colorDepth:24 09:01:14 INFO - PROCESS | 6710 | Browser inner width/height: 1024/697 09:01:14 INFO - PROCESS | 6710 | __metrics 09:01:14 INFO - PROCESS | 6710 | 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" 09:01:14 INFO - PROCESS | 6710 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:15 INFO - PROCESS | 6710 | console.error: PushService: 09:01:15 INFO - PROCESS | 6710 | stateChangeProcessEnqueue: Error transitioning state 09:01:15 INFO - PROCESS | 6710 | UnknownError 09:01:15 INFO - TEST-INFO | 6710: exit 0 09:01:15 INFO - Browser initialized. 09:01:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:15 INFO - TEST-INFO | started process 6799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpieTSUz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:27 INFO - PROCESS | 6799 | RSS: Main: 145145856 09:01:27 INFO - PROCESS | 6799 | 09:02:19 INFO - PROCESS | 6799 | 1471363339334 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] 09:04:27 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35573/tests/dromaeo/cssquery-ext.html) 09:04:28 INFO - PROCESS | 6799 | RSS: Main: 225406976 09:04:28 INFO - PROCESS | 6799 | 09:07:30 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-ext.html (next: http://localhost:35573/tests/dromaeo/cssquery-jquery.html) 09:07:31 INFO - PROCESS | 6799 | RSS: Main: 232669184 09:07:31 INFO - PROCESS | 6799 | 09:10:33 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35573/tests/dromaeo/cssquery-mootools.html) 09:10:33 INFO - PROCESS | 6799 | RSS: Main: 211222528 09:10:33 INFO - PROCESS | 6799 | 09:13:35 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35573/tests/dromaeo/cssquery-prototype.html) 09:13:36 INFO - PROCESS | 6799 | RSS: Main: 175374336 09:13:36 INFO - PROCESS | 6799 | 09:16:39 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35573/tests/dromaeo/cssquery-yui.html) 09:16:39 INFO - PROCESS | 6799 | RSS: Main: 175345664 09:16:39 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | Cycle 1(1): loaded http://localhost:35573/tests/dromaeo/cssquery-yui.html (next: http://localhost:35573/tests/dromaeo/cssquery-dojo.html) 09:19:42 INFO - PROCESS | 6799 | RSS: Main: 264269824 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | __start_tp_report 09:19:42 INFO - PROCESS | 6799 | _x_x_mozilla_page_load 09:19:42 INFO - PROCESS | 6799 | _x_x_mozilla_page_load_details 09:19:42 INFO - PROCESS | 6799 | |i|pagename|runs| 09:19:42 INFO - PROCESS | 6799 | |0;dojo.html;3209.903702888913;3229.031290612817;3238.174265562282;3254.1213872254507;3270.6892845179714;5491.093969495034;5604.635698679588;5610.551155907528;5626.718664066791;5641.09742441209;5554.472325129116;5598.1322894951345;5612.803551875685;5629.71851407429;5637.661740295575;4962.6526143169995;5005.374328208974;5055.51972562606;5105.591552675795;5119.462456442076;4906.828261010865;5047.041062198171;5075.873103172414;5100.183970564711;5106.08090543702;4970.502949705036;5006.249062640597;5019.799208031674;5034.370703662043;5047.293378926953;5168;5175.430702622709;5186.92219616706;5199.168133098724;5207.687538747668;4894.853154405374;4928.1129396708575;4938.38270216223;5098.388193416777;5133.743312834344;5483.588730845203;5515.145152501369;5519.613627046108;5564.944350556483;5580.609357344987;6051.394860513958;6059.581576644657;6098.603590766586;6098.69506524672;6103;5893.675847828367;5935.287765468128;5943.078822782432;6058.242719660043;6084.996459312026;5588.832335029955;5624.3531993820825;5630.268065151545;5631.2397826293745;5644.85887852807;5835.854103647358;6120.326764055951;6121.510279177655;6128.453176423585;6128.479079278222;5858.707064646751;5871.765129394862;5894.086416605433;5953.970230148821;5983.461488465972;6040.546958978094;6053.4854537363935;6053.848653783691;6059.454649081604;6101;5771.278590176228;6021.7591296347655;6026.246719160105;6033.306169790442;6054.152418661391;6000.849978750566;6044.969775151096;6058.939410605837;6079.148919151322;6084.391560843968;4588.655850811202;4607.838725644587;4608.838690645811;4625.32932724753;4636.281376386665;4025.9597404025585;4110.321796903538;4137.40007698882;4158.979205104016;4176.77027763476;5567.47109024642;5611.80358687444;5614.07367784311;5675.971620141955;5694.1458781183155;5955.493783028404;5969.522438204933;5969.611975221596;5991.191189189491;6011.368806275365;5514.172874068921;5641.661500309994;5642.605017648725;5646.265985421869;5652.660840349592;5047.4447810740785;5073.264376665363;5092.541671249605;5136.43499215086;5156.845294641167;6104.908426373519;6122;6130.049842274544;6131.049687298387;6131.202943617391;5781.710914454345;5925.674087925033;5935.5548333875995;5938.613990090803;5940.376260492586;5494.2857428534035;6085.056816193497;6129.328227221949;6221.191245138103;6222.657753823402;5918.526517878473;5923.644581325134;5923.703814809329;5927.585069045125;5927.881442371215;5781;5980.401959803985;6036.396360363929;6036.577439579188;6043.486303664238;5789.189513468202;5799.8550036249435;5861.472467477947;5916.822495325147;5949.0481522956115;5846.619969701956;5896.174535564938;5898.882022359443;5900.793472228452;5903.409659034062;5627.409122042127;5874.970625146847;5880.14737863016;5880.9708301048;5881.5000724937145;5832.12518122285;5888.587798854039;5912.49743771767;5931.822045338647;6011.639301641915;5821.592488525763;5834.212381328466;5834.649921004753;5834.737436815439;5836.387179346108 09:19:42 INFO - PROCESS | 6799 | |1;ext.html;620.0451304990315;626.1546911669247;642.1395330257454;665.2084020016177;665.2615596687679;34893.82553087234;38701;46008.53991460084;46799.532004679946;46883.765581172134;45615.771921140346;46057.7697111514;46079.53920460794;46114.53885461144;46121.538784612145;9081.27349812015;9223.400478968879;9229.676961306355;9236.6305347786;9240.907590924106;8514.48913065215;10707.78584428311;10996.945015274912;10997.560097596086;11324;11180.385078820702;11271.605493807718;11284.717882052932;11289.830652540219;11291.983721465067;11126;11161.218714689974;11190.272632278877;11194.66416007521;11205.271657342288;10931.56273749049;10988.560457581687;10999.780004399947;11004.669859904216;11006.559737610485;45857.312140317925;46129.53870461286;46246.306305405444;46305.76847115777;46331.53668463306;3863.130795571002;3875.968992248052;3981.5023122109737;4070.5318888327924;4632.5830675239395;33896.661033389595;34105.48841767351;34760.826195869115;34884.302313953835;36773.81613091944;4369.344598310247;4439.733615983051;4446.7109637873755;4490.550944905516;4529.932051019237;360975;361026;361136;363296;364042.179789102;34580.48129278063;63152.684236579444;63631.68184159049;63643;63768;35359.11602209965;38640;38683.61316386856;38697;38727;17601.647967040586;17813;17861.464156075337;17869.642607148045;17879.10604469797;13875.098118622258;14725.337359818619;14824.851751482347;14827.184504852337;14968.101913885203;9034.503102329303;9186.40288381253;9323.953380233055;9356.4386136832;9359.906400936039;3465.203003309253;3542.4686297055646;3573.356795776733;3580.230250496156;3604.062943634621;1075.2795626929976;1082.783443311341;1085.777415629794;1098.2038022433671;1144.6648836373704;34940.65059349425;35444.64555354465;35804.64195358065;35814.28371432557;35898.82050589783;1195.9701007474882;1207.1489599832098;1223.0521345956954;1283.6534135783286;1287.2212311551377;18730;18750.812491874905;18826;18858.71711924322;18893.338733144275;579120;582281;583250;584727;585381;64350.35649643537;64862.67568662127;64935.35064649293;64978.35021649818;64987.67506162439;1355.6949686320697;1378.9241591712353;1398.6503374156462;1487.3976039703937;1496.177102593569;28574.571381429712;37969.810150949066;38182.23635527329;38269;39123.804380979054;59226.407735923814;59893.401065990525;59973;60021.69989150201;60229.39770602238;484300;484761;485248;486553;487235;30421.543676845307;31269.374612507167;40608;45634.54365456303;47074.29388559106;50669;51417.742911285204;51854.74072629613;52018.73990630022;52964;44995.32507012332;46103.769481152376;46104;46150.769246153555;47589.76205118952;45997.310040348755;46735;46805;47229.74932256113;47433.52566474291;45539;46111.76944115258;46481;46491.76754116208;46761.29858052064;37500.62499374971;41700.58299416967;43830;44011.33982990322;44574;30247.848760756056;30767.84616076905;31023.53464697986;31182.37635247328;31426.528602071445 09:19:42 INFO - PROCESS | 6799 | |2;jquery.html;402.15748007923406;408.38334115485605;408.72615347717027;409.32052792364675;427.6429181633336;19541.41375758726;21109.57780844382;21384.46538836528;22014.559708805813;22432.32703018908;19958.800411995875;20572.588548229025;20828.895855520703;21019.2643257486;21176.682349764727;5718.628289161202;5942.019566771499;5946.851328716773;5947.464728174464;5951.970240148793;5660.60375773696;5820.010598198316;5826.271716035496;5837.9124313135335;5845.503132233744;5806.071028635419;5806.390329015456;5816.185733997244;5816.563757718167;5817.941820581804;5605.971970140144;5782.826515204551;5816.1275808628625;5834.766609335643;5838.795642152547;5143.717095559743;5214.139666954948;5216.191490319006;5218.034663587248;5220;20619.896900515552;20737.688934665992;20952.5809483811;22546.549069018693;23889.761102388926;2500.0749722602573;2513.3967847716417;2536.543422184006;2555.0674003988597;2556.693196816394;14987.100773953489;15019.624509387244;15199.01206421579;15841.52475425739;16230.026198428131;2009.1963214714085;2024.483756642047;2058.4030631116957;2070.1926248762993;2075.107703687414;81125.18874811236;81831.590842046;82718.17281827165;84497;84725;10616.681499555025;10732.892671073188;11135.610253641084;11488.425578721031;11605.593804216813;14667.85332146665;14804.407823687146;14811.333489992976;14816.185109818847;14824;8735.301175905912;8736.475811451333;8887;8924.8661270081;9040.367174297737;9133.269338452908;9188.724338269863;9214.76963075915;9285.164335209729;9350.532473376305;6550.639714815731;6657.367550082734;6691.1636045494315;6746.190457145174;6829.146356705412;1928.9517762056062;1949.8635095543175;1970.300543307134;1978.7031945208046;2031.492126968258;991.6281394477071;1045.4981608827804;1101.7410908436511;1125.189863298424;1181.078758568318;18994.81005189958;19935.700964485546;21598.704077755385;21630.35108946734;21858.672119917897;1066.184368957748;1089.5478376473834;1124.3085502415956;1128.6219116595869;1138.8177891537314;10191.796164076677;11789.587364442203;11857.822132668018;11860.288382697066;12031.879681203076;520304;528209;535781;545402;557924;76377;80356.59821700853;95821;106697;107425.4628726867;1064.387976913278;1075.1560025380038;1108.3626914524182;1109.7114750165015;1111.2943281016612;18638.81361186371;18942.621147576694;19115.713264300768;19168.808311916702;19268;46092;46159.76920115378;46284.30573541332;46687.53312466832;47518.28722569234;65457.672711636136;65800;67010;67805.66097169483;68009.65995169993;8035.718749843727;8039.718609848627;8107.14874938123;8329.916700832913;8355.498670079814;10685.732856678334;12813.93593032029;12988.870111298766;13177.077604567972;13323.733525329633;11161.330320180816;11513.021393181674;11664.825027624423;11698.70753231176;11970.70073248141;10925.781484370109;11130.499127539442;11194.66416007521;11236.66290011301;11629.53481860716;11459.942700286445;11583.305001699924;11659.417029148679;12002.39988000579;12028.398580070787;8367.079621241537;8440.15598440151;8556.957215213884;8557.786055348666;8733.388662793543;9842.901570984199;11289.20975531705;11619.186656933933;11854.170208085352;11870.881291187323 09:19:42 INFO - PROCESS | 6799 | |3;mootools.html;1512.546236129161;1574.9685006299874;1740.6170642458662;1837.705967045273;1879.4455635587508;9013.14375134362;9621.759456013595;9652.083052110038;9660;9663.758406039853;7788;8888;9197.12627300407;9257.120573545519;9399.34204605678;2342.273895092518;2371.27676058802;2376.7979721723673;2376.7979721723673;2380.33350661815;2332.8017118544944;2335.2177020698086;2364.9408764780933;2367.0768400323886;2368.111958015744;2367.3016460144213;2378.9286321410386;2388.52229554089;2400.23192578375;2401.1835975768236;2261.4007288068674;2277.1802151225547;2279.509905370345;2285.31440567829;2309.6650985606993;2264.8301377396597;2266.138867230442;2268.1834539565743;2269.1944359752215;2273.2043784675398;9567.808643827084;9638.566264518113;9657.758556036086;9680.370775899544;9696.272779541492;5396.19057141428;5546.500814926636;5555.2222688823595;5555.472230138131;5563.193336966157;3281.392942305681;3372.4098282800655;3504;3518.7712798668003;3534.2048039190977;1133.1274918312936;1156.468024708635;1193.9820902686467;1223.2904915149193;1226.907981901352;13510.189388636712;13555.390007449689;13558.796618050737;13563.321833908363;13572.93213533926;4508.774561271924;4613.3771940787565;4737.928931066037;4747.169245382044;4802.6638135330195;10839.078678312275;10932.180086493545;10938.124950003981;11022.118230541537;11031.613893513688;5573.331200255914;5576.414476479992;5582.469665381782;5585.58108141891;5587.664740115606;4364.148990946735;4936.9259461107395;4973.651844370932;5012.04771093491;5062.240663900445;4080.065327707878;4127.442795222667;4159.376093585986;4194.993201631647;4199.68502362324;1311.90816642836;1339.1563315111418;1340.825692659948;1364.9590512284647;1368.835739711241;972.2610815780058;1024.939187940478;1043.2332235806675;1066.2269854355686;1075.5697720911699;6422.325655806073;6489.2212934447225;6601.3728695773825;6649.468042556584;6811.523193376416;450.8940399006231;456.01955795040925;456.8812108851656;483.0242909323218;484.04642853578395;4468.620167285752;4476.731396116244;4589.58693717567;4607.746573938399;4717.103750287503;11555.76888462226;12032.939835300767;12037.217580857216;12038.097142714332;12071.939640301742;10688.51901664427;11119.94440027811;11307.49116289769;11662;11970.042396608425;8758.912410875937;8796.692115775917;8972.775680607907;8981.05698901619;8991;6524.6411447368955;6680.565763225375;6717.261101278953;6739.258681544928;6765.695543700644;10396.792064158826;10572.524236409534;10617;10682.73293167677;10683.358998459806;10869.347839129678;10885.238033337908;10888.727781805519;11026.779464410507;11059.557617695524;7452.813679658052;7793.337566306701;7849.8037549059445;8611.440256383315;8747.825043499222;9497.43015419077;9574.186194173573;9585.993470685478;9587.712368628952;9589.904100958902;8076.030876294883;8283.461574997606;8311.16888311164;8523.531205783742;8622.525761083201;8142.796430089058;8179.83640327202;8197.918020819954;8291.046529649167;8322.16778322163;7657.310842024244;7678.8080297993;7682.078150621961;7723.459357844897;7746.651400687096;5532.087205611029;5777.364489906191;5778.768849245983;5847.7368518417625;6078.756849726138;5610.158476228598;5611.831645050655;5758.568107391879;5803.970980145073;5830.591858569859 09:19:42 INFO - PROCESS | 6799 | |4;prototype.html;331.810867805351;334.05462541008944;339.70785124792684;341.5355117040826;341.7812599936041;5532.087205611075;5538.701438902851;5575.275214222151;5593.916091258634;5601.327840659116;5505.769518956987;5525.557955363572;5549.445055494459;5561.193626924093;5564.304461942257;4025.6779457643465;4149.502059752826;4174.269502837006;4199.2651286024975;4267.594578515036;4256.87229383119;4259.531451540328;4270.316749320109;4309.806058727365;4314.288142456507;4233.772206060255;4243.681723870706;4246.511651160125;4258.701890867641;4308.375285583604;4130.896727581804;4227.957720422787;4229.429027081351;4253.064325848293;4339.1972485090355;3800.327855743464;3803.182315802088;3806.219724956405;3810.066533699234;3871.341871881787;5245.763940622681;5327.46725327468;5339.279197308352;5378.112611419111;5387.595930305243;4946.5795407390415;4959.231319145538;4960.305557221992;4961.131801934647;4978.6763860348965;5714.771409143629;5756.079027355645;5765;5768.596198266123;5776.768929242825;3998.980005099985;4073.531543872463;4089.591040895916;4090.8159132839096;4096.754194748295;5737.971310143423;5872.678647304322;5971.193888824996;5997.460228579405;6004.159417681528;4267.295896177096;4270.145970805851;4274.166537525215;4280.315149576053;4290.957090429056;4038.4749982502085;4198.265303571863;4214.452121224283;4214.473190851144;4253.106847561985;4808.663393562487;4817.253325734517;4869.732164730974;4887.608991280689;4893.926591101136;5753.021986262345;5761.481466667936;5767.134929760486;5767.394423585463;5794.1308803679785;1381.8272715910512;1434.784782282666;1442.7619442791822;1446.4792674637122;1451.5774540949928;907.088376181933;917.9816403672023;938.9483578403253;942.2697409507549;949.1647350331802;4928.581070609039;4956.801727930842;4994.675346102492;5016.924746128811;5018;5155.9742201288755;5192.662476938987;5198.532132108128;5200.245964335226;5205.219217117463;4774.522547745198;4893.388326459192;4948.752562371867;4979.427365852901;4980.05378977993;2778.527650299454;2778.7915906307107;2810.7329803669027;2825.6891741908375;2839.7586205172793;5439.020976224238;5466.671999680032;5468.234447177399;5478.534324582375;5479.315085614298;4947.604191664658;5228.215767634809;5247.9766445542755;5272.446393128743;5297.410776767031;5274.736263186902;5344.706041167773;5350.277712508761;5435.510804027656;5456.372517160418;5157.587393008475;5232.3982741985155;5609.691466969356;5685.573581981285;5942.316633587193;5865.387018569757;5886.911696324473;5898.439648233411;5900.468957793819;5916.171735957056;5860.860785813063;6193.81418557444;6205.720742566505;6229.781957631461;6238.968805155945;4200.579942005775;4235.512916014698;4247.702660813837;4292.484901811803;4292.849750258726;6067.332593414632;6067.575269731076;6087.66517841523;6093.105774756955;6093.969530152498;5714.342850572238;5852.356240813422;5869.970650146722;5936.28764548257;5947.732352044255;5795.768169273182;5861.35525092231;5869.4717475427415;5870.207521984648;5877.177195192762;5785.132230165341;5824.5631577631;5827.650340979555;5871.7651293947765;5897.970510147422;5590.357108932362;5781.768729250783;5817.738201781015;5825.388334225015;5826.329972053268;5722.599418040697;5727.226824378656;5729.3984131665575;5744.195812586325;5814.94185058144 09:19:42 INFO - PROCESS | 6799 | |5;yui.html;815.388458656008;820.5774026376417;845.9873101903472;873.0614589316486;893.8972018217903;16334.34662613494;17484.3006279749;17583.1208439578;17638.735418968692;17657.20542575584;16372.918135409307;16508.66982660349;16542.255598498068;16550.7517387239;16552.58618534537;734.0897287363671;870.7344260000697;872.1801506583822;876.6318146378536;880.8546589812673;896.7847716548014;899.5951821680237;900.7522931193942;901.1124042817819;901.197933838884;937.8968313485511;944.211583327922;944.3814301632442;946.4983558713858;947.0340252941995;779.664744160011;909.2544113826665;910.2308549275917;924.7780532672177;945.5981207986613;682.8804959132182;690.5373399822081;692.0138802206835;693.4868197533839;693.9271376505445;15460.226988650635;15490.225488725631;15504;15508.45720399792;15512.224388780516;15354.309056092503;15538.378464861393;15697.607559810982;15926.601834954103;16161.353545858152;12350.320732359716;12547.749045019049;12675.366231688471;12777.680557986032;12828.294443805587;4566.680332376735;4566.70316429431;4598.770061496912;4620.168369693454;4629.259318509023;80869.59565202195;80992;81038;81113.18886811116;85560.14439855647;12132.393380330948;12422.565210217599;12432.378381081091;12573.24560526353;12675.93662031684;20147.496312592302;20177.899110504353;20265.493362665755;20292.89853550723;20314.390568282975;9043.864342034874;9120.954395228115;9145.90854091464;9204.217641500414;9471.10024547667;4761.047790441926;4895.290182923456;4914.434839993375;4914.459409464956;5226.973865130726;3696.611855755107;3834.386498160337;3838.1747924196434;3838.3858582627204;3838.5201849768778;631.9146915166485;643.4594940250213;644.5616980453336;648.0182523476936;653.8169312592481;597.9073243647242;608.2093278737624;651.5797310734549;696.5333226737996;714.5034201230095;9827.754306142404;10020;10174.186065114773;10350.896491035144;10599.788004239872;666.2438132719302;670.1622971285892;683.3542302524068;692.3561088187932;697.5117417807555;4766.761661916959;4941.308216849644;4990.975045124751;5000.474950130257;5013.448520662724;123861.38069309776;128273.35863320624;132910;135208;135559;64020.67989660022;64142.358576414576;104109.47945260225;112832.43583782029;114572.42713786378;58806.41193588095;60588;85006;100500.99499005063;103071;9489.620415183315;9765.75585610337;9778.75553111178;9802.509874506104;9898.604055837686;42395;56077.439225607224;56902.715486422305;57683.42316576948;59190.40809592021;47807;66993;67199;73064;80490.59754701189;13544.59366219015;13635.795463067863;13860.930695346458;14566.927165364104;14842.406303748161;15917.68164636724;16102;16766;17060.488185354458;17732.202050908;15132.546023619308;15393.30730117125;16054.197290135213;16729.91635041817;16746.162691865113;14268.928655357073;14847.480338188112;15013.6997260052;15152.31814568325;15861.206939653202;14745.041572297767;15188.924055379652;15919;16148.515544533682;16153.111578863276;12233.571824986082;12397.132200745862;12631.81052284198;13070.54253101137;13515;10460.110890574386;10977.114427860697;11009.889901100887;12759.808602870778;12884.806727898902 09:19:42 INFO - PROCESS | 6799 | __end_tp_report 09:19:42 INFO - PROCESS | 6799 | __start_cc_report 09:19:42 INFO - PROCESS | 6799 | _x_x_mozilla_cycle_collect,1052 09:19:42 INFO - PROCESS | 6799 | __end_cc_report 09:19:42 INFO - PROCESS | 6799 | __startTimestamp1471364382407__endTimestamp 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | ------- Summary: start ------- 09:19:42 INFO - PROCESS | 6799 | Number of tests: 6 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#0] dojo.html Cycles:165 Average:5557.40 Median:5821.59 stddev:632.93 (10.9%) stddev-sans-first:607.49 09:19:42 INFO - PROCESS | 6799 | Values: 3209.9 3229.0 3238.2 3254.1 3270.7 5491.1 5604.6 5610.6 5626.7 5641.1 5554.5 5598.1 5612.8 5629.7 5637.7 4962.7 5005.4 5055.5 5105.6 5119.5 4906.8 5047.0 5075.9 5100.2 5106.1 4970.5 5006.2 5019.8 5034.4 5047.3 5168.0 5175.4 5186.9 5199.2 5207.7 4894.9 4928.1 4938.4 5098.4 5133.7 5483.6 5515.1 5519.6 5564.9 5580.6 6051.4 6059.6 6098.6 6098.7 6103.0 5893.7 5935.3 5943.1 6058.2 6085.0 5588.8 5624.4 5630.3 5631.2 5644.9 5835.9 6120.3 6121.5 6128.5 6128.5 5858.7 5871.8 5894.1 5954.0 5983.5 6040.5 6053.5 6053.8 6059.5 6101.0 5771.3 6021.8 6026.2 6033.3 6054.2 6000.8 6045.0 6058.9 6079.1 6084.4 4588.7 4607.8 4608.8 4625.3 4636.3 4026.0 4110.3 4137.4 4159.0 4176.8 5567.5 5611.8 5614.1 5676.0 5694.1 5955.5 5969.5 5969.6 5991.2 6011.4 5514.2 5641.7 5642.6 5646.3 5652.7 5047.4 5073.3 5092.5 5136.4 5156.8 6104.9 6122.0 6130.0 6131.0 6131.2 5781.7 5925.7 5935.6 5938.6 5940.4 5494.3 6085.1 6129.3 6221.2 6222.7 5918.5 5923.6 5923.7 5927.6 5927.9 5781.0 5980.4 6036.4 6036.6 6043.5 5789.2 5799.9 5861.5 5916.8 5949.0 5846.6 5896.2 5898.9 5900.8 5903.4 5627.4 5875.0 5880.1 5881.0 5881.5 5832.1 5888.6 5912.5 5931.8 6011.6 5821.6 5834.2 5834.6 5834.7 5836.4 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#1] ext.html Cycles:180 Average:64620.65 Median:21499.89 stddev:128826.17 (599.2%) stddev-sans-first:129097.94 09:19:42 INFO - PROCESS | 6799 | Values: 620.0 626.2 642.1 665.2 665.3 34893.8 38701.0 46008.5 46799.5 46883.8 45615.8 46057.8 46079.5 46114.5 46121.5 9081.3 9223.4 9229.7 9236.6 9240.9 8514.5 10707.8 10996.9 10997.6 11324.0 11180.4 11271.6 11284.7 11289.8 11292.0 11126.0 11161.2 11190.3 11194.7 11205.3 10931.6 10988.6 10999.8 11004.7 11006.6 45857.3 46129.5 46246.3 46305.8 46331.5 3863.1 3876.0 3981.5 4070.5 4632.6 33896.7 34105.5 34760.8 34884.3 36773.8 4369.3 4439.7 4446.7 4490.6 4529.9 360975.0 361026.0 361136.0 363296.0 364042.2 34580.5 63152.7 63631.7 63643.0 63768.0 35359.1 38640.0 38683.6 38697.0 38727.0 17601.6 17813.0 17861.5 17869.6 17879.1 13875.1 14725.3 14824.9 14827.2 14968.1 9034.5 9186.4 9324.0 9356.4 9359.9 3465.2 3542.5 3573.4 3580.2 3604.1 1075.3 1082.8 1085.8 1098.2 1144.7 34940.7 35444.6 35804.6 35814.3 35898.8 1196.0 1207.1 1223.1 1283.7 1287.2 18730.0 18750.8 18826.0 18858.7 18893.3 579120.0 582281.0 583250.0 584727.0 585381.0 64350.4 64862.7 64935.4 64978.4 64987.7 1355.7 1378.9 1398.7 1487.4 1496.2 28574.6 37969.8 38182.2 38269.0 39123.8 59226.4 59893.4 59973.0 60021.7 60229.4 484300.0 484761.0 485248.0 486553.0 487235.0 30421.5 31269.4 40608.0 45634.5 47074.3 50669.0 51417.7 51854.7 52018.7 52964.0 44995.3 46103.8 46104.0 46150.8 47589.8 45997.3 46735.0 46805.0 47229.7 47433.5 45539.0 46111.8 46481.0 46491.8 46761.3 37500.6 41700.6 43830.0 44011.3 44574.0 30247.8 30767.8 31023.5 31182.4 31426.5 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#2] jquery.html Cycles:180 Average:31177.86 Median:21936.62 stddev:88531.77 (403.6%) stddev-sans-first:88749.96 09:19:42 INFO - PROCESS | 6799 | Values: 402.2 408.4 408.7 409.3 427.6 19541.4 21109.6 21384.5 22014.6 22432.3 19958.8 20572.6 20828.9 21019.3 21176.7 5718.6 5942.0 5946.9 5947.5 5952.0 5660.6 5820.0 5826.3 5837.9 5845.5 5806.1 5806.4 5816.2 5816.6 5817.9 5606.0 5782.8 5816.1 5834.8 5838.8 5143.7 5214.1 5216.2 5218.0 5220.0 20619.9 20737.7 20952.6 22546.5 23889.8 2500.1 2513.4 2536.5 2555.1 2556.7 14987.1 15019.6 15199.0 15841.5 16230.0 2009.2 2024.5 2058.4 2070.2 2075.1 81125.2 81831.6 82718.2 84497.0 84725.0 10616.7 10732.9 11135.6 11488.4 11605.6 14667.9 14804.4 14811.3 14816.2 14824.0 8735.3 8736.5 8887.0 8924.9 9040.4 9133.3 9188.7 9214.8 9285.2 9350.5 6550.6 6657.4 6691.2 6746.2 6829.1 1929.0 1949.9 1970.3 1978.7 2031.5 991.6 1045.5 1101.7 1125.2 1181.1 18994.8 19935.7 21598.7 21630.4 21858.7 1066.2 1089.5 1124.3 1128.6 1138.8 10191.8 11789.6 11857.8 11860.3 12031.9 520304.0 528209.0 535781.0 545402.0 557924.0 76377.0 80356.6 95821.0 106697.0 107425.5 1064.4 1075.2 1108.4 1109.7 1111.3 18638.8 18942.6 19115.7 19168.8 19268.0 46092.0 46159.8 46284.3 46687.5 47518.3 65457.7 65800.0 67010.0 67805.7 68009.7 8035.7 8039.7 8107.1 8329.9 8355.5 10685.7 12813.9 12988.9 13177.1 13323.7 11161.3 11513.0 11664.8 11698.7 11970.7 10925.8 11130.5 11194.7 11236.7 11629.5 11459.9 11583.3 11659.4 12002.4 12028.4 8367.1 8440.2 8557.0 8557.8 8733.4 9842.9 11289.2 11619.2 11854.2 11870.9 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#3] mootools.html Cycles:180 Average:6202.61 Median:2523.23 stddev:3607.84 (143.0%) stddev-sans-first:3600.75 09:19:42 INFO - PROCESS | 6799 | Values: 1512.5 1575.0 1740.6 1837.7 1879.4 9013.1 9621.8 9652.1 9660.0 9663.8 7788.0 8888.0 9197.1 9257.1 9399.3 2342.3 2371.3 2376.8 2376.8 2380.3 2332.8 2335.2 2364.9 2367.1 2368.1 2367.3 2378.9 2388.5 2400.2 2401.2 2261.4 2277.2 2279.5 2285.3 2309.7 2264.8 2266.1 2268.2 2269.2 2273.2 9567.8 9638.6 9657.8 9680.4 9696.3 5396.2 5546.5 5555.2 5555.5 5563.2 3281.4 3372.4 3504.0 3518.8 3534.2 1133.1 1156.5 1194.0 1223.3 1226.9 13510.2 13555.4 13558.8 13563.3 13572.9 4508.8 4613.4 4737.9 4747.2 4802.7 10839.1 10932.2 10938.1 11022.1 11031.6 5573.3 5576.4 5582.5 5585.6 5587.7 4364.1 4936.9 4973.7 5012.0 5062.2 4080.1 4127.4 4159.4 4195.0 4199.7 1311.9 1339.2 1340.8 1365.0 1368.8 972.3 1024.9 1043.2 1066.2 1075.6 6422.3 6489.2 6601.4 6649.5 6811.5 450.9 456.0 456.9 483.0 484.0 4468.6 4476.7 4589.6 4607.7 4717.1 11555.8 12032.9 12037.2 12038.1 12071.9 10688.5 11119.9 11307.5 11662.0 11970.0 8758.9 8796.7 8972.8 8981.1 8991.0 6524.6 6680.6 6717.3 6739.3 6765.7 10396.8 10572.5 10617.0 10682.7 10683.4 10869.3 10885.2 10888.7 11026.8 11059.6 7452.8 7793.3 7849.8 8611.4 8747.8 9497.4 9574.2 9586.0 9587.7 9589.9 8076.0 8283.5 8311.2 8523.5 8622.5 8142.8 8179.8 8197.9 8291.0 8322.2 7657.3 7678.8 7682.1 7723.5 7746.7 5532.1 5777.4 5778.8 5847.7 6078.8 5610.2 5611.8 5758.6 5804.0 5830.6 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#4] prototype.html Cycles:180 Average:4738.94 Median:5260.21 stddev:1398.51 (26.6%) stddev-sans-first:1362.76 09:19:42 INFO - PROCESS | 6799 | Values: 331.8 334.1 339.7 341.5 341.8 5532.1 5538.7 5575.3 5593.9 5601.3 5505.8 5525.6 5549.4 5561.2 5564.3 4025.7 4149.5 4174.3 4199.3 4267.6 4256.9 4259.5 4270.3 4309.8 4314.3 4233.8 4243.7 4246.5 4258.7 4308.4 4130.9 4228.0 4229.4 4253.1 4339.2 3800.3 3803.2 3806.2 3810.1 3871.3 5245.8 5327.5 5339.3 5378.1 5387.6 4946.6 4959.2 4960.3 4961.1 4978.7 5714.8 5756.1 5765.0 5768.6 5776.8 3999.0 4073.5 4089.6 4090.8 4096.8 5738.0 5872.7 5971.2 5997.5 6004.2 4267.3 4270.1 4274.2 4280.3 4291.0 4038.5 4198.3 4214.5 4214.5 4253.1 4808.7 4817.3 4869.7 4887.6 4893.9 5753.0 5761.5 5767.1 5767.4 5794.1 1381.8 1434.8 1442.8 1446.5 1451.6 907.1 918.0 938.9 942.3 949.2 4928.6 4956.8 4994.7 5016.9 5018.0 5156.0 5192.7 5198.5 5200.2 5205.2 4774.5 4893.4 4948.8 4979.4 4980.1 2778.5 2778.8 2810.7 2825.7 2839.8 5439.0 5466.7 5468.2 5478.5 5479.3 4947.6 5228.2 5248.0 5272.4 5297.4 5274.7 5344.7 5350.3 5435.5 5456.4 5157.6 5232.4 5609.7 5685.6 5942.3 5865.4 5886.9 5898.4 5900.5 5916.2 5860.9 6193.8 6205.7 6229.8 6239.0 4200.6 4235.5 4247.7 4292.5 4292.8 6067.3 6067.6 6087.7 6093.1 6094.0 5714.3 5852.4 5870.0 5936.3 5947.7 5795.8 5861.4 5869.5 5870.2 5877.2 5785.1 5824.6 5827.7 5871.8 5898.0 5590.4 5781.8 5817.7 5825.4 5826.3 5722.6 5727.2 5729.4 5744.2 5814.9 09:19:42 INFO - PROCESS | 6799 | 09:19:42 INFO - PROCESS | 6799 | [#5] yui.html Cycles:180 Average:21526.80 Median:4566.69 stddev:31061.53 (680.2%) stddev-sans-first:31109.73 09:19:42 INFO - PROCESS | 6799 | Values: 815.4 820.6 846.0 873.1 893.9 16334.3 17484.3 17583.1 17638.7 17657.2 16372.9 16508.7 16542.3 16550.8 16552.6 734.1 870.7 872.2 876.6 880.9 896.8 899.6 900.8 901.1 901.2 937.9 944.2 944.4 946.5 947.0 779.7 909.3 910.2 924.8 945.6 682.9 690.5 692.0 693.5 693.9 15460.2 15490.2 15504.0 15508.5 15512.2 15354.3 15538.4 15697.6 15926.6 16161.4 12350.3 12547.7 12675.4 12777.7 12828.3 4566.7 4566.7 4598.8 4620.2 4629.3 80869.6 80992.0 81038.0 81113.2 85560.1 12132.4 12422.6 12432.4 12573.2 12675.9 20147.5 20177.9 20265.5 20292.9 20314.4 9043.9 9121.0 9145.9 9204.2 9471.1 4761.0 4895.3 4914.4 4914.5 5227.0 3696.6 3834.4 3838.2 3838.4 3838.5 631.9 643.5 644.6 648.0 653.8 597.9 608.2 651.6 696.5 714.5 9827.8 10020.0 10174.2 10350.9 10599.8 666.2 670.2 683.4 692.4 697.5 4766.8 4941.3 4991.0 5000.5 5013.4 123861.4 128273.4 132910.0 135208.0 135559.0 64020.7 64142.4 104109.5 112832.4 114572.4 58806.4 60588.0 85006.0 100501.0 103071.0 9489.6 9765.8 9778.8 9802.5 9898.6 42395.0 56077.4 56902.7 57683.4 59190.4 47807.0 66993.0 67199.0 73064.0 80490.6 13544.6 13635.8 13860.9 14566.9 14842.4 15917.7 16102.0 16766.0 17060.5 17732.2 15132.5 15393.3 16054.2 16729.9 16746.2 14268.9 14847.5 15013.7 15152.3 15861.2 14745.0 15188.9 15919.0 16148.5 16153.1 12233.6 12397.1 12631.8 13070.5 13515.0 10460.1 10977.1 11009.9 12759.8 12884.8 09:19:42 INFO - PROCESS | 6799 | -------- Summary: end -------- 09:19:42 INFO - PROCESS | 6799 | 09:19:43 INFO - TEST-INFO | 6799: exit 0 09:19:43 INFO - TEST-OK | dromaeo_css | took 1113671ms 09:19:43 INFO - TEST-START | kraken 09:19:43 INFO - operating with platform_type : linux_ 09:19:43 INFO - Initialising browser for kraken test... 09:19:43 INFO - TEST-INFO | started process 7003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_dlmu/profile http://localhost:35573/getInfo.html) 09:19:47 INFO - PROCESS | 7003 | __metrics Screen width/height:1600/1200 09:19:47 INFO - PROCESS | 7003 | colorDepth:24 09:19:47 INFO - PROCESS | 7003 | Browser inner width/height: 1024/697 09:19:47 INFO - PROCESS | 7003 | __metrics 09:19:48 INFO - PROCESS | 7003 | 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" 09:19:48 INFO - PROCESS | 7003 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:49 INFO - TEST-INFO | 7003: exit 0 09:19:49 INFO - Browser initialized. 09:19:49 INFO - Running cycle 1/1 for kraken test... 09:19:49 INFO - TEST-INFO | started process 7091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ_dlmu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:01 INFO - PROCESS | 7091 | RSS: Main: 145272832 09:20:01 INFO - PROCESS | 7091 | 09:20:03 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35573/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:04 INFO - PROCESS | 7091 | RSS: Main: 190676992 09:20:04 INFO - PROCESS | 7091 | 09:20:07 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35573/tests/kraken/driver.html?testName=audio-dft) 09:20:07 INFO - PROCESS | 7091 | RSS: Main: 181477376 09:20:07 INFO - PROCESS | 7091 | 09:20:10 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35573/tests/kraken/driver.html?testName=audio-fft) 09:20:11 INFO - PROCESS | 7091 | RSS: Main: 268910592 09:20:11 INFO - PROCESS | 7091 | 09:20:13 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35573/tests/kraken/driver.html?testName=audio-oscillator) 09:20:13 INFO - PROCESS | 7091 | RSS: Main: 175099904 09:20:14 INFO - PROCESS | 7091 | 09:20:16 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35573/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:16 INFO - PROCESS | 7091 | RSS: Main: 176553984 09:20:16 INFO - PROCESS | 7091 | 09:20:19 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35573/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:20 INFO - PROCESS | 7091 | RSS: Main: 212566016 09:20:20 INFO - PROCESS | 7091 | 09:20:23 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35573/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:24 INFO - PROCESS | 7091 | RSS: Main: 211992576 09:20:24 INFO - PROCESS | 7091 | 09:20:27 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35573/tests/kraken/driver.html?testName=json-parse-financial) 09:20:27 INFO - PROCESS | 7091 | RSS: Main: 211501056 09:20:27 INFO - PROCESS | 7091 | 09:20:29 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35573/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:29 INFO - PROCESS | 7091 | RSS: Main: 175271936 09:20:29 INFO - PROCESS | 7091 | 09:20:31 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:32 INFO - PROCESS | 7091 | RSS: Main: 181919744 09:20:32 INFO - PROCESS | 7091 | 09:20:34 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:34 INFO - PROCESS | 7091 | RSS: Main: 185913344 09:20:34 INFO - PROCESS | 7091 | 09:20:37 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:37 INFO - PROCESS | 7091 | RSS: Main: 186470400 09:20:37 INFO - PROCESS | 7091 | 09:20:40 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:40 INFO - PROCESS | 7091 | RSS: Main: 184643584 09:20:40 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | Cycle 1(1): loaded http://localhost:35573/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35573/tests/kraken/driver.html?testName=ai-astar) 09:20:42 INFO - PROCESS | 7091 | RSS: Main: 235175936 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | __start_tp_report 09:20:42 INFO - PROCESS | 7091 | _x_x_mozilla_page_load 09:20:42 INFO - PROCESS | 7091 | _x_x_mozilla_page_load_details 09:20:42 INFO - PROCESS | 7091 | |i|pagename|runs| 09:20:42 INFO - PROCESS | 7091 | |0;ai-astar;101;112;111;110;111;111;109;110;111;110 09:20:42 INFO - PROCESS | 7091 | |1;audio-beat-detection;140;116;118;169;115;118;144;118;144;116 09:20:42 INFO - PROCESS | 7091 | |2;audio-dft;161;161;161;159;160;161;160;158;160;160 09:20:42 INFO - PROCESS | 7091 | |3;audio-fft;75;75;75;108;85;76;75;75;112;75 09:20:42 INFO - PROCESS | 7091 | |4;audio-oscillator;84;83;132;83;84;84;122;83;85;118 09:20:42 INFO - PROCESS | 7091 | |5;imaging-gaussian-blur;117;117;116;116;117;117;117;117;116;117 09:20:42 INFO - PROCESS | 7091 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;146;146 09:20:42 INFO - PROCESS | 7091 | |7;imaging-desaturate;95;94;95;95;95;94;94;95;94;94 09:20:42 INFO - PROCESS | 7091 | |8;json-parse-financial;54;51;52;51;51;51;52;51;52;51 09:20:42 INFO - PROCESS | 7091 | |9;json-stringify-tinderbox;58;62;60;59;60;58;60;59;59;60 09:20:42 INFO - PROCESS | 7091 | |10;stanford-crypto-aes;72;80;74;72;72;72;74;73;73;72 09:20:42 INFO - PROCESS | 7091 | |11;stanford-crypto-ccm;129;119;116;120;114;114;116;115;155;117 09:20:42 INFO - PROCESS | 7091 | |12;stanford-crypto-pbkdf2;153;151;148;151;149;149;154;152;155;151 09:20:42 INFO - PROCESS | 7091 | |13;stanford-crypto-sha256-iterative;62;62;63;61;61;61;61;65;60;61 09:20:42 INFO - PROCESS | 7091 | __end_tp_report 09:20:42 INFO - PROCESS | 7091 | __start_cc_report 09:20:42 INFO - PROCESS | 7091 | _x_x_mozilla_cycle_collect,1631 09:20:42 INFO - PROCESS | 7091 | __end_cc_report 09:20:42 INFO - PROCESS | 7091 | __startTimestamp1471364442843__endTimestamp 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | ------- Summary: start ------- 09:20:42 INFO - PROCESS | 7091 | Number of tests: 14 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.13 (2.8%) stddev-sans-first:0.88 09:20:42 INFO - PROCESS | 7091 | Values: 101.0 112.0 111.0 110.0 111.0 111.0 109.0 110.0 111.0 110.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:129.00 stddev:18.44 (14.3%) stddev-sans-first:19.19 09:20:42 INFO - PROCESS | 7091 | Values: 140.0 116.0 118.0 169.0 115.0 118.0 144.0 118.0 144.0 116.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:0.99 (0.6%) stddev-sans-first:1.00 09:20:42 INFO - PROCESS | 7091 | Values: 161.0 161.0 161.0 159.0 160.0 161.0 160.0 158.0 160.0 160.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.00 stddev:14.54 (19.4%) stddev-sans-first:15.12 09:20:42 INFO - PROCESS | 7091 | Values: 75.0 75.0 75.0 108.0 85.0 76.0 75.0 75.0 112.0 75.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:83.50 stddev:19.76 (23.7%) stddev-sans-first:20.50 09:20:42 INFO - PROCESS | 7091 | Values: 84.0 83.0 132.0 83.0 84.0 84.0 122.0 83.0 85.0 118.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:20:42 INFO - PROCESS | 7091 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 09:20:42 INFO - PROCESS | 7091 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:20:42 INFO - PROCESS | 7091 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:0.97 (1.9%) stddev-sans-first:0.50 09:20:42 INFO - PROCESS | 7091 | Values: 54.0 51.0 52.0 51.0 51.0 51.0 52.0 51.0 52.0 51.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:1.18 (2.0%) stddev-sans-first:1.12 09:20:42 INFO - PROCESS | 7091 | Values: 58.0 62.0 60.0 59.0 60.0 58.0 60.0 59.0 59.0 60.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:73.00 stddev:2.46 (3.4%) stddev-sans-first:2.55 09:20:42 INFO - PROCESS | 7091 | Values: 72.0 80.0 74.0 72.0 72.0 72.0 74.0 73.0 73.0 72.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:118.00 stddev:12.57 (10.7%) stddev-sans-first:13.04 09:20:42 INFO - PROCESS | 7091 | Values: 129.0 119.0 116.0 120.0 114.0 114.0 116.0 115.0 155.0 117.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.50 stddev:2.26 (1.5%) stddev-sans-first:2.32 09:20:42 INFO - PROCESS | 7091 | Values: 153.0 151.0 148.0 151.0 149.0 149.0 154.0 152.0 155.0 151.0 09:20:42 INFO - PROCESS | 7091 | 09:20:42 INFO - PROCESS | 7091 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:1.42 (2.3%) stddev-sans-first:1.50 09:20:42 INFO - PROCESS | 7091 | Values: 62.0 62.0 63.0 61.0 61.0 61.0 61.0 65.0 60.0 61.0 09:20:42 INFO - PROCESS | 7091 | -------- Summary: end -------- 09:20:42 INFO - PROCESS | 7091 | 09:20:43 INFO - TEST-INFO | 7091: exit 0 09:20:43 INFO - TEST-OK | kraken | took 60755ms 09:20:43 INFO - SUITE-END | took 1174s 09:20:44 INFO - Completed test suite (00:19:34) 09:20:44 INFO - javascript benchmark 09:20:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3209.903702888913, 3229.031290612817, 3238.174265562282, 3254.1213872254507, 3270.6892845179714, 5491.093969495034, 5604.635698679588, 5610.551155907528, 5626.718664066791, 5641.09742441209, 5554.472325129116, 5598.1322894951345, 5612.803551875685, 5629.71851407429, 5637.661740295575, 4962.6526143169995, 5005.374328208974, 5055.51972562606, 5105.591552675795, 5119.462456442076, 4906.828261010865, 5047.041062198171, 5075.873103172414, 5100.183970564711, 5106.08090543702, 4970.502949705036, 5006.249062640597, 5019.799208031674, 5034.370703662043, 5047.293378926953, 5168.0, 5175.430702622709, 5186.92219616706, 5199.168133098724, 5207.687538747668, 4894.853154405374, 4928.1129396708575, 4938.38270216223, 5098.388193416777, 5133.743312834344, 5483.588730845203, 5515.145152501369, 5519.613627046108, 5564.944350556483, 5580.609357344987, 6051.394860513958, 6059.581576644657, 6098.603590766586, 6098.69506524672, 6103.0, 5893.675847828367, 5935.287765468128, 5943.078822782432, 6058.242719660043, 6084.996459312026, 5588.832335029955, 5624.3531993820825, 5630.268065151545, 5631.2397826293745, 5644.85887852807, 5835.854103647358, 6120.326764055951, 6121.510279177655, 6128.453176423585, 6128.479079278222, 5858.707064646751, 5871.765129394862, 5894.086416605433, 5953.970230148821, 5983.461488465972, 6040.546958978094, 6053.4854537363935, 6053.848653783691, 6059.454649081604, 6101.0, 5771.278590176228, 6021.7591296347655, 6026.246719160105, 6033.306169790442, 6054.152418661391, 6000.849978750566, 6044.969775151096, 6058.939410605837, 6079.148919151322, 6084.391560843968, 4588.655850811202, 4607.838725644587, 4608.838690645811, 4625.32932724753, 4636.281376386665, 4025.9597404025585, 4110.321796903538, 4137.40007698882, 4158.979205104016, 4176.77027763476, 5567.47109024642, 5611.80358687444, 5614.07367784311, 5675.971620141955, 5694.1458781183155, 5955.493783028404, 5969.522438204933, 5969.611975221596, 5991.191189189491, 6011.368806275365, 5514.172874068921, 5641.661500309994, 5642.605017648725, 5646.265985421869, 5652.660840349592, 5047.4447810740785, 5073.264376665363, 5092.541671249605, 5136.43499215086, 5156.845294641167, 6104.908426373519, 6122.0, 6130.049842274544, 6131.049687298387, 6131.202943617391, 5781.710914454345, 5925.674087925033, 5935.5548333875995, 5938.613990090803, 5940.376260492586, 5494.2857428534035, 6085.056816193497, 6129.328227221949, 6221.191245138103, 6222.657753823402, 5918.526517878473, 5923.644581325134, 5923.703814809329, 5927.585069045125, 5927.881442371215, 5781.0, 5980.401959803985, 6036.396360363929, 6036.577439579188, 6043.486303664238, 5789.189513468202, 5799.8550036249435, 5861.472467477947, 5916.822495325147, 5949.0481522956115, 5846.619969701956, 5896.174535564938, 5898.882022359443, 5900.793472228452, 5903.409659034062, 5627.409122042127, 5874.970625146847, 5880.14737863016, 5880.9708301048, 5881.5000724937145, 5832.12518122285, 5888.587798854039, 5912.49743771767, 5931.822045338647, 6011.639301641915, 5821.592488525763, 5834.212381328466, 5834.649921004753, 5834.737436815439, 5836.387179346108], "value": 5514.88934229159, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.0451304990315, 626.1546911669247, 642.1395330257454, 665.2084020016177, 665.2615596687679, 34893.82553087234, 38701.0, 46008.53991460084, 46799.532004679946, 46883.765581172134, 45615.771921140346, 46057.7697111514, 46079.53920460794, 46114.53885461144, 46121.538784612145, 9081.27349812015, 9223.400478968879, 9229.676961306355, 9236.6305347786, 9240.907590924106, 8514.48913065215, 10707.78584428311, 10996.945015274912, 10997.560097596086, 11324.0, 11180.385078820702, 11271.605493807718, 11284.717882052932, 11289.830652540219, 11291.983721465067, 11126.0, 11161.218714689974, 11190.272632278877, 11194.66416007521, 11205.271657342288, 10931.56273749049, 10988.560457581687, 10999.780004399947, 11004.669859904216, 11006.559737610485, 45857.312140317925, 46129.53870461286, 46246.306305405444, 46305.76847115777, 46331.53668463306, 3863.130795571002, 3875.968992248052, 3981.5023122109737, 4070.5318888327924, 4632.5830675239395, 33896.661033389595, 34105.48841767351, 34760.826195869115, 34884.302313953835, 36773.81613091944, 4369.344598310247, 4439.733615983051, 4446.7109637873755, 4490.550944905516, 4529.932051019237, 360975.0, 361026.0, 361136.0, 363296.0, 364042.179789102, 34580.48129278063, 63152.684236579444, 63631.68184159049, 63643.0, 63768.0, 35359.11602209965, 38640.0, 38683.61316386856, 38697.0, 38727.0, 17601.647967040586, 17813.0, 17861.464156075337, 17869.642607148045, 17879.10604469797, 13875.098118622258, 14725.337359818619, 14824.851751482347, 14827.184504852337, 14968.101913885203, 9034.503102329303, 9186.40288381253, 9323.953380233055, 9356.4386136832, 9359.906400936039, 3465.203003309253, 3542.4686297055646, 3573.356795776733, 3580.230250496156, 3604.062943634621, 1075.2795626929976, 1082.783443311341, 1085.777415629794, 1098.2038022433671, 1144.6648836373704, 34940.65059349425, 35444.64555354465, 35804.64195358065, 35814.28371432557, 35898.82050589783, 1195.9701007474882, 1207.1489599832098, 1223.0521345956954, 1283.6534135783286, 1287.2212311551377, 18730.0, 18750.812491874905, 18826.0, 18858.71711924322, 18893.338733144275, 579120.0, 582281.0, 583250.0, 584727.0, 585381.0, 64350.35649643537, 64862.67568662127, 64935.35064649293, 64978.35021649818, 64987.67506162439, 1355.6949686320697, 1378.9241591712353, 1398.6503374156462, 1487.3976039703937, 1496.177102593569, 28574.571381429712, 37969.810150949066, 38182.23635527329, 38269.0, 39123.804380979054, 59226.407735923814, 59893.401065990525, 59973.0, 60021.69989150201, 60229.39770602238, 484300.0, 484761.0, 485248.0, 486553.0, 487235.0, 30421.543676845307, 31269.374612507167, 40608.0, 45634.54365456303, 47074.29388559106, 50669.0, 51417.742911285204, 51854.74072629613, 52018.73990630022, 52964.0, 44995.32507012332, 46103.769481152376, 46104.0, 46150.769246153555, 47589.76205118952, 45997.310040348755, 46735.0, 46805.0, 47229.74932256113, 47433.52566474291, 45539.0, 46111.76944115258, 46481.0, 46491.76754116208, 46761.29858052064, 37500.62499374971, 41700.58299416967, 43830.0, 44011.33982990322, 44574.0, 30247.848760756056, 30767.84616076905, 31023.53464697986, 31182.37635247328, 31426.528602071445], "value": 21091.097008878725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.15748007923406, 408.38334115485605, 408.72615347717027, 409.32052792364675, 427.6429181633336, 19541.41375758726, 21109.57780844382, 21384.46538836528, 22014.559708805813, 22432.32703018908, 19958.800411995875, 20572.588548229025, 20828.895855520703, 21019.2643257486, 21176.682349764727, 5718.628289161202, 5942.019566771499, 5946.851328716773, 5947.464728174464, 5951.970240148793, 5660.60375773696, 5820.010598198316, 5826.271716035496, 5837.9124313135335, 5845.503132233744, 5806.071028635419, 5806.390329015456, 5816.185733997244, 5816.563757718167, 5817.941820581804, 5605.971970140144, 5782.826515204551, 5816.1275808628625, 5834.766609335643, 5838.795642152547, 5143.717095559743, 5214.139666954948, 5216.191490319006, 5218.034663587248, 5220.0, 20619.896900515552, 20737.688934665992, 20952.5809483811, 22546.549069018693, 23889.761102388926, 2500.0749722602573, 2513.3967847716417, 2536.543422184006, 2555.0674003988597, 2556.693196816394, 14987.100773953489, 15019.624509387244, 15199.01206421579, 15841.52475425739, 16230.026198428131, 2009.1963214714085, 2024.483756642047, 2058.4030631116957, 2070.1926248762993, 2075.107703687414, 81125.18874811236, 81831.590842046, 82718.17281827165, 84497.0, 84725.0, 10616.681499555025, 10732.892671073188, 11135.610253641084, 11488.425578721031, 11605.593804216813, 14667.85332146665, 14804.407823687146, 14811.333489992976, 14816.185109818847, 14824.0, 8735.301175905912, 8736.475811451333, 8887.0, 8924.8661270081, 9040.367174297737, 9133.269338452908, 9188.724338269863, 9214.76963075915, 9285.164335209729, 9350.532473376305, 6550.639714815731, 6657.367550082734, 6691.1636045494315, 6746.190457145174, 6829.146356705412, 1928.9517762056062, 1949.8635095543175, 1970.300543307134, 1978.7031945208046, 2031.492126968258, 991.6281394477071, 1045.4981608827804, 1101.7410908436511, 1125.189863298424, 1181.078758568318, 18994.81005189958, 19935.700964485546, 21598.704077755385, 21630.35108946734, 21858.672119917897, 1066.184368957748, 1089.5478376473834, 1124.3085502415956, 1128.6219116595869, 1138.8177891537314, 10191.796164076677, 11789.587364442203, 11857.822132668018, 11860.288382697066, 12031.879681203076, 520304.0, 528209.0, 535781.0, 545402.0, 557924.0, 76377.0, 80356.59821700853, 95821.0, 106697.0, 107425.4628726867, 1064.387976913278, 1075.1560025380038, 1108.3626914524182, 1109.7114750165015, 1111.2943281016612, 18638.81361186371, 18942.621147576694, 19115.713264300768, 19168.808311916702, 19268.0, 46092.0, 46159.76920115378, 46284.30573541332, 46687.53312466832, 47518.28722569234, 65457.672711636136, 65800.0, 67010.0, 67805.66097169483, 68009.65995169993, 8035.718749843727, 8039.718609848627, 8107.14874938123, 8329.916700832913, 8355.498670079814, 10685.732856678334, 12813.93593032029, 12988.870111298766, 13177.077604567972, 13323.733525329633, 11161.330320180816, 11513.021393181674, 11664.825027624423, 11698.70753231176, 11970.70073248141, 10925.781484370109, 11130.499127539442, 11194.66416007521, 11236.66290011301, 11629.53481860716, 11459.942700286445, 11583.305001699924, 11659.417029148679, 12002.39988000579, 12028.398580070787, 8367.079621241537, 8440.15598440151, 8556.957215213884, 8557.786055348666, 8733.388662793543, 9842.901570984199, 11289.20975531705, 11619.186656933933, 11854.170208085352, 11870.881291187323], "value": 9748.976492051856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1512.546236129161, 1574.9685006299874, 1740.6170642458662, 1837.705967045273, 1879.4455635587508, 9013.14375134362, 9621.759456013595, 9652.083052110038, 9660.0, 9663.758406039853, 7788.0, 8888.0, 9197.12627300407, 9257.120573545519, 9399.34204605678, 2342.273895092518, 2371.27676058802, 2376.7979721723673, 2376.7979721723673, 2380.33350661815, 2332.8017118544944, 2335.2177020698086, 2364.9408764780933, 2367.0768400323886, 2368.111958015744, 2367.3016460144213, 2378.9286321410386, 2388.52229554089, 2400.23192578375, 2401.1835975768236, 2261.4007288068674, 2277.1802151225547, 2279.509905370345, 2285.31440567829, 2309.6650985606993, 2264.8301377396597, 2266.138867230442, 2268.1834539565743, 2269.1944359752215, 2273.2043784675398, 9567.808643827084, 9638.566264518113, 9657.758556036086, 9680.370775899544, 9696.272779541492, 5396.19057141428, 5546.500814926636, 5555.2222688823595, 5555.472230138131, 5563.193336966157, 3281.392942305681, 3372.4098282800655, 3504.0, 3518.7712798668003, 3534.2048039190977, 1133.1274918312936, 1156.468024708635, 1193.9820902686467, 1223.2904915149193, 1226.907981901352, 13510.189388636712, 13555.390007449689, 13558.796618050737, 13563.321833908363, 13572.93213533926, 4508.774561271924, 4613.3771940787565, 4737.928931066037, 4747.169245382044, 4802.6638135330195, 10839.078678312275, 10932.180086493545, 10938.124950003981, 11022.118230541537, 11031.613893513688, 5573.331200255914, 5576.414476479992, 5582.469665381782, 5585.58108141891, 5587.664740115606, 4364.148990946735, 4936.9259461107395, 4973.651844370932, 5012.04771093491, 5062.240663900445, 4080.065327707878, 4127.442795222667, 4159.376093585986, 4194.993201631647, 4199.68502362324, 1311.90816642836, 1339.1563315111418, 1340.825692659948, 1364.9590512284647, 1368.835739711241, 972.2610815780058, 1024.939187940478, 1043.2332235806675, 1066.2269854355686, 1075.5697720911699, 6422.325655806073, 6489.2212934447225, 6601.3728695773825, 6649.468042556584, 6811.523193376416, 450.8940399006231, 456.01955795040925, 456.8812108851656, 483.0242909323218, 484.04642853578395, 4468.620167285752, 4476.731396116244, 4589.58693717567, 4607.746573938399, 4717.103750287503, 11555.76888462226, 12032.939835300767, 12037.217580857216, 12038.097142714332, 12071.939640301742, 10688.51901664427, 11119.94440027811, 11307.49116289769, 11662.0, 11970.042396608425, 8758.912410875937, 8796.692115775917, 8972.775680607907, 8981.05698901619, 8991.0, 6524.6411447368955, 6680.565763225375, 6717.261101278953, 6739.258681544928, 6765.695543700644, 10396.792064158826, 10572.524236409534, 10617.0, 10682.73293167677, 10683.358998459806, 10869.347839129678, 10885.238033337908, 10888.727781805519, 11026.779464410507, 11059.557617695524, 7452.813679658052, 7793.337566306701, 7849.8037549059445, 8611.440256383315, 8747.825043499222, 9497.43015419077, 9574.186194173573, 9585.993470685478, 9587.712368628952, 9589.904100958902, 8076.030876294883, 8283.461574997606, 8311.16888311164, 8523.531205783742, 8622.525761083201, 8142.796430089058, 8179.83640327202, 8197.918020819954, 8291.046529649167, 8322.16778322163, 7657.310842024244, 7678.8080297993, 7682.078150621961, 7723.459357844897, 7746.651400687096, 5532.087205611029, 5777.364489906191, 5778.768849245983, 5847.7368518417625, 6078.756849726138, 5610.158476228598, 5611.831645050655, 5758.568107391879, 5803.970980145073, 5830.591858569859], "value": 4832.641186290818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.810867805351, 334.05462541008944, 339.70785124792684, 341.5355117040826, 341.7812599936041, 5532.087205611075, 5538.701438902851, 5575.275214222151, 5593.916091258634, 5601.327840659116, 5505.769518956987, 5525.557955363572, 5549.445055494459, 5561.193626924093, 5564.304461942257, 4025.6779457643465, 4149.502059752826, 4174.269502837006, 4199.2651286024975, 4267.594578515036, 4256.87229383119, 4259.531451540328, 4270.316749320109, 4309.806058727365, 4314.288142456507, 4233.772206060255, 4243.681723870706, 4246.511651160125, 4258.701890867641, 4308.375285583604, 4130.896727581804, 4227.957720422787, 4229.429027081351, 4253.064325848293, 4339.1972485090355, 3800.327855743464, 3803.182315802088, 3806.219724956405, 3810.066533699234, 3871.341871881787, 5245.763940622681, 5327.46725327468, 5339.279197308352, 5378.112611419111, 5387.595930305243, 4946.5795407390415, 4959.231319145538, 4960.305557221992, 4961.131801934647, 4978.6763860348965, 5714.771409143629, 5756.079027355645, 5765.0, 5768.596198266123, 5776.768929242825, 3998.980005099985, 4073.531543872463, 4089.591040895916, 4090.8159132839096, 4096.754194748295, 5737.971310143423, 5872.678647304322, 5971.193888824996, 5997.460228579405, 6004.159417681528, 4267.295896177096, 4270.145970805851, 4274.166537525215, 4280.315149576053, 4290.957090429056, 4038.4749982502085, 4198.265303571863, 4214.452121224283, 4214.473190851144, 4253.106847561985, 4808.663393562487, 4817.253325734517, 4869.732164730974, 4887.608991280689, 4893.926591101136, 5753.021986262345, 5761.481466667936, 5767.134929760486, 5767.394423585463, 5794.1308803679785, 1381.8272715910512, 1434.784782282666, 1442.7619442791822, 1446.4792674637122, 1451.5774540949928, 907.088376181933, 917.9816403672023, 938.9483578403253, 942.2697409507549, 949.1647350331802, 4928.581070609039, 4956.801727930842, 4994.675346102492, 5016.924746128811, 5018.0, 5155.9742201288755, 5192.662476938987, 5198.532132108128, 5200.245964335226, 5205.219217117463, 4774.522547745198, 4893.388326459192, 4948.752562371867, 4979.427365852901, 4980.05378977993, 2778.527650299454, 2778.7915906307107, 2810.7329803669027, 2825.6891741908375, 2839.7586205172793, 5439.020976224238, 5466.671999680032, 5468.234447177399, 5478.534324582375, 5479.315085614298, 4947.604191664658, 5228.215767634809, 5247.9766445542755, 5272.446393128743, 5297.410776767031, 5274.736263186902, 5344.706041167773, 5350.277712508761, 5435.510804027656, 5456.372517160418, 5157.587393008475, 5232.3982741985155, 5609.691466969356, 5685.573581981285, 5942.316633587193, 5865.387018569757, 5886.911696324473, 5898.439648233411, 5900.468957793819, 5916.171735957056, 5860.860785813063, 6193.81418557444, 6205.720742566505, 6229.781957631461, 6238.968805155945, 4200.579942005775, 4235.512916014698, 4247.702660813837, 4292.484901811803, 4292.849750258726, 6067.332593414632, 6067.575269731076, 6087.66517841523, 6093.105774756955, 6093.969530152498, 5714.342850572238, 5852.356240813422, 5869.970650146722, 5936.28764548257, 5947.732352044255, 5795.768169273182, 5861.35525092231, 5869.4717475427415, 5870.207521984648, 5877.177195192762, 5785.132230165341, 5824.5631577631, 5827.650340979555, 5871.7651293947765, 5897.970510147422, 5590.357108932362, 5781.768729250783, 5817.738201781015, 5825.388334225015, 5826.329972053268, 5722.599418040697, 5727.226824378656, 5729.3984131665575, 5744.195812586325, 5814.94185058144], "value": 4290.659086131508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.388458656008, 820.5774026376417, 845.9873101903472, 873.0614589316486, 893.8972018217903, 16334.34662613494, 17484.3006279749, 17583.1208439578, 17638.735418968692, 17657.20542575584, 16372.918135409307, 16508.66982660349, 16542.255598498068, 16550.7517387239, 16552.58618534537, 734.0897287363671, 870.7344260000697, 872.1801506583822, 876.6318146378536, 880.8546589812673, 896.7847716548014, 899.5951821680237, 900.7522931193942, 901.1124042817819, 901.197933838884, 937.8968313485511, 944.211583327922, 944.3814301632442, 946.4983558713858, 947.0340252941995, 779.664744160011, 909.2544113826665, 910.2308549275917, 924.7780532672177, 945.5981207986613, 682.8804959132182, 690.5373399822081, 692.0138802206835, 693.4868197533839, 693.9271376505445, 15460.226988650635, 15490.225488725631, 15504.0, 15508.45720399792, 15512.224388780516, 15354.309056092503, 15538.378464861393, 15697.607559810982, 15926.601834954103, 16161.353545858152, 12350.320732359716, 12547.749045019049, 12675.366231688471, 12777.680557986032, 12828.294443805587, 4566.680332376735, 4566.70316429431, 4598.770061496912, 4620.168369693454, 4629.259318509023, 80869.59565202195, 80992.0, 81038.0, 81113.18886811116, 85560.14439855647, 12132.393380330948, 12422.565210217599, 12432.378381081091, 12573.24560526353, 12675.93662031684, 20147.496312592302, 20177.899110504353, 20265.493362665755, 20292.89853550723, 20314.390568282975, 9043.864342034874, 9120.954395228115, 9145.90854091464, 9204.217641500414, 9471.10024547667, 4761.047790441926, 4895.290182923456, 4914.434839993375, 4914.459409464956, 5226.973865130726, 3696.611855755107, 3834.386498160337, 3838.1747924196434, 3838.3858582627204, 3838.5201849768778, 631.9146915166485, 643.4594940250213, 644.5616980453336, 648.0182523476936, 653.8169312592481, 597.9073243647242, 608.2093278737624, 651.5797310734549, 696.5333226737996, 714.5034201230095, 9827.754306142404, 10020.0, 10174.186065114773, 10350.896491035144, 10599.788004239872, 666.2438132719302, 670.1622971285892, 683.3542302524068, 692.3561088187932, 697.5117417807555, 4766.761661916959, 4941.308216849644, 4990.975045124751, 5000.474950130257, 5013.448520662724, 123861.38069309776, 128273.35863320624, 132910.0, 135208.0, 135559.0, 64020.67989660022, 64142.358576414576, 104109.47945260225, 112832.43583782029, 114572.42713786378, 58806.41193588095, 60588.0, 85006.0, 100500.99499005063, 103071.0, 9489.620415183315, 9765.75585610337, 9778.75553111178, 9802.509874506104, 9898.604055837686, 42395.0, 56077.439225607224, 56902.715486422305, 57683.42316576948, 59190.40809592021, 47807.0, 66993.0, 67199.0, 73064.0, 80490.59754701189, 13544.59366219015, 13635.795463067863, 13860.930695346458, 14566.927165364104, 14842.406303748161, 15917.68164636724, 16102.0, 16766.0, 17060.488185354458, 17732.202050908, 15132.546023619308, 15393.30730117125, 16054.197290135213, 16729.91635041817, 16746.162691865113, 14268.928655357073, 14847.480338188112, 15013.6997260052, 15152.31814568325, 15861.206939653202, 14745.041572297767, 15188.924055379652, 15919.0, 16148.515544533682, 16153.111578863276, 12233.571824986082, 12397.132200745862, 12631.81052284198, 13070.54253101137, 13515.0, 10460.110890574386, 10977.114427860697, 11009.889901100887, 12759.808602870778, 12884.806727898902], "value": 8027.862081949411, "unit": "score"}], "name": "dromaeo_css", "value": 7573.990327491231, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 110.0, 111.0, 111.0, 109.0, 110.0, 111.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 116.0, 118.0, 169.0, 115.0, 118.0, 144.0, 118.0, 144.0, 116.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 159.0, 160.0, 161.0, 160.0, 158.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 108.0, 85.0, 76.0, 75.0, 75.0, 112.0, 75.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 132.0, 83.0, 84.0, 84.0, 122.0, 83.0, 85.0, 118.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0, 51.0, 52.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 60.0, 59.0, 60.0, 58.0, 60.0, 59.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 74.0, 72.0, 72.0, 72.0, 74.0, 73.0, 73.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 119.0, 116.0, 120.0, 114.0, 114.0, 116.0, 115.0, 155.0, 117.0], "value": 121.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 148.0, 151.0, 149.0, 149.0, 154.0, 152.0, 155.0, 151.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 61.0, 61.0, 61.0, 61.0, 65.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1454.7000000000003, "alertThreshold": 2.0}]} 09:20:44 INFO - Return code: 0 09:20:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:44 INFO - # TBPL SUCCESS # 09:20:44 INFO - Running post-action listener: _resource_record_post_action 09:20:44 INFO - [mozharness: 2016-08-16 16:20:44.157352Z] Finished run-tests step (success) 09:20:44 INFO - Running post-run listener: _resource_record_post_run 09:20:44 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325447680; Read time: 8; Write time: 480412 09:20:44 INFO - TinderboxPrint: CPU usage
13.0% 09:20:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:20:44 INFO - TinderboxPrint: I/O write bytes / time
325,447,680 / 480,412 09:20:44 INFO - TinderboxPrint: CPU idle
8,277.5 (87.0%) 09:20:44 INFO - TinderboxPrint: CPU user
1,187.2 (12.5%) 09:20:44 INFO - TinderboxPrint: Swap in / out
0 / 0 09:20:44 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 77361152; Read time: 0; Write time: 161032 09:20:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 245534720; Read time: 0; Write time: 319096 09:20:44 INFO - Running post-run listener: _upload_blobber_files 09:20:44 INFO - Blob upload gear active. 09:20:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:44 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 09:20:44 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'] 09:20:44 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 09:20:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:46 INFO - (blobuploader) - INFO - Done attempting. 09:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:46 INFO - (blobuploader) - INFO - Done attempting. 09:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:50 INFO - (blobuploader) - INFO - Done attempting. 09:20:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:50 INFO - Return code: 0 09:20:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/749c97d56bdd438806863b64f38c1937f8404e4b23efc2709da30aa41db64367b5d4d5457b70a2747ab9626829bff79fac80bbef85b82add6ae0d57e00e2da1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6af337d34811ea5b2783c7efa9a19e8cdfffc58917959e97ab382666beda3c3662e6752aebc605333e448d3e5190f3cf3ccc195402c672534f9be96644cf52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9332b460204e6630dc7be9d5db49740eceae4012020c1c8762c65033038f5c35968fc289eb01f34ff0db95d9d712fd0e6f0f4c180f19cad7b62605430738656e"} 09:20:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:50 INFO - Contents: 09:20:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/749c97d56bdd438806863b64f38c1937f8404e4b23efc2709da30aa41db64367b5d4d5457b70a2747ab9626829bff79fac80bbef85b82add6ae0d57e00e2da1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6af337d34811ea5b2783c7efa9a19e8cdfffc58917959e97ab382666beda3c3662e6752aebc605333e448d3e5190f3cf3ccc195402c672534f9be96644cf52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9332b460204e6630dc7be9d5db49740eceae4012020c1c8762c65033038f5c35968fc289eb01f34ff0db95d9d712fd0e6f0f4c180f19cad7b62605430738656e"} 09:20:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:50 INFO - Copying logs to upload dir... 09:20:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.713726 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-16 09:20:50.198610) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 09:20:50.202536) ========= 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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 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/749c97d56bdd438806863b64f38c1937f8404e4b23efc2709da30aa41db64367b5d4d5457b70a2747ab9626829bff79fac80bbef85b82add6ae0d57e00e2da1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6af337d34811ea5b2783c7efa9a19e8cdfffc58917959e97ab382666beda3c3662e6752aebc605333e448d3e5190f3cf3ccc195402c672534f9be96644cf52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9332b460204e6630dc7be9d5db49740eceae4012020c1c8762c65033038f5c35968fc289eb01f34ff0db95d9d712fd0e6f0f4c180f19cad7b62605430738656e"} build_url:https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011434 build_url: 'https://queue.taskcluster.net/v1/task/YOv6XqDiQLiOjcJtS_dBxA/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/749c97d56bdd438806863b64f38c1937f8404e4b23efc2709da30aa41db64367b5d4d5457b70a2747ab9626829bff79fac80bbef85b82add6ae0d57e00e2da1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6af337d34811ea5b2783c7efa9a19e8cdfffc58917959e97ab382666beda3c3662e6752aebc605333e448d3e5190f3cf3ccc195402c672534f9be96644cf52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9332b460204e6630dc7be9d5db49740eceae4012020c1c8762c65033038f5c35968fc289eb01f34ff0db95d9d712fd0e6f0f4c180f19cad7b62605430738656e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 09:20:50.227344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:20:50.227629) ========= 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=5cf47e208febbcd81da23b2e0000026f-1471362654.760736-1153670688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 09:20:50.312754) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 09:20:50.313056) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 09:20:50.313336) ========= ========= Total master_lag: 0.17 =========