builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1472161614.97 results: success (0) buildid: 20160825114018 builduid: acce7ee8aca24b39a7cac47982a6c356 revision: e29cdbb036abf01b465dcbae14d6252feb115102 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:54.971173) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:54.971741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:54.972074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:54.994100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:54.994516) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 14:46:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.0M=0s 2016-08-25 14:46:55 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094998 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.108551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.108931) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016103 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.167259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.167699) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e29cdbb036abf01b465dcbae14d6252feb115102 --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 e29cdbb036abf01b465dcbae14d6252feb115102 --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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 14:46:55,271 truncating revision to first 12 chars 2016-08-25 14:46:55,271 Setting DEBUG logging. 2016-08-25 14:46:55,271 attempt 1/10 2016-08-25 14:46:55,271 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e29cdbb036ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 14:46:55,597 unpacking tar archive at: mozilla-aurora-e29cdbb036ab/testing/mozharness/ program finished with exit code 0 elapsedTime=0.618739 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.801065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.801489) ========= script_repo_revision: e29cdbb036abf01b465dcbae14d6252feb115102 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.801936) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.802268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 14:46:55.812758) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-25 14:46:55.813107) ========= /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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:46:55 INFO - MultiFileLogger online at 20160825 14:46:55 in /builds/slave/test 14:46:55 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 14:46:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:46:55 INFO - {'append_to_log': False, 14:46:55 INFO - 'base_work_dir': '/builds/slave/test', 14:46:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:46:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:46:55 INFO - 'branch': 'Mozilla-Aurora', 14:46:56 INFO - 'buildbot_json_path': 'buildprops.json', 14:46:56 INFO - 'config_files': ('talos/linux_config.py',), 14:46:56 INFO - 'default_actions': ('clobber', 14:46:56 INFO - 'read-buildbot-config', 14:46:56 INFO - 'download-and-extract', 14:46:56 INFO - 'populate-webroot', 14:46:56 INFO - 'create-virtualenv', 14:46:56 INFO - 'install', 14:46:56 INFO - 'run-tests'), 14:46:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:46:56 INFO - 'download_minidump_stackwalk': True, 14:46:56 INFO - 'download_symbols': 'ondemand', 14:46:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:46:56 INFO - 'tooltool.py': '/tools/tooltool.py', 14:46:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:46:56 INFO - '/tools/misc-python/virtualenv.py')}, 14:46:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:46:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:46:56 INFO - 'installer_path': 'installer.exe', 14:46:56 INFO - 'log_level': 'info', 14:46:56 INFO - 'log_name': 'talos', 14:46:56 INFO - 'log_to_console': True, 14:46:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:46:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:46:56 INFO - 'opt_config_files': (), 14:46:56 INFO - 'pip_index': False, 14:46:56 INFO - 'sps_profile': False, 14:46:56 INFO - 'sps_profile_interval': 0, 14:46:56 INFO - 'suite': 'dromaeojs', 14:46:56 INFO - 'system_bits': '32', 14:46:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:46:56 INFO - 'title': 'talos-linux64-ix-055', 14:46:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:46:56 INFO - 'use_talos_json': True, 14:46:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:46:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:46:56 INFO - 'work_dir': 'build'} 14:46:56 INFO - [mozharness: 2016-08-25 21:46:56.005794Z] Running clobber step. 14:46:56 INFO - Running pre-action listener: _resource_record_pre_action 14:46:56 INFO - Running main action method: clobber 14:46:56 INFO - rmtree: /builds/slave/test/build 14:46:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:46:56 INFO - Running post-action listener: _resource_record_post_action 14:46:56 INFO - [mozharness: 2016-08-25 21:46:56.446229Z] Finished clobber step (success) 14:46:56 INFO - [mozharness: 2016-08-25 21:46:56.446330Z] Running read-buildbot-config step. 14:46:56 INFO - Running pre-action listener: _resource_record_pre_action 14:46:56 INFO - Running main action method: read_buildbot_config 14:46:56 INFO - Using buildbot properties: 14:46:56 INFO - { 14:46:56 INFO - "project": "", 14:46:56 INFO - "product": "firefox", 14:46:56 INFO - "script_repo_revision": "production", 14:46:56 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:46:56 INFO - "repository": "", 14:46:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:46:56 INFO - "buildid": "20160825114018", 14:46:56 INFO - "pgo_build": "True", 14:46:56 INFO - "basedir": "/builds/slave/test", 14:46:56 INFO - "buildnumber": 428, 14:46:56 INFO - "slavename": "talos-linux64-ix-055", 14:46:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:46:56 INFO - "platform": "ubuntu64_hw", 14:46:56 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:46:56 INFO - "branch": "mozilla-aurora", 14:46:56 INFO - "repo_path": "releases/mozilla-aurora", 14:46:56 INFO - "revision": "e29cdbb036abf01b465dcbae14d6252feb115102", 14:46:56 INFO - "stage_platform": "linux64-pgo", 14:46:56 INFO - "builduid": "acce7ee8aca24b39a7cac47982a6c356", 14:46:56 INFO - "slavebuilddir": "test" 14:46:56 INFO - } 14:46:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:46:56 INFO - Running post-action listener: _resource_record_post_action 14:46:56 INFO - [mozharness: 2016-08-25 21:46:56.448769Z] Finished read-buildbot-config step (success) 14:46:56 INFO - [mozharness: 2016-08-25 21:46:56.449051Z] Running download-and-extract step. 14:46:56 INFO - Running pre-action listener: _resource_record_pre_action 14:46:56 INFO - Running main action method: download_and_extract 14:46:56 INFO - mkdir: /builds/slave/test/build/tests 14:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:46:56 INFO - https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:46:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:46:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:46:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:46:56 INFO - retry: Failed, sleeping 30 seconds before retrying 14:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:47:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:47:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:47:26 INFO - retry: Failed, sleeping 60 seconds before retrying 14:48:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:48:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:48:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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! 14:48:26 INFO - Caught exception: HTTP Error 404: Not Found 14:48:26 INFO - Caught exception: HTTP Error 404: Not Found 14:48:26 INFO - Caught exception: HTTP Error 404: Not Found 14:48:26 INFO - trying https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:48:26 INFO - Downloading https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:27 INFO - Downloaded 1361 bytes. 14:48:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:48:27 INFO - Using the following test package requirements: 14:48:27 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:48:27 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:48:27 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:48:27 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'jsshell-linux-x86_64.zip'], 14:48:27 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:48:27 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:48:27 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:48:27 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:48:27 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:48:27 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:48:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:48:27 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:48:27 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')]} 14:48:27 INFO - https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:48:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:48:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:48:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:48:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:48:27 INFO - retry: Failed, sleeping 30 seconds before retrying 14:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:48:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:48:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:48:57 INFO - retry: Failed, sleeping 60 seconds before retrying 14:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:49:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:49:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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! 14:49:57 INFO - Caught exception: HTTP Error 404: Not Found 14:49:57 INFO - Caught exception: HTTP Error 404: Not Found 14:49:57 INFO - Caught exception: HTTP Error 404: Not Found 14:49:57 INFO - trying https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:57 INFO - Downloading https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:49:58 INFO - Downloaded 21769067 bytes. 14:49:58 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'] 14:49:58 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 14:49:59 INFO - Return code: 0 14:49:59 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 14:49:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:49:59 INFO - https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:49:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:49:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:49:59 INFO - retry: Failed, sleeping 30 seconds before retrying 14:50:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:50:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:50:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:50:29 INFO - retry: Failed, sleeping 60 seconds before retrying 14:51:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:51:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:51:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:51:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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! 14:51:29 INFO - Caught exception: HTTP Error 404: Not Found 14:51:29 INFO - Caught exception: HTTP Error 404: Not Found 14:51:29 INFO - Caught exception: HTTP Error 404: Not Found 14:51:29 INFO - trying https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:51:29 INFO - Downloading https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:51:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:51:30 INFO - Downloaded 21769067 bytes. 14:51:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:51:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:51:31 INFO - Return code: 0 14:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:51:31 INFO - https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:51:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:51:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:51:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:51:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:51:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:51:31 INFO - retry: Failed, sleeping 30 seconds before retrying 14:52:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:52:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:52:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:52:01 INFO - retry: Failed, sleeping 60 seconds before retrying 14:53:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:53:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:53:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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! 14:53:01 INFO - Caught exception: HTTP Error 404: Not Found 14:53:01 INFO - Caught exception: HTTP Error 404: Not Found 14:53:01 INFO - Caught exception: HTTP Error 404: Not Found 14:53:01 INFO - trying https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:53:01 INFO - Downloading https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:02 INFO - Downloaded 14413723 bytes. 14:53:02 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'] 14:53:02 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 14:53:02 INFO - Return code: 0 14:53:02 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')]} 14:53:02 INFO - https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:53:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:53:02 INFO - retry: Failed, sleeping 30 seconds before retrying 14:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:53:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:53:32 INFO - retry: Failed, sleeping 60 seconds before retrying 14:54:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:54:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:54:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:54:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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! 14:54:32 INFO - Caught exception: HTTP Error 404: Not Found 14:54:32 INFO - Caught exception: HTTP Error 404: Not Found 14:54:32 INFO - Caught exception: HTTP Error 404: Not Found 14:54:32 INFO - trying https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:54:32 INFO - Downloading https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:54:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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 14:54:34 INFO - Downloaded 63757858 bytes. 14:54:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:54:34 INFO - mkdir: /builds/slave/test/properties 14:54:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:54:34 INFO - Writing to file /builds/slave/test/properties/build_url 14:54:34 INFO - Contents: 14:54:34 INFO - build_url:https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:54:34 INFO - Running post-action listener: _resource_record_post_action 14:54:34 INFO - Running post-action listener: set_extra_try_arguments 14:54:34 INFO - [mozharness: 2016-08-25 21:54:34.697047Z] Finished download-and-extract step (success) 14:54:34 INFO - [mozharness: 2016-08-25 21:54:34.697280Z] Running populate-webroot step. 14:54:34 INFO - Running pre-action listener: _resource_record_pre_action 14:54:34 INFO - Running main action method: populate_webroot 14:54:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:54:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:54:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:54:34 INFO - '--tppagecycles', 14:54:34 INFO - '7'], 14:54:34 INFO - 'tests': ['tsvgm']}}, 14:54:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:54:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:54:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:54:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:54:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['damp', 'tps']}, 14:54:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'tests': ['damp', 'tps']}, 14:54:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['dromaeo_dom']}, 14:54:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:54:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['basic_compositor_video']}, 14:54:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:54:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'other-e10s': {'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['a11yr', 14:54:34 INFO - 'ts_paint', 14:54:34 INFO - 'tpaint', 14:54:34 INFO - 'sessionrestore', 14:54:34 INFO - 'sessionrestore_no_auto_restore', 14:54:34 INFO - 'tabpaint']}, 14:54:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['tsvgx', 14:54:34 INFO - 'tsvgr_opacity', 14:54:34 INFO - 'tart', 14:54:34 INFO - 'tscrollx', 14:54:34 INFO - 'cart']}, 14:54:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:54:34 INFO - 'tsvgr_opacity', 14:54:34 INFO - 'tart', 14:54:34 INFO - 'tscrollx', 14:54:34 INFO - 'cart']}, 14:54:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'talos_options': ['--disable-e10s'], 14:54:34 INFO - 'tests': ['tp5o']}, 14:54:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'tests': ['tp5o']}, 14:54:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'talos_options': ['--disable-e10s', 14:54:34 INFO - '--xperf_path', 14:54:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:34 INFO - 'tests': ['tp5n']}, 14:54:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:34 INFO - 'talos_options': ['--xperf_path', 14:54:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:34 INFO - 'tests': ['tp5n']}}, 14:54:34 INFO - 'talos.zip': {'path': '', 14:54:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:54:34 INFO - Running post-action listener: _resource_record_post_action 14:54:34 INFO - [mozharness: 2016-08-25 21:54:34.715490Z] Finished populate-webroot step (success) 14:54:34 INFO - [mozharness: 2016-08-25 21:54:34.715579Z] Running create-virtualenv step. 14:54:34 INFO - Running pre-action listener: _resource_record_pre_action 14:54:34 INFO - Running main action method: create_virtualenv 14:54:34 INFO - Creating virtualenv /builds/slave/test/build/venv 14:54:34 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 14:54:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:54:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:54:34 INFO - Using real prefix '/usr' 14:54:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:54:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:54:37 INFO - Installing pip.................done. 14:54:37 INFO - Return code: 0 14:54:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54: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', 'pip>=1.5'] in /builds/slave/test/build 14:54: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 pip>=1.5 14:54:37 INFO - Using env: {'DISPLAY': ':0', 14:54:37 INFO - 'HOME': '/home/cltbld', 14:54:37 INFO - 'LANG': 'en_US.UTF-8', 14:54:37 INFO - 'LANGUAGE': 'en_US:en', 14:54:37 INFO - 'LOGNAME': 'cltbld', 14:54:37 INFO - 'MAIL': '/var/mail/cltbld', 14:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:37 INFO - 'MOZ_NO_REMOTE': '1', 14:54:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:37 INFO - 'NO_EM_RESTART': '1', 14:54:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:37 INFO - 'PWD': '/builds/slave/test', 14:54:37 INFO - 'SHELL': '/bin/bash', 14:54:37 INFO - 'SHLVL': '1', 14:54:37 INFO - 'TERM': 'linux', 14:54:37 INFO - 'TMOUT': '86400', 14:54:37 INFO - 'USER': 'cltbld', 14:54:37 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:37 INFO - '_': '/tools/buildbot/bin/python'} 14:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:37 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 14:54:37 INFO - Cleaning up... 14:54:37 INFO - Return code: 0 14:54:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', '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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:54: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 psutil>=3.1.1 14:54:37 INFO - Using env: {'DISPLAY': ':0', 14:54:37 INFO - 'HOME': '/home/cltbld', 14:54:37 INFO - 'LANG': 'en_US.UTF-8', 14:54:37 INFO - 'LANGUAGE': 'en_US:en', 14:54:37 INFO - 'LOGNAME': 'cltbld', 14:54:37 INFO - 'MAIL': '/var/mail/cltbld', 14:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:37 INFO - 'MOZ_NO_REMOTE': '1', 14:54:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:37 INFO - 'NO_EM_RESTART': '1', 14:54:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:37 INFO - 'PWD': '/builds/slave/test', 14:54:37 INFO - 'SHELL': '/bin/bash', 14:54:37 INFO - 'SHLVL': '1', 14:54:37 INFO - 'TERM': 'linux', 14:54:37 INFO - 'TMOUT': '86400', 14:54:37 INFO - 'USER': 'cltbld', 14:54:37 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:37 INFO - '_': '/tools/buildbot/bin/python'} 14:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:37 INFO - Downloading/unpacking psutil>=3.1.1 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:54:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:39 INFO - Installing collected packages: psutil 14:54:39 INFO - Running setup.py install for psutil 14:54:39 INFO - building 'psutil._psutil_linux' extension 14:54:39 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 14:54:39 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 14:54:39 INFO - building 'psutil._psutil_posix' extension 14:54:39 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 14:54:40 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 14:54:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:40 INFO - Successfully installed psutil 14:54:40 INFO - Cleaning up... 14:54:40 INFO - Return code: 0 14:54:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:54: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 mozsystemmonitor==0.3 14:54:40 INFO - Using env: {'DISPLAY': ':0', 14:54:40 INFO - 'HOME': '/home/cltbld', 14:54:40 INFO - 'LANG': 'en_US.UTF-8', 14:54:40 INFO - 'LANGUAGE': 'en_US:en', 14:54:40 INFO - 'LOGNAME': 'cltbld', 14:54:40 INFO - 'MAIL': '/var/mail/cltbld', 14:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:40 INFO - 'MOZ_NO_REMOTE': '1', 14:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:40 INFO - 'NO_EM_RESTART': '1', 14:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:40 INFO - 'PWD': '/builds/slave/test', 14:54:40 INFO - 'SHELL': '/bin/bash', 14:54:40 INFO - 'SHLVL': '1', 14:54:40 INFO - 'TERM': 'linux', 14:54:40 INFO - 'TMOUT': '86400', 14:54:40 INFO - 'USER': 'cltbld', 14:54:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:40 INFO - '_': '/tools/buildbot/bin/python'} 14:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:54:42 INFO - Installing collected packages: mozsystemmonitor 14:54:42 INFO - Running setup.py install for mozsystemmonitor 14:54:42 INFO - Successfully installed mozsystemmonitor 14:54:42 INFO - Cleaning up... 14:54:42 INFO - Return code: 0 14:54:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:54:42 INFO - Using env: {'DISPLAY': ':0', 14:54:42 INFO - 'HOME': '/home/cltbld', 14:54:42 INFO - 'LANG': 'en_US.UTF-8', 14:54:42 INFO - 'LANGUAGE': 'en_US:en', 14:54:42 INFO - 'LOGNAME': 'cltbld', 14:54:42 INFO - 'MAIL': '/var/mail/cltbld', 14:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:42 INFO - 'MOZ_NO_REMOTE': '1', 14:54:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:42 INFO - 'NO_EM_RESTART': '1', 14:54:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:42 INFO - 'PWD': '/builds/slave/test', 14:54:42 INFO - 'SHELL': '/bin/bash', 14:54:42 INFO - 'SHLVL': '1', 14:54:42 INFO - 'TERM': 'linux', 14:54:42 INFO - 'TMOUT': '86400', 14:54:42 INFO - 'USER': 'cltbld', 14:54:42 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:42 INFO - '_': '/tools/buildbot/bin/python'} 14:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:42 INFO - Downloading/unpacking jsonschema==2.5.1 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:54:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:44 INFO - Downloading functools32-3.2.3-2.tar.gz 14:54:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:54:44 INFO - warning: no files found matching '*.txt' 14:54:44 INFO - no previously-included directories found matching 'build' 14:54:44 INFO - no previously-included directories found matching 'dist' 14:54:44 INFO - no previously-included directories found matching '.git*' 14:54:44 INFO - Installing collected packages: jsonschema, functools32 14:54:44 INFO - Running setup.py install for functools32 14:54:45 INFO - warning: no files found matching '*.txt' 14:54:45 INFO - no previously-included directories found matching 'build' 14:54:45 INFO - no previously-included directories found matching 'dist' 14:54:45 INFO - no previously-included directories found matching '.git*' 14:54:45 INFO - Successfully installed jsonschema functools32 14:54:45 INFO - Cleaning up... 14:54:45 INFO - Return code: 0 14:54:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:45 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:45 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 14:54:45 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 14:54:45 INFO - Using env: {'DISPLAY': ':0', 14:54:45 INFO - 'HOME': '/home/cltbld', 14:54:45 INFO - 'LANG': 'en_US.UTF-8', 14:54:45 INFO - 'LANGUAGE': 'en_US:en', 14:54:45 INFO - 'LOGNAME': 'cltbld', 14:54:45 INFO - 'MAIL': '/var/mail/cltbld', 14:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:45 INFO - 'MOZ_NO_REMOTE': '1', 14:54:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:45 INFO - 'NO_EM_RESTART': '1', 14:54:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:45 INFO - 'PWD': '/builds/slave/test', 14:54:45 INFO - 'SHELL': '/bin/bash', 14:54:45 INFO - 'SHLVL': '1', 14:54:45 INFO - 'TERM': 'linux', 14:54:45 INFO - 'TMOUT': '86400', 14:54:45 INFO - 'USER': 'cltbld', 14:54:45 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:45 INFO - '_': '/tools/buildbot/bin/python'} 14:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:54:45 INFO - Cleaning up... 14:54:45 INFO - Return code: 0 14:54:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:45 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:45 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 14:54:45 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 14:54:45 INFO - Using env: {'DISPLAY': ':0', 14:54:45 INFO - 'HOME': '/home/cltbld', 14:54:45 INFO - 'LANG': 'en_US.UTF-8', 14:54:45 INFO - 'LANGUAGE': 'en_US:en', 14:54:45 INFO - 'LOGNAME': 'cltbld', 14:54:45 INFO - 'MAIL': '/var/mail/cltbld', 14:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:45 INFO - 'MOZ_NO_REMOTE': '1', 14:54:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:45 INFO - 'NO_EM_RESTART': '1', 14:54:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:45 INFO - 'PWD': '/builds/slave/test', 14:54:45 INFO - 'SHELL': '/bin/bash', 14:54:45 INFO - 'SHLVL': '1', 14:54:45 INFO - 'TERM': 'linux', 14:54:45 INFO - 'TMOUT': '86400', 14:54:45 INFO - 'USER': 'cltbld', 14:54:45 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:45 INFO - '_': '/tools/buildbot/bin/python'} 14:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:45 INFO - Downloading/unpacking blobuploader==1.2.4 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:47 INFO - Downloading blobuploader-1.2.4.tar.gz 14:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:54:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:54:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:48 INFO - Downloading docopt-0.6.1.tar.gz 14:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:54:48 INFO - Installing collected packages: blobuploader, requests, docopt 14:54:48 INFO - Running setup.py install for blobuploader 14:54:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:54:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:54:48 INFO - Running setup.py install for requests 14:54:48 INFO - Running setup.py install for docopt 14:54:48 INFO - Successfully installed blobuploader requests docopt 14:54:48 INFO - Cleaning up... 14:54:48 INFO - Return code: 0 14:54:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:48 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:48 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 14:54:48 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 14:54:48 INFO - Using env: {'DISPLAY': ':0', 14:54:48 INFO - 'HOME': '/home/cltbld', 14:54:48 INFO - 'LANG': 'en_US.UTF-8', 14:54:48 INFO - 'LANGUAGE': 'en_US:en', 14:54:48 INFO - 'LOGNAME': 'cltbld', 14:54:48 INFO - 'MAIL': '/var/mail/cltbld', 14:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:48 INFO - 'MOZ_NO_REMOTE': '1', 14:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:48 INFO - 'NO_EM_RESTART': '1', 14:54:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:48 INFO - 'PWD': '/builds/slave/test', 14:54:48 INFO - 'SHELL': '/bin/bash', 14:54:48 INFO - 'SHLVL': '1', 14:54:48 INFO - 'TERM': 'linux', 14:54:48 INFO - 'TMOUT': '86400', 14:54:48 INFO - 'USER': 'cltbld', 14:54:48 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:48 INFO - '_': '/tools/buildbot/bin/python'} 14:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:49 INFO - Running setup.py (path:/tmp/pip-vium5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:49 INFO - Running setup.py (path:/tmp/pip-9XbPEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:49 INFO - Running setup.py (path:/tmp/pip-A4roLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:49 INFO - Running setup.py (path:/tmp/pip-wbFjzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:49 INFO - Running setup.py (path:/tmp/pip-jJ8Umn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:49 INFO - Running setup.py (path:/tmp/pip-qJcVrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:49 INFO - Running setup.py (path:/tmp/pip-qtvXvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:49 INFO - Running setup.py (path:/tmp/pip-lwaRBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:49 INFO - Running setup.py (path:/tmp/pip-YzsjvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:49 INFO - Running setup.py (path:/tmp/pip-p5IH3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:49 INFO - Running setup.py (path:/tmp/pip-vl29oJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:49 INFO - Running setup.py (path:/tmp/pip-uUB_qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:49 INFO - Running setup.py (path:/tmp/pip-_5DRmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:49 INFO - Running setup.py (path:/tmp/pip-bYfqWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:50 INFO - Running setup.py (path:/tmp/pip-cpBwgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:50 INFO - Running setup.py (path:/tmp/pip-cb2G5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:50 INFO - Running setup.py (path:/tmp/pip-FFlPrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:54:50 INFO - Running setup.py install for manifestparser 14:54:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:54:50 INFO - Running setup.py install for mozcrash 14:54:50 INFO - Running setup.py install for mozdebug 14:54:50 INFO - Running setup.py install for mozdevice 14:54:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:54:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:54:50 INFO - Running setup.py install for mozfile 14:54:50 INFO - Running setup.py install for mozhttpd 14:54:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:54:50 INFO - Running setup.py install for mozinfo 14:54:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:54:50 INFO - Running setup.py install for mozInstall 14:54:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Running setup.py install for mozleak 14:54:51 INFO - Running setup.py install for mozlog 14:54:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Running setup.py install for moznetwork 14:54:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Running setup.py install for mozprocess 14:54:51 INFO - Running setup.py install for mozprofile 14:54:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Running setup.py install for mozrunner 14:54:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:54:51 INFO - Running setup.py install for mozscreenshot 14:54:51 INFO - Running setup.py install for moztest 14:54:51 INFO - Running setup.py install for mozversion 14:54:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:54:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:54:52 INFO - Cleaning up... 14:54:52 INFO - Return code: 0 14:54:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:52 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:52 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 14:54:52 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 14:54:52 INFO - Using env: {'DISPLAY': ':0', 14:54:52 INFO - 'HOME': '/home/cltbld', 14:54:52 INFO - 'LANG': 'en_US.UTF-8', 14:54:52 INFO - 'LANGUAGE': 'en_US:en', 14:54:52 INFO - 'LOGNAME': 'cltbld', 14:54:52 INFO - 'MAIL': '/var/mail/cltbld', 14:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:52 INFO - 'MOZ_NO_REMOTE': '1', 14:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:52 INFO - 'NO_EM_RESTART': '1', 14:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:52 INFO - 'PWD': '/builds/slave/test', 14:54:52 INFO - 'SHELL': '/bin/bash', 14:54:52 INFO - 'SHLVL': '1', 14:54:52 INFO - 'TERM': 'linux', 14:54:52 INFO - 'TMOUT': '86400', 14:54:52 INFO - 'USER': 'cltbld', 14:54:52 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:52 INFO - '_': '/tools/buildbot/bin/python'} 14:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:52 INFO - Running setup.py (path:/tmp/pip-rpLNn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:52 INFO - Running setup.py (path:/tmp/pip-V2F62N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:52 INFO - Running setup.py (path:/tmp/pip-GgKckz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:52 INFO - Running setup.py (path:/tmp/pip-4YjVlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:52 INFO - Running setup.py (path:/tmp/pip-c9XyIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:52 INFO - Running setup.py (path:/tmp/pip-3o3JE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:52 INFO - Running setup.py (path:/tmp/pip-eHTP5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:52 INFO - Running setup.py (path:/tmp/pip-f308fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:52 INFO - Running setup.py (path:/tmp/pip-SC2C7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:52 INFO - Running setup.py (path:/tmp/pip-wyDnXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:52 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)) 14:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:52 INFO - Running setup.py (path:/tmp/pip-1Shg9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:53 INFO - Running setup.py (path:/tmp/pip-trJ9B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:53 INFO - Running setup.py (path:/tmp/pip-4HxBgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:53 INFO - Running setup.py (path:/tmp/pip-LUurj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:53 INFO - Running setup.py (path:/tmp/pip-EKCNtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:53 INFO - Running setup.py (path:/tmp/pip-b80ltn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:53 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)) 14:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:53 INFO - Running setup.py (path:/tmp/pip-lZUvMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:53 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)) 14:54:53 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)) 14:54:53 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)) 14:54:53 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)) 14:54:53 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)) 14:54:53 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)) 14:54:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54:53 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 14:54:53 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 14:54:53 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 14:54:53 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 14:54:53 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 14:54:53 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 14:54:55 INFO - Downloading blessings-1.6.tar.gz 14:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:54:55 INFO - Installing collected packages: blessings 14:54:55 INFO - Running setup.py install for blessings 14:54:55 INFO - Successfully installed blessings 14:54:55 INFO - Cleaning up... 14:54:55 INFO - Return code: 0 14:54:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:54:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:55 INFO - Reading from file tmpfile_stdout 14:54:55 INFO - Current package versions: 14:54:55 INFO - argparse == 1.2.1 14:54:55 INFO - blessings == 1.6 14:54:55 INFO - blobuploader == 1.2.4 14:54:55 INFO - docopt == 0.6.1 14:54:55 INFO - functools32 == 3.2.3-2 14:54:55 INFO - jsonschema == 2.5.1 14:54:55 INFO - manifestparser == 1.1 14:54:55 INFO - mozInstall == 1.12 14:54:55 INFO - mozcrash == 0.17 14:54:55 INFO - mozdebug == 0.1 14:54:55 INFO - mozdevice == 0.48 14:54:55 INFO - mozfile == 1.2 14:54:55 INFO - mozhttpd == 0.7 14:54:55 INFO - mozinfo == 0.9 14:54:55 INFO - mozleak == 0.1 14:54:55 INFO - mozlog == 3.3 14:54:55 INFO - moznetwork == 0.27 14:54:55 INFO - mozprocess == 0.23 14:54:55 INFO - mozprofile == 0.28 14:54:55 INFO - mozrunner == 6.12 14:54:55 INFO - mozscreenshot == 0.1 14:54:55 INFO - mozsystemmonitor == 0.3 14:54:55 INFO - moztest == 0.7 14:54:55 INFO - mozversion == 1.4 14:54:55 INFO - psutil == 3.1.1 14:54:55 INFO - requests == 1.2.3 14:54:55 INFO - wsgiref == 0.1.2 14:54:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:55 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')]} 14:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:55 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')]} 14:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:55 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:55 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 14:54:55 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 14:54:55 INFO - Using env: {'DISPLAY': ':0', 14:54:55 INFO - 'HOME': '/home/cltbld', 14:54:55 INFO - 'LANG': 'en_US.UTF-8', 14:54:55 INFO - 'LANGUAGE': 'en_US:en', 14:54:55 INFO - 'LOGNAME': 'cltbld', 14:54:55 INFO - 'MAIL': '/var/mail/cltbld', 14:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:55 INFO - 'MOZ_NO_REMOTE': '1', 14:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:55 INFO - 'NO_EM_RESTART': '1', 14:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:55 INFO - 'PWD': '/builds/slave/test', 14:54:55 INFO - 'SHELL': '/bin/bash', 14:54:55 INFO - 'SHLVL': '1', 14:54:55 INFO - 'TERM': 'linux', 14:54:55 INFO - 'TMOUT': '86400', 14:54:55 INFO - 'USER': 'cltbld', 14:54:55 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:55 INFO - '_': '/tools/buildbot/bin/python'} 14:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 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)) 14:54:56 INFO - Cleaning up... 14:54:56 INFO - Return code: 0 14:54:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:56 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 0x10dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', '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 14:54:56 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 14:54:56 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 14:54:56 INFO - Using env: {'DISPLAY': ':0', 14:54:56 INFO - 'HOME': '/home/cltbld', 14:54:56 INFO - 'LANG': 'en_US.UTF-8', 14:54:56 INFO - 'LANGUAGE': 'en_US:en', 14:54:56 INFO - 'LOGNAME': 'cltbld', 14:54:56 INFO - 'MAIL': '/var/mail/cltbld', 14:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:56 INFO - 'MOZ_NO_REMOTE': '1', 14:54:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:56 INFO - 'NO_EM_RESTART': '1', 14:54:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:56 INFO - 'PWD': '/builds/slave/test', 14:54:56 INFO - 'SHELL': '/bin/bash', 14:54:56 INFO - 'SHLVL': '1', 14:54:56 INFO - 'TERM': 'linux', 14:54:56 INFO - 'TMOUT': '86400', 14:54:56 INFO - 'USER': 'cltbld', 14:54:56 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:56 INFO - '_': '/tools/buildbot/bin/python'} 14:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:54:56 INFO - Cleaning up... 14:54:56 INFO - Return code: 0 14:54:56 INFO - Running post-action listener: _resource_record_post_action 14:54:56 INFO - Running post-action listener: _start_resource_monitoring 14:54:56 INFO - Starting resource monitoring. 14:54:56 INFO - [mozharness: 2016-08-25 21:54:56.296184Z] Finished create-virtualenv step (success) 14:54:56 INFO - [mozharness: 2016-08-25 21:54:56.296615Z] Running install step. 14:54:56 INFO - Running pre-action listener: _resource_record_pre_action 14:54:56 INFO - Running main action method: install 14:54:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:56 INFO - Reading from file tmpfile_stdout 14:54:56 INFO - Detecting whether we're running mozinstall >=1.0... 14:54:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:54:56 INFO - Reading from file tmpfile_stdout 14:54:56 INFO - Output received: 14:54:56 INFO - Usage: mozinstall [options] installer 14:54:56 INFO - Options: 14:54:56 INFO - -h, --help show this help message and exit 14:54:56 INFO - -d DEST, --destination=DEST 14:54:56 INFO - Directory to install application into. [default: 14:54:56 INFO - "/builds/slave/test"] 14:54:56 INFO - --app=APP Application being installed. [default: firefox] 14:54:56 INFO - mkdir: /builds/slave/test/build/application 14:54:56 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'] 14:54:56 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 14:55:12 INFO - Reading from file tmpfile_stdout 14:55:12 INFO - Output received: 14:55:12 INFO - /builds/slave/test/build/application/firefox/firefox 14:55:12 INFO - Running post-action listener: _resource_record_post_action 14:55:12 INFO - [mozharness: 2016-08-25 21:55:12.566319Z] Finished install step (success) 14:55:12 INFO - [mozharness: 2016-08-25 21:55:12.566594Z] Running run-tests step. 14:55:12 INFO - Running pre-action listener: _resource_record_pre_action 14:55:12 INFO - Running main action method: run_tests 14:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:55:12 INFO - Python 2.7.3 14:55:12 INFO - Return code: 0 14:55:12 INFO - grabbing minidump binary from tooltool 14:55:12 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')]} 14:55:12 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 0x139b3b0>, '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 0x139c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x139c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:55:12 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 14:55:12 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 14:55:12 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 14:55:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:55:12 INFO - Return code: 0 14:55:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:55:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:55:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:55:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:55:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:55:12 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/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 14:55:12 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/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 14:55:12 INFO - Using env: {'DISPLAY': ':0', 14:55:12 INFO - 'HOME': '/home/cltbld', 14:55:12 INFO - 'LANG': 'en_US.UTF-8', 14:55:12 INFO - 'LANGUAGE': 'en_US:en', 14:55:12 INFO - 'LOGNAME': 'cltbld', 14:55:12 INFO - 'MAIL': '/var/mail/cltbld', 14:55:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:55:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:12 INFO - 'MOZ_NO_REMOTE': '1', 14:55:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:55:12 INFO - 'NO_EM_RESTART': '1', 14:55:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:12 INFO - 'PWD': '/builds/slave/test', 14:55:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:55:12 INFO - 'SHELL': '/bin/bash', 14:55:12 INFO - 'SHLVL': '1', 14:55:12 INFO - 'TERM': 'linux', 14:55:12 INFO - 'TMOUT': '86400', 14:55:12 INFO - 'USER': 'cltbld', 14:55:12 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520', 14:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:12 INFO - '_': '/tools/buildbot/bin/python'} 14:55:12 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/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 14:55:12 INFO - mozversion application_buildid: 20160825114018 14:55:12 INFO - mozversion application_changeset: e29cdbb036abf01b465dcbae14d6252feb115102 14:55:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:55:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:55:12 INFO - mozversion application_name: Firefox 14:55:12 INFO - mozversion application_remotingname: firefox-dev 14:55:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:55:12 INFO - mozversion application_vendor: Mozilla 14:55:12 INFO - mozversion application_version: 50.0a2 14:55:12 INFO - mozversion platform_buildid: 20160825114018 14:55:12 INFO - mozversion platform_changeset: e29cdbb036abf01b465dcbae14d6252feb115102 14:55:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:55:12 INFO - mozversion platform_version: 50.0a2 14:55:12 INFO - using testdate: 1472162112 14:55:12 INFO - actual date: 1472162112 14:55:12 INFO - starting webserver on 'localhost:43877' 14:55:12 INFO - SUITE-START | Running 2 tests 14:55:12 INFO - TEST-START | dromaeo_css 14:55:12 INFO - operating with platform_type : linux_ 14:55:12 INFO - Initialising browser for dromaeo_css test... 14:55:12 INFO - TEST-INFO | started process 10239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg4WOJa/profile http://localhost:43877/getInfo.html) 14:55:16 INFO - PROCESS | 10239 | __metrics Screen width/height:1600/1200 14:55:16 INFO - PROCESS | 10239 | colorDepth:24 14:55:16 INFO - PROCESS | 10239 | Browser inner width/height: 1024/697 14:55:16 INFO - PROCESS | 10239 | __metrics 14:55:17 INFO - PROCESS | 10239 | 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" 14:55:17 INFO - PROCESS | 10239 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:55:18 INFO - TEST-INFO | 10239: exit 0 14:55:18 INFO - Browser initialized. 14:55:18 INFO - Running cycle 1/1 for dromaeo_css test... 14:55:18 INFO - TEST-INFO | started process 10328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg4WOJa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:30 INFO - PROCESS | 10328 | RSS: Main: 145264640 14:55:30 INFO - PROCESS | 10328 | 14:56:21 INFO - PROCESS | 10328 | 1472162181169 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] 14:58:30 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43877/tests/dromaeo/cssquery-ext.html) 14:58:31 INFO - PROCESS | 10328 | RSS: Main: 221310976 14:58:31 INFO - PROCESS | 10328 | 15:01:33 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-ext.html (next: http://localhost:43877/tests/dromaeo/cssquery-jquery.html) 15:01:34 INFO - PROCESS | 10328 | RSS: Main: 230522880 15:01:34 INFO - PROCESS | 10328 | 15:04:36 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43877/tests/dromaeo/cssquery-mootools.html) 15:04:36 INFO - PROCESS | 10328 | RSS: Main: 176136192 15:04:36 INFO - PROCESS | 10328 | 15:07:38 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43877/tests/dromaeo/cssquery-prototype.html) 15:07:39 INFO - PROCESS | 10328 | RSS: Main: 176975872 15:07:39 INFO - PROCESS | 10328 | 15:10:42 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43877/tests/dromaeo/cssquery-yui.html) 15:10:42 INFO - PROCESS | 10328 | RSS: Main: 178634752 15:10:42 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | Cycle 1(1): loaded http://localhost:43877/tests/dromaeo/cssquery-yui.html (next: http://localhost:43877/tests/dromaeo/cssquery-dojo.html) 15:13:45 INFO - PROCESS | 10328 | RSS: Main: 264814592 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | __start_tp_report 15:13:45 INFO - PROCESS | 10328 | _x_x_mozilla_page_load 15:13:45 INFO - PROCESS | 10328 | _x_x_mozilla_page_load_details 15:13:45 INFO - PROCESS | 10328 | |i|pagename|runs| 15:13:45 INFO - PROCESS | 10328 | |0;dojo.html;3278.164068162618;3311.7681762276634;3321.9501707474383;3343.1474973881654;3360.1599600099976;5603.467670571295;5710.857228569288;5727.341355744091;5732.885342293157;5736;5618.15727640854;5670.206171136044;5676.205331253617;5679.290088738908;5687.971560142193;4970.6520543561965;5136.02415541047;5140.383154021522;5186.688798672073;5218.895622087556;5043.319151914481;5137.12668846297;5179.430262671103;5188.974055129719;5194.71429071401;5065.544101030907;5105.438401775802;5118.667286626376;5119.411267704206;5180.352455943007;5202.479752024805;5212.556932660728;5215.24378965048;5219.739013049332;5252.185911183773;5039.496050394968;5042.142835717937;5086.440491545927;5114.488551144893;5120.257562653432;5537.529310008614;5544.77820887164;5552.278203833475;5564.582656300794;5574.944250557483;6016.037434010537;6062.514998800129;6063.211782468251;6073.665948372838;6093.969530152365;5850.268716410449;5961.850953726148;6006.699665016732;6030.155778191057;6110.335195530717;5620.100783874561;5636.830895073154;5646.943530564683;5648.181013752983;5686.061799803069;6140.232470941132;6142.662153581596;6145.293291271471;6145.784897528565;6171.321154672983;5787.739551720183;5902;5959.314678811992;5961.642301461926;6045.244344456943;6096.4649490713355;6105.564615568199;6106.25420309353;6106.328303886569;6112.484056122425;6063.211782468339;6066.090086486974;6068.302145253264;6070.878582428327;6092.847678807977;5809.099589563624;5899.233099697012;5975.701214939236;5979.820605381846;6038.094285857069;4620.145273124483;4627.213373726475;4635.281531362644;4648.140094082605;4655.930161047587;4138.503379594407;4151.875443736692;4156.854510092132;4160.45914031174;4189.664826813848;5572.526335261548;5689.715514224355;5749.1663708762;5760.769569217268;5762.510186634099;6091.725872335755;6094.146819445281;6096.908546371719;6098.481629061579;6126.203593532812;5768.7692492300675;5795.0727883539275;5801.6519008859605;5802.767889284466;5805.477507024388;5019.0965626187635;5164.457731938168;5212.843614691489;5216.947830521722;5245.082110630625;6262.530310226751;6297.2128483939505;6311.684415779193;6314.494840412756;6328.873422531524;6018.127371531191;6033.12519684652;6083.1483592298;6090.390960903874;6090.725917333642;6098.847528811815;6258.749650013949;6300.991841305369;6328.367163283635;6377;6072.787452439144;6073.848153796191;6085.148079268817;6086.360932102066;6105.14527966094;6127.785527506516;6135.262573504107;6182.783602573888;6187.412195841388;6187.84530386744;5802.680852552981;5876.647401155944;5918.053111502139;5941.168236446987;5989.850253743692;5946.07835785454;5947.3457919627945;5952.404759524013;5953.374895636069;5953.940460595339;5771.942280577308;5781.971090144522;5823.563232757645;5831.446012628793;5846.356900741001;5773.971130144322;5865.677387743544;5923.526117910643;5927.644341339534;6004.5796794225735;5777.133429985536;5811.56413269015;5811.622244554091;5824.388439213822;5831.533477321889 15:13:45 INFO - PROCESS | 10328 | |1;ext.html;597.354856754705;600.3846057790765;632.9999999999997;640.8686219325039;647.2524234509142;45832.54167458324;47412;47424.76287618561;47431;51735.74132129343;48828.75585622067;49159.262611060774;49333.26000110001;49357;49412.75293623536;8467.745967620982;9139.771505712344;9278.582463789146;9284.396514226588;10935.453227338641;10966.342019478818;11426.200165988384;11428.599999000036;11430.199886007982;11433.085353171726;11507.539698412096;11561.826572601418;11562.653120406401;11563.653090407259;11572.305661660284;8618.784530386727;10994.615188468366;11294.774104517946;11412.20114591979;11483.42582870853;11171.273867198606;11261.831072533918;11265.26775759573;11265.831012534818;11269.323840569592;45546.08907821825;46009.30986035212;46173.53826461726;46219.306710399374;46349.53650463486;3768.208676178006;3805.6574908258112;3850.8074596270076;3941.9802900985596;4147.668186545069;35748.64251357479;35860.103497412514;35862.641373586455;35890.82054589737;36192.457113143326;4712.505186955389;4736.455307639631;4780.49804770501;4812.085703716318;4885.755712214411;376125;378355.10822445987;378505;378967;379138;37895.24209515794;65143.674281628286;65448.34551654423;65538.67230663817;65557.67221163864;39041.60958390436;40757.38863917044;41292.1741565167;41339.7933010333;41346.1730765383;16029.19854007295;16199.352025918832;16218.837811621968;16232.59418514523;16235.107069111313;11450.37022963745;12633.873661263453;12800.423980920881;12841.422136003903;12894.484220631257;9337.346385753068;9348.672796452227;9351.438913665066;9399.060093990684;9402.95298523503;3449.2756521130846;3540.8577810424836;3540.964590354115;3575.248128417109;3620.420732682758;1081.0648788797632;1094.9014588687057;1153.5616465743133;1234.1467032559449;1239.4670291774444;35416;35807.64192358095;35954.46068308925;35989.64010359915;35995.64004359975;1170.2218025013271;1174.5536696055615;1187.4419023059322;1205.2045649871043;1227.6071657069651;18277.3602923897;18363.265469381342;18375.77323429315;18477.630447391246;18518.537036574195;632510;634789;634876;636047;640906;66721.6663916687;67236.32763672301;67368.6631566839;67403.3259667397;67423.32576674268;1318.4660212613908;1432.0119117808679;1462.3419461242484;1464.0483685604443;1497.8726808221206;34517.654823452445;36357.45463818098;36417.45373819342;36475.27049459049;36705.44941825928;54406.455935440135;54964.45035549593;55104.72447637736;55165.72417138049;55328.72335638296;455001;455197;455557;457396;457455;30363.392732145676;31592.210194745316;31709;33053.17367065843;33205.16987075342;52475.21287180619;52609.736951315;52662.42012739624;52705;52758.73620632026;44244.336334955646;44925.77537112294;45218.32172517349;45718;47093.29360059533;44347.33478997882;45093.774531127136;45115.37847333194;45920;46448.071038579714;44018;44863;44906.55093449154;45004.77497612622;45245.77377113225;38211.61788382081;39417.40873886837;39745;39824.14690313066;40555.39166912558;29270.41459170762;29950.170729770438;30088;30288.394232115676;30312.242193945327 15:13:45 INFO - PROCESS | 10328 | |2;jquery.html;385.3641491538959;401.80713257636336;416.41909536197005;422.6407553579457;427.1648926348988;16130.516084517469;19772.60454790903;20321.695174572375;20565.794342056575;21872;19685.50786230345;20231.696524552106;20496.282630107948;21140.57718845622;21385.893070534665;5314.654547454423;5477.1510415885605;5504.146857237135;5538.75075621596;5544.500994910457;4149.2531344358;5426.077566813651;5492.615516913797;5493.423190564973;5493.478119578634;4326;6628.668566571652;6662.1339225900565;6748.156480439945;6764.59412435253;6079.817605471843;6152.046432802923;6518.18522684662;6758.391744742972;6767.052612634235;5969.940300597025;5979.252593425822;5985;5990.400959904018;5998.580099393044;16396.75404868928;24797.380065498506;25126.12058577941;25221.243362698966;25652.35869103269;2342.4862705941086;2410.288964755401;2472.0606169655416;2527.2544599343055;2587.6452428290354;10667.78664426715;10668.253222274443;10718.303310284884;11015.449227538591;11178.944105279503;2048.7131801547794;2070.710100585919;2073.5645514441985;2077.096463038568;2128.31893793986;57465.71267143638;86266;86329.56835215847;87581.56209218912;88499.55750221208;11291.040261577858;11344.943275283571;11429.142814288962;11608.187426880037;11776.882231177578;16453.42413015539;16459.670806583803;16476.588085297724;16505.33978640865;16520.339186432648;8043.678252869937;8279.337652987868;8336.874946875801;8435.662573496991;8480.830383392298;7837.412194085467;7855.13593504714;7904.446688731849;8020.438569300093;8032.839343213103;4942.654014219042;4948.851534453972;5026.472220416804;5027.924581131286;5135.512126347991;2022.595480903825;2065.793420657952;2085.9478513037297;2093.5394213273057;2100.1308835136742;473.54303097510376;806.9072056713553;938.6057855700623;960.2366118935463;978.0268632710356;11937.224080434744;12710;13097.738045239043;13139.34303284832;13223.537176198786;493.79260710500955;494.47091611974844;495.4549995005537;500.41451501743046;501.9648624596316;6708.127943367429;6850.280720524373;6991.370776630026;7068.964655176691;7183.640817959081;529053;540793;543853;556504;572166;97541;99842.50078749559;100387;103330.48334758429;106502.46748766207;458.41094193961266;489.41514889707173;496.9652124351333;498.16805934090155;498.9925151122736;10303.53634086453;12438.502459901865;12484;12485.687857803265;12748.171368860994;47203;47247.29129063136;47458.28812567883;47930.520694794;48077.75961120312;66052.33947660463;66399;67434.3256567428;67636;67954.66022669854;8251.95874020626;8568.91431085706;8874.822503550022;8923.063078376677;9001.729948101567;7213.278672132745;10256.794864102825;13594.912407007218;13711.177329360271;14073.085249458753;12071.094667899766;12167.087468440079;12311.75376492483;12518;12611.30637814929;8534.762459443471;11263.098952083978;11594.826077609012;11849.703757406134;12038.75922481563;12278.447469863699;12292.81560776607;12506.812397814221;12561.183523070971;12578.811317830057;8915.910840891767;8995.055519170393;9013;9141.177294043568;9149.22231610294;10208.489575521342;12153;12194.878051219373;12316.261024338568;12318.568850090203 15:13:45 INFO - PROCESS | 10328 | |3;mootools.html;1533.072491142858;1598.896071755336;1746.7205247160457;1839.84361329287;1901.3725470594713;9221.63113475461;9975.351602145856;9979.50102494876;9979.600815967353;9981.151602113829;8377.078521362646;9086.909130908689;9358.953205233964;9389.859152112707;9695.127438530531;2483.1557270527974;2513.710923243832;2516.899324027046;2517.622356646499;2520.861352625605;2462.802975761944;2537.7716005559496;2539.073238268037;2542.427953710419;2545.4018305698146;2467.6668649732237;2473.678421805163;2508.234988328559;2515.019142534413;2517.773400393964;2413.4087148648605;2428.429319110008;2428.781409673129;2432.6959130108735;2436.11081955086;2389.9522009559887;2391.5575618510456;2404.1465279825584;2410.783029527351;2443.9389015274583;9854.901450985542;9919.652812151613;9941.850872236922;9954;9961.053699898497;5444.128939369722;5659.207710920475;5672.205891175238;5673.773049078032;5675.035244008528;3666.5600127984544;3745.887623371276;3781.3193625147464;3785.6971442284553;3802.296575133609;1131.1459847814929;1169.1698893785422;1189.8631657359342;1193.773183095209;1199.5741511763285;13789.655258618515;13801.240931748749;13814.930925345308;13823.377947992365;13827.792583111363;4762.452317983476;4827.396575428072;4839.975800120977;4945.406551213878;5005.574526165244;11477.540898363972;11504.884951150549;11507.19449638534;11517;11527.365994870195;5640.407757185437;5661.377248502661;5664.575156863251;5676.035074037423;5684.602077854511;4462.085272519126;4963.759060234941;5094.6943183408375;5131.384233891957;5220.034293655686;4263.488381394252;4309.892252693708;4364.258076127066;4392.868213953587;4406.9779651101535;1235.3452670084869;1236.863944966053;1246.3581255653346;1261.470182523324;1263.2231177825572;1041.5833666533447;1043.7025447747355;1069.5775168808277;1070.6573896353093;1076.9030787229187;6062.484688801413;6194.628322300677;6288.52836037299;6378.107065010902;6576.605403675795;984.5667906121427;997.7006897930592;997.8403455447094;1007.3653598233067;1023.3194925374543;4111.8560850370095;4125.1131006833675;4221.493420789525;4225.471816022997;4315.913681726348;10995.120390368751;11574.594889178838;11614.361210133524;11623.6512904613;11643.301401915744;11329;11499.712507187387;11545.307281563133;11550.018248448809;11553.826692599618;9098.181163695166;9222.769430764285;9236.168744813;9241.768955776159;9243.121903419298;5919.6744179070565;5989.101634754822;6030.547708921936;6086.178365920544;6120.7551697931585;10874.94562527214;11139.610113645984;11191;11373.488193031168;11446.198766086294;11292.887071129513;11456.885431145914;11531.596394126165;11576;11584.594539191088;7361.521501102411;7601.125870524739;7606.391488680782;8129.105798362294;8237.588120594066;8855.822883542422;9149.542522873962;9439.103285187895;9490.525473726424;9764.365316254421;8645.221930026328;8664.723264120215;8757;8819.162179592928;8862.689805856764;8512.446690965067;8529.786755331166;8588.828223435372;8661.740147795575;8731.738047858575;7779.649915753692;7793.06483222017;7843.372530197685;7848.293653571206;7848.9215107848195;5521.613487055867;5614.63504872182;5779.335376431596;5800;5842.2405087338375;5500.367457742411;5563.499285064364;5649.12438572022;5694.515966142759;5741.741621626953 15:13:45 INFO - PROCESS | 10328 | |4;prototype.html;349.5578093711455;353.58453816765297;357.07339454116567;357.14820153932897;361.71424574586064;5550.306211723535;5550.972245138773;5556.638818476797;5564.360098588663;5574.714367109486;5475.753591088401;5484.232207490954;5494.340679118502;5494.80768173114;5530;3859.691224702031;4075.7829977628685;4326.935095973563;4334.349847522865;4351.934720979188;4309.224339618867;4314.3097104463295;4321.243782338094;4323;4342.283523218681;4284.33592793116;4314.956850431487;4318.52496225415;4325.832025353153;4329.3332067154215;4132.194222126686;4237.872863814106;4266.274733295348;4333.349997500401;4335.653147748172;3844.884653460401;3856.9807150964066;3858.6527212550723;3862.806859657006;3863.9227215455535;5346.385165705916;5347.6256662033675;5352.197170424468;5393.78424863005;5425.9186112208345;4730.526947305242;4918.188498897677;4923.606111511071;4927.482614325516;4945.5163450964565;5637.069883469223;5654.886902261932;5661.830145095612;5670.234518340013;5676.460736230051;4207.642350400189;4217.746935183868;4220.957790422118;4226.577342265779;4239.7492739641875;5603.803866864681;5656.171914042979;5756.683382413966;5830.300363956352;5860.472557469806;4266.402703621496;4283.443152390231;4296.484421869396;4325.178216138924;4327.913441731148;4359.455068116486;4381.5618438155925;4398.252297109435;4406.867793966187;4412.514623391425;4836.226203807374;4909.852704418873;4922.310876477296;4935.136351138536;4956.107900577858;5699.829005129853;5704.657720536698;5707.315122185364;5747.453991870766;5764.365919748824;1475;1501.5195137556095;1505.5784380373514;1507.2388443835794;1508.1705062215738;924.6347692661361;964.6286179820679;971.8580667715406;972.5234635028927;981.4651015196735;4861.781219845116;4876.4392094909535;4901.926471102936;4910.337104490848;4944.554990050912;5146.228065790161;5154.144507977126;5162.6128040397125;5168.250603662448;5172.422411164555;4585.403897493305;4834;4953.869835970568;4958.429780575208;4981.9252711209365;2731.9043833465735;2753.9810270199755;2761.8538306602936;2762.557990721475;2769.850512037522;5311.946880531145;5325.09473389517;5348.930213957224;5352.892942141136;5375.865603359947;5193.974030129825;5211.895762084813;5559.332880054339;6056.818295451144;6082.361352058058;6154.292256390572;6168.422315057256;6176.567640265138;6201.56589038772;6216.99999999991;5718.542516598579;5983.743413883133;5984.730687119177;6021.187139736079;6039.939600603938;5899.17411562373;5937.139114828412;5958.136070269873;5959.433853783884;5959.940400595939;5884.057971014493;6014.669193194416;6046.274447066205;6063.939360606338;6221.968890155701;4120.587941205856;4204.558521355336;4254.87235382939;4308.935365969575;4317.676174286878;5748.655080695172;5968.373320801427;5968.522518198447;5974.581779275582;5986.2217911671205;5762;5770.355448697102;5788.060999565193;5798.1302804579655;5802;5743.391850281928;5767.769289228384;5774.855628609318;5780.913286300793;5791.942080579141;5488.643238189505;5687.43125687428;5699.373068962494;5724.713764311685;5725.6850873202375;5720.570957178312;5774.0761478163295;5802.912956305743;5815.76736930518;5826.94173058264;5619.831405057855;5680.2047713321;5682.403347648437;5693.800716974886;5720.599558030898 15:13:45 INFO - PROCESS | 10328 | |5;yui.html;808.5795386399072;841.6044459104222;870.3428911172064;887.8890138732658;923.3167456082499;16620.25208865601;16633.334666613337;16652.58368540787;16678.166091695413;16755.74866377004;16285.430009949654;16306.510804675878;16470.83529164708;16480.42318518852;17215.655686886254;892.4823602310661;921.3228277216241;921.7188757428981;925.1950802801572;925.3707478914336;892.6072528087653;895.0557162193883;896.7937374403891;896.8296023755498;896.9865452018225;965.1844191658038;970.8009857979133;971.6696323250094;973.4013581647272;974.2644303550844;967.666155176463;967.7725734452433;968.2544440780595;968.6706519783237;968.6706519783272;705.5696025424487;709.0250905005618;710.2400431538256;710.4494017136709;710.7725527831096;17984.37054703092;18093.7285940711;18128.90935545327;18183.909080454512;18203.908980455144;15699.529014129594;16312.184390780532;16492.587685307844;16700.331986720517;17099.74350384745;12768.361581920866;12947.22316660994;13204;13398.19610823344;13403.530876419278;4038.474998250238;4093.5906409359122;4116.423700681907;4163.167366526707;4185.162967406501;65670.3432965669;68866.31133688649;72464.6376768118;78203.21796782073;99711;12290.508379664714;12698.238105713686;12707.174033687781;12789.552365667343;12864.935675321563;22311.219107331166;22339.55320893573;22468.55062898733;22500.437489062577;22671.319860404215;8908.688195913111;9298.256139508823;9317.813643727222;9337.673181438617;9553.713388598353;4925.11347957364;5121.667091639106;5128.974355128201;5267.920981185285;5313.54834839043;3775.452559378875;3895.0651843557907;3912.4131380292615;3940.054386947112;3943.211357728466;684.6918886501095;695.5652717051843;699.1156187422914;711.3384552366246;713.9143302803697;654.9214094308713;659.7525927777084;683.5898460923505;706.3642721550646;716.4948711158621;9907.900920990842;10193.388396696222;10329.793404132026;10344.896551034544;10376.273660843819;617.2777849915609;725.6661935509725;726.5422783646269;759.7151068349369;765.6478020110811;4974.726390048582;5100.923486147711;5146.408163061221;5169.53474187325;5198.428172900978;129321;138074;139436;140162;144426.27786861;119130;121163;121533;123908.38045809894;125110;62030;88183.55908220418;99968.50015749874;102203.48898255461;104718.476407619;9163.816723665355;9782.168515676247;9795.9020409795;9817.901820981699;9822.807587533936;47008.29487557621;54544;59484.405155949615;59548.40451595429;64837.05488835343;89944.5502772482;90725.54637226772;95089;97074;102322;15102.924485377504;15125;15974.201289935225;16071;16100.27548760332;15622.453214137451;16040.278187481357;16808.24362903648;17649.735253970943;18304.633907322044;15488.148151851756;15740.763888541911;15851;17002.829971700125;17023.659526809643;13809.654758631115;14980.250987450367;15072.09567425914;15233.162176080423;15430.382784688487;13974.790378144133;14644.19456929879;14921.048819353939;15073.397064117313;15130.848691512943;10763.623273185402;11007.339559626127;11208.159388045766;12012.579559715368;12276.386180690752;10814.646749858555;10959.397233151934;10993.395363255098;11263.436828158723;11668.124890633067 15:13:45 INFO - PROCESS | 10328 | __end_tp_report 15:13:45 INFO - PROCESS | 10328 | __start_cc_report 15:13:45 INFO - PROCESS | 10328 | _x_x_mozilla_cycle_collect,1012 15:13:45 INFO - PROCESS | 10328 | __end_cc_report 15:13:45 INFO - PROCESS | 10328 | __startTimestamp1472163225472__endTimestamp 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | ------- Summary: start ------- 15:13:45 INFO - PROCESS | 10328 | Number of tests: 6 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#0] dojo.html Cycles:165 Average:5626.70 Median:5811.56 stddev:636.62 (11.0%) stddev-sans-first:611.33 15:13:45 INFO - PROCESS | 10328 | Values: 3278.2 3311.8 3322.0 3343.1 3360.2 5603.5 5710.9 5727.3 5732.9 5736.0 5618.2 5670.2 5676.2 5679.3 5688.0 4970.7 5136.0 5140.4 5186.7 5218.9 5043.3 5137.1 5179.4 5189.0 5194.7 5065.5 5105.4 5118.7 5119.4 5180.4 5202.5 5212.6 5215.2 5219.7 5252.2 5039.5 5042.1 5086.4 5114.5 5120.3 5537.5 5544.8 5552.3 5564.6 5574.9 6016.0 6062.5 6063.2 6073.7 6094.0 5850.3 5961.9 6006.7 6030.2 6110.3 5620.1 5636.8 5646.9 5648.2 5686.1 6140.2 6142.7 6145.3 6145.8 6171.3 5787.7 5902.0 5959.3 5961.6 6045.2 6096.5 6105.6 6106.3 6106.3 6112.5 6063.2 6066.1 6068.3 6070.9 6092.8 5809.1 5899.2 5975.7 5979.8 6038.1 4620.1 4627.2 4635.3 4648.1 4655.9 4138.5 4151.9 4156.9 4160.5 4189.7 5572.5 5689.7 5749.2 5760.8 5762.5 6091.7 6094.1 6096.9 6098.5 6126.2 5768.8 5795.1 5801.7 5802.8 5805.5 5019.1 5164.5 5212.8 5216.9 5245.1 6262.5 6297.2 6311.7 6314.5 6328.9 6018.1 6033.1 6083.1 6090.4 6090.7 6098.8 6258.7 6301.0 6328.4 6377.0 6072.8 6073.8 6085.1 6086.4 6105.1 6127.8 6135.3 6182.8 6187.4 6187.8 5802.7 5876.6 5918.1 5941.2 5989.9 5946.1 5947.3 5952.4 5953.4 5953.9 5771.9 5782.0 5823.6 5831.4 5846.4 5774.0 5865.7 5923.5 5927.6 6004.6 5777.1 5811.6 5811.6 5824.4 5831.5 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#1] ext.html Cycles:180 Average:65593.87 Median:21491.79 stddev:133498.34 (621.2%) stddev-sans-first:133783.64 15:13:45 INFO - PROCESS | 10328 | Values: 597.4 600.4 633.0 640.9 647.3 45832.5 47412.0 47424.8 47431.0 51735.7 48828.8 49159.3 49333.3 49357.0 49412.8 8467.7 9139.8 9278.6 9284.4 10935.5 10966.3 11426.2 11428.6 11430.2 11433.1 11507.5 11561.8 11562.7 11563.7 11572.3 8618.8 10994.6 11294.8 11412.2 11483.4 11171.3 11261.8 11265.3 11265.8 11269.3 45546.1 46009.3 46173.5 46219.3 46349.5 3768.2 3805.7 3850.8 3942.0 4147.7 35748.6 35860.1 35862.6 35890.8 36192.5 4712.5 4736.5 4780.5 4812.1 4885.8 376125.0 378355.1 378505.0 378967.0 379138.0 37895.2 65143.7 65448.3 65538.7 65557.7 39041.6 40757.4 41292.2 41339.8 41346.2 16029.2 16199.4 16218.8 16232.6 16235.1 11450.4 12633.9 12800.4 12841.4 12894.5 9337.3 9348.7 9351.4 9399.1 9403.0 3449.3 3540.9 3541.0 3575.2 3620.4 1081.1 1094.9 1153.6 1234.1 1239.5 35416.0 35807.6 35954.5 35989.6 35995.6 1170.2 1174.6 1187.4 1205.2 1227.6 18277.4 18363.3 18375.8 18477.6 18518.5 632510.0 634789.0 634876.0 636047.0 640906.0 66721.7 67236.3 67368.7 67403.3 67423.3 1318.5 1432.0 1462.3 1464.0 1497.9 34517.7 36357.5 36417.5 36475.3 36705.4 54406.5 54964.5 55104.7 55165.7 55328.7 455001.0 455197.0 455557.0 457396.0 457455.0 30363.4 31592.2 31709.0 33053.2 33205.2 52475.2 52609.7 52662.4 52705.0 52758.7 44244.3 44925.8 45218.3 45718.0 47093.3 44347.3 45093.8 45115.4 45920.0 46448.1 44018.0 44863.0 44906.6 45004.8 45245.8 38211.6 39417.4 39745.0 39824.1 40555.4 29270.4 29950.2 30088.0 30288.4 30312.2 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#2] jquery.html Cycles:180 Average:31028.65 Median:23860.42 stddev:90573.43 (379.6%) stddev-sans-first:90798.29 15:13:45 INFO - PROCESS | 10328 | Values: 385.4 401.8 416.4 422.6 427.2 16130.5 19772.6 20321.7 20565.8 21872.0 19685.5 20231.7 20496.3 21140.6 21385.9 5314.7 5477.2 5504.1 5538.8 5544.5 4149.3 5426.1 5492.6 5493.4 5493.5 4326.0 6628.7 6662.1 6748.2 6764.6 6079.8 6152.0 6518.2 6758.4 6767.1 5969.9 5979.3 5985.0 5990.4 5998.6 16396.8 24797.4 25126.1 25221.2 25652.4 2342.5 2410.3 2472.1 2527.3 2587.6 10667.8 10668.3 10718.3 11015.4 11178.9 2048.7 2070.7 2073.6 2077.1 2128.3 57465.7 86266.0 86329.6 87581.6 88499.6 11291.0 11344.9 11429.1 11608.2 11776.9 16453.4 16459.7 16476.6 16505.3 16520.3 8043.7 8279.3 8336.9 8435.7 8480.8 7837.4 7855.1 7904.4 8020.4 8032.8 4942.7 4948.9 5026.5 5027.9 5135.5 2022.6 2065.8 2085.9 2093.5 2100.1 473.5 806.9 938.6 960.2 978.0 11937.2 12710.0 13097.7 13139.3 13223.5 493.8 494.5 495.5 500.4 502.0 6708.1 6850.3 6991.4 7069.0 7183.6 529053.0 540793.0 543853.0 556504.0 572166.0 97541.0 99842.5 100387.0 103330.5 106502.5 458.4 489.4 497.0 498.2 499.0 10303.5 12438.5 12484.0 12485.7 12748.2 47203.0 47247.3 47458.3 47930.5 48077.8 66052.3 66399.0 67434.3 67636.0 67954.7 8252.0 8568.9 8874.8 8923.1 9001.7 7213.3 10256.8 13594.9 13711.2 14073.1 12071.1 12167.1 12311.8 12518.0 12611.3 8534.8 11263.1 11594.8 11849.7 12038.8 12278.4 12292.8 12506.8 12561.2 12578.8 8915.9 8995.1 9013.0 9141.2 9149.2 10208.5 12153.0 12194.9 12316.3 12318.6 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#3] mootools.html Cycles:180 Average:6309.48 Median:4399.92 stddev:3655.35 (83.1%) stddev-sans-first:3647.98 15:13:45 INFO - PROCESS | 10328 | Values: 1533.1 1598.9 1746.7 1839.8 1901.4 9221.6 9975.4 9979.5 9979.6 9981.2 8377.1 9086.9 9359.0 9389.9 9695.1 2483.2 2513.7 2516.9 2517.6 2520.9 2462.8 2537.8 2539.1 2542.4 2545.4 2467.7 2473.7 2508.2 2515.0 2517.8 2413.4 2428.4 2428.8 2432.7 2436.1 2390.0 2391.6 2404.1 2410.8 2443.9 9854.9 9919.7 9941.9 9954.0 9961.1 5444.1 5659.2 5672.2 5673.8 5675.0 3666.6 3745.9 3781.3 3785.7 3802.3 1131.1 1169.2 1189.9 1193.8 1199.6 13789.7 13801.2 13814.9 13823.4 13827.8 4762.5 4827.4 4840.0 4945.4 5005.6 11477.5 11504.9 11507.2 11517.0 11527.4 5640.4 5661.4 5664.6 5676.0 5684.6 4462.1 4963.8 5094.7 5131.4 5220.0 4263.5 4309.9 4364.3 4392.9 4407.0 1235.3 1236.9 1246.4 1261.5 1263.2 1041.6 1043.7 1069.6 1070.7 1076.9 6062.5 6194.6 6288.5 6378.1 6576.6 984.6 997.7 997.8 1007.4 1023.3 4111.9 4125.1 4221.5 4225.5 4315.9 10995.1 11574.6 11614.4 11623.7 11643.3 11329.0 11499.7 11545.3 11550.0 11553.8 9098.2 9222.8 9236.2 9241.8 9243.1 5919.7 5989.1 6030.5 6086.2 6120.8 10874.9 11139.6 11191.0 11373.5 11446.2 11292.9 11456.9 11531.6 11576.0 11584.6 7361.5 7601.1 7606.4 8129.1 8237.6 8855.8 9149.5 9439.1 9490.5 9764.4 8645.2 8664.7 8757.0 8819.2 8862.7 8512.4 8529.8 8588.8 8661.7 8731.7 7779.6 7793.1 7843.4 7848.3 7848.9 5521.6 5614.6 5779.3 5800.0 5842.2 5500.4 5563.5 5649.1 5694.5 5741.7 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#4] prototype.html Cycles:180 Average:4768.24 Median:5348.28 stddev:1394.94 (26.1%) stddev-sans-first:1358.85 15:13:45 INFO - PROCESS | 10328 | Values: 349.6 353.6 357.1 357.1 361.7 5550.3 5551.0 5556.6 5564.4 5574.7 5475.8 5484.2 5494.3 5494.8 5530.0 3859.7 4075.8 4326.9 4334.3 4351.9 4309.2 4314.3 4321.2 4323.0 4342.3 4284.3 4315.0 4318.5 4325.8 4329.3 4132.2 4237.9 4266.3 4333.3 4335.7 3844.9 3857.0 3858.7 3862.8 3863.9 5346.4 5347.6 5352.2 5393.8 5425.9 4730.5 4918.2 4923.6 4927.5 4945.5 5637.1 5654.9 5661.8 5670.2 5676.5 4207.6 4217.7 4221.0 4226.6 4239.7 5603.8 5656.2 5756.7 5830.3 5860.5 4266.4 4283.4 4296.5 4325.2 4327.9 4359.5 4381.6 4398.3 4406.9 4412.5 4836.2 4909.9 4922.3 4935.1 4956.1 5699.8 5704.7 5707.3 5747.5 5764.4 1475.0 1501.5 1505.6 1507.2 1508.2 924.6 964.6 971.9 972.5 981.5 4861.8 4876.4 4901.9 4910.3 4944.6 5146.2 5154.1 5162.6 5168.3 5172.4 4585.4 4834.0 4953.9 4958.4 4981.9 2731.9 2754.0 2761.9 2762.6 2769.9 5311.9 5325.1 5348.9 5352.9 5375.9 5194.0 5211.9 5559.3 6056.8 6082.4 6154.3 6168.4 6176.6 6201.6 6217.0 5718.5 5983.7 5984.7 6021.2 6039.9 5899.2 5937.1 5958.1 5959.4 5959.9 5884.1 6014.7 6046.3 6063.9 6222.0 4120.6 4204.6 4254.9 4308.9 4317.7 5748.7 5968.4 5968.5 5974.6 5986.2 5762.0 5770.4 5788.1 5798.1 5802.0 5743.4 5767.8 5774.9 5780.9 5791.9 5488.6 5687.4 5699.4 5724.7 5725.7 5720.6 5774.1 5802.9 5815.8 5826.9 5619.8 5680.2 5682.4 5693.8 5720.6 15:13:45 INFO - PROCESS | 10328 | 15:13:45 INFO - PROCESS | 10328 | [#5] yui.html Cycles:180 Average:23693.80 Median:3941.63 stddev:35354.33 (896.9%) stddev-sans-first:35411.75 15:13:45 INFO - PROCESS | 10328 | Values: 808.6 841.6 870.3 887.9 923.3 16620.3 16633.3 16652.6 16678.2 16755.7 16285.4 16306.5 16470.8 16480.4 17215.7 892.5 921.3 921.7 925.2 925.4 892.6 895.1 896.8 896.8 897.0 965.2 970.8 971.7 973.4 974.3 967.7 967.8 968.3 968.7 968.7 705.6 709.0 710.2 710.4 710.8 17984.4 18093.7 18128.9 18183.9 18203.9 15699.5 16312.2 16492.6 16700.3 17099.7 12768.4 12947.2 13204.0 13398.2 13403.5 4038.5 4093.6 4116.4 4163.2 4185.2 65670.3 68866.3 72464.6 78203.2 99711.0 12290.5 12698.2 12707.2 12789.6 12864.9 22311.2 22339.6 22468.6 22500.4 22671.3 8908.7 9298.3 9317.8 9337.7 9553.7 4925.1 5121.7 5129.0 5267.9 5313.5 3775.5 3895.1 3912.4 3940.1 3943.2 684.7 695.6 699.1 711.3 713.9 654.9 659.8 683.6 706.4 716.5 9907.9 10193.4 10329.8 10344.9 10376.3 617.3 725.7 726.5 759.7 765.6 4974.7 5100.9 5146.4 5169.5 5198.4 129321.0 138074.0 139436.0 140162.0 144426.3 119130.0 121163.0 121533.0 123908.4 125110.0 62030.0 88183.6 99968.5 102203.5 104718.5 9163.8 9782.2 9795.9 9817.9 9822.8 47008.3 54544.0 59484.4 59548.4 64837.1 89944.6 90725.5 95089.0 97074.0 102322.0 15102.9 15125.0 15974.2 16071.0 16100.3 15622.5 16040.3 16808.2 17649.7 18304.6 15488.1 15740.8 15851.0 17002.8 17023.7 13809.7 14980.3 15072.1 15233.2 15430.4 13974.8 14644.2 14921.0 15073.4 15130.8 10763.6 11007.3 11208.2 12012.6 12276.4 10814.6 10959.4 10993.4 11263.4 11668.1 15:13:45 INFO - PROCESS | 10328 | -------- Summary: end -------- 15:13:45 INFO - PROCESS | 10328 | 15:13:46 INFO - TEST-INFO | 10328: exit 0 15:13:46 INFO - TEST-OK | dromaeo_css | took 1113417ms 15:13:46 INFO - TEST-START | kraken 15:13:46 INFO - operating with platform_type : linux_ 15:13:46 INFO - Initialising browser for kraken test... 15:13:46 INFO - TEST-INFO | started process 10526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTLPP5u/profile http://localhost:43877/getInfo.html) 15:13:50 INFO - PROCESS | 10526 | __metrics Screen width/height:1600/1200 15:13:50 INFO - PROCESS | 10526 | colorDepth:24 15:13:50 INFO - PROCESS | 10526 | Browser inner width/height: 1024/697 15:13:50 INFO - PROCESS | 10526 | __metrics 15:13:51 INFO - PROCESS | 10526 | 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" 15:13:51 INFO - PROCESS | 10526 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:13:52 INFO - TEST-INFO | 10526: exit 0 15:13:52 INFO - Browser initialized. 15:13:52 INFO - Running cycle 1/1 for kraken test... 15:13:52 INFO - TEST-INFO | started process 10613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTLPP5u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:03 INFO - PROCESS | 10613 | RSS: Main: 145145856 15:14:03 INFO - PROCESS | 10613 | 15:14:06 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43877/tests/kraken/driver.html?testName=audio-beat-detection) 15:14:07 INFO - PROCESS | 10613 | RSS: Main: 190881792 15:14:07 INFO - PROCESS | 10613 | 15:14:09 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43877/tests/kraken/driver.html?testName=audio-dft) 15:14:10 INFO - PROCESS | 10613 | RSS: Main: 180924416 15:14:10 INFO - PROCESS | 10613 | 15:14:13 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43877/tests/kraken/driver.html?testName=audio-fft) 15:14:13 INFO - PROCESS | 10613 | RSS: Main: 269426688 15:14:13 INFO - PROCESS | 10613 | 15:14:15 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43877/tests/kraken/driver.html?testName=audio-oscillator) 15:14:16 INFO - PROCESS | 10613 | RSS: Main: 175443968 15:14:16 INFO - PROCESS | 10613 | 15:14:18 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43877/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:14:19 INFO - PROCESS | 10613 | RSS: Main: 176521216 15:14:19 INFO - PROCESS | 10613 | 15:14:22 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43877/tests/kraken/driver.html?testName=imaging-darkroom) 15:14:22 INFO - PROCESS | 10613 | RSS: Main: 212320256 15:14:22 INFO - PROCESS | 10613 | 15:14:26 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43877/tests/kraken/driver.html?testName=imaging-desaturate) 15:14:26 INFO - PROCESS | 10613 | RSS: Main: 211361792 15:14:26 INFO - PROCESS | 10613 | 15:14:29 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43877/tests/kraken/driver.html?testName=json-parse-financial) 15:14:30 INFO - PROCESS | 10613 | RSS: Main: 210997248 15:14:30 INFO - PROCESS | 10613 | 15:14:31 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43877/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:14:32 INFO - PROCESS | 10613 | RSS: Main: 175398912 15:14:32 INFO - PROCESS | 10613 | 15:14:33 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:14:34 INFO - PROCESS | 10613 | RSS: Main: 181800960 15:14:34 INFO - PROCESS | 10613 | 15:14:36 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:14:37 INFO - PROCESS | 10613 | RSS: Main: 186245120 15:14:37 INFO - PROCESS | 10613 | 15:14:39 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:14:40 INFO - PROCESS | 10613 | RSS: Main: 181010432 15:14:40 INFO - PROCESS | 10613 | 15:14:42 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:14:43 INFO - PROCESS | 10613 | RSS: Main: 184057856 15:14:43 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:43877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43877/tests/kraken/driver.html?testName=ai-astar) 15:14:45 INFO - PROCESS | 10613 | RSS: Main: 235937792 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | __start_tp_report 15:14:45 INFO - PROCESS | 10613 | _x_x_mozilla_page_load 15:14:45 INFO - PROCESS | 10613 | _x_x_mozilla_page_load_details 15:14:45 INFO - PROCESS | 10613 | |i|pagename|runs| 15:14:45 INFO - PROCESS | 10613 | |0;ai-astar;101;110;112;111;112;110;111;110;110;110 15:14:45 INFO - PROCESS | 10613 | |1;audio-beat-detection;144;116;115;175;114;116;157;124;150;115 15:14:45 INFO - PROCESS | 10613 | |2;audio-dft;160;161;172;161;161;161;162;159;160;161 15:14:45 INFO - PROCESS | 10613 | |3;audio-fft;74;74;75;106;75;76;73;74;105;75 15:14:45 INFO - PROCESS | 10613 | |4;audio-oscillator;84;83;125;82;83;83;114;83;83;132 15:14:45 INFO - PROCESS | 10613 | |5;imaging-gaussian-blur;116;117;116;116;116;117;117;116;117;116 15:14:45 INFO - PROCESS | 10613 | |6;imaging-darkroom;147;147;146;145;146;145;145;145;145;145 15:14:45 INFO - PROCESS | 10613 | |7;imaging-desaturate;95;95;95;94;95;94;95;94;94;95 15:14:45 INFO - PROCESS | 10613 | |8;json-parse-financial;53;49;51;51;53;50;53;51;50;52 15:14:45 INFO - PROCESS | 10613 | |9;json-stringify-tinderbox;60;61;60;59;60;59;60;60;60;60 15:14:45 INFO - PROCESS | 10613 | |10;stanford-crypto-aes;79;82;71;72;72;72;72;72;72;77 15:14:45 INFO - PROCESS | 10613 | |11;stanford-crypto-ccm;119;115;130;117;120;121;116;127;152;127 15:14:45 INFO - PROCESS | 10613 | |12;stanford-crypto-pbkdf2;152;152;155;151;150;153;150;152;158;155 15:14:45 INFO - PROCESS | 10613 | |13;stanford-crypto-sha256-iterative;61;62;64;61;61;60;62;63;60;63 15:14:45 INFO - PROCESS | 10613 | __end_tp_report 15:14:45 INFO - PROCESS | 10613 | __start_cc_report 15:14:45 INFO - PROCESS | 10613 | _x_x_mozilla_cycle_collect,1621 15:14:45 INFO - PROCESS | 10613 | __end_cc_report 15:14:45 INFO - PROCESS | 10613 | __startTimestamp1472163285268__endTimestamp 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | ------- Summary: start ------- 15:14:45 INFO - PROCESS | 10613 | Number of tests: 14 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:3.16 (2.9%) stddev-sans-first:0.87 15:14:45 INFO - PROCESS | 10613 | Values: 101.0 110.0 112.0 111.0 112.0 110.0 111.0 110.0 110.0 110.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:134.00 stddev:22.15 (16.5%) stddev-sans-first:23.11 15:14:45 INFO - PROCESS | 10613 | Values: 144.0 116.0 115.0 175.0 114.0 116.0 157.0 124.0 150.0 115.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.00 stddev:3.68 (2.3%) stddev-sans-first:3.84 15:14:45 INFO - PROCESS | 10613 | Values: 160.0 161.0 172.0 161.0 161.0 161.0 162.0 159.0 160.0 161.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#3] audio-fft Cycles:10 Average:80.70 Median:74.50 stddev:13.10 (17.6%) stddev-sans-first:13.67 15:14:45 INFO - PROCESS | 10613 | Values: 74.0 74.0 75.0 106.0 75.0 76.0 73.0 74.0 105.0 75.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:83.00 stddev:20.11 (24.2%) stddev-sans-first:20.92 15:14:45 INFO - PROCESS | 10613 | Values: 84.0 83.0 125.0 82.0 83.0 83.0 114.0 83.0 83.0 132.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:14:45 INFO - PROCESS | 10613 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.73 15:14:45 INFO - PROCESS | 10613 | Values: 147.0 147.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:14:45 INFO - PROCESS | 10613 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:1.42 (2.8%) stddev-sans-first:1.36 15:14:45 INFO - PROCESS | 10613 | Values: 53.0 49.0 51.0 51.0 53.0 50.0 53.0 51.0 50.0 52.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 15:14:45 INFO - PROCESS | 10613 | Values: 60.0 61.0 60.0 59.0 60.0 59.0 60.0 60.0 60.0 60.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:72.00 stddev:3.81 (5.3%) stddev-sans-first:3.61 15:14:45 INFO - PROCESS | 10613 | Values: 79.0 82.0 71.0 72.0 72.0 72.0 72.0 72.0 72.0 77.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:124.00 stddev:10.96 (8.8%) stddev-sans-first:11.45 15:14:45 INFO - PROCESS | 10613 | Values: 119.0 115.0 130.0 117.0 120.0 121.0 116.0 127.0 152.0 127.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:152.50 stddev:2.53 (1.7%) stddev-sans-first:2.67 15:14:45 INFO - PROCESS | 10613 | Values: 152.0 152.0 155.0 151.0 150.0 153.0 150.0 152.0 158.0 155.0 15:14:45 INFO - PROCESS | 10613 | 15:14:45 INFO - PROCESS | 10613 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.34 (2.2%) stddev-sans-first:1.39 15:14:45 INFO - PROCESS | 10613 | Values: 61.0 62.0 64.0 61.0 61.0 60.0 62.0 63.0 60.0 63.0 15:14:45 INFO - PROCESS | 10613 | -------- Summary: end -------- 15:14:45 INFO - PROCESS | 10613 | 15:14:46 INFO - TEST-INFO | 10613: exit 0 15:14:46 INFO - TEST-OK | kraken | took 60007ms 15:14:46 INFO - SUITE-END | took 1173s 15:14:46 INFO - Completed test suite (00:19:33) 15:14:46 INFO - javascript benchmark 15:14:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.164068162618, 3311.7681762276634, 3321.9501707474383, 3343.1474973881654, 3360.1599600099976, 5603.467670571295, 5710.857228569288, 5727.341355744091, 5732.885342293157, 5736.0, 5618.15727640854, 5670.206171136044, 5676.205331253617, 5679.290088738908, 5687.971560142193, 4970.6520543561965, 5136.02415541047, 5140.383154021522, 5186.688798672073, 5218.895622087556, 5043.319151914481, 5137.12668846297, 5179.430262671103, 5188.974055129719, 5194.71429071401, 5065.544101030907, 5105.438401775802, 5118.667286626376, 5119.411267704206, 5180.352455943007, 5202.479752024805, 5212.556932660728, 5215.24378965048, 5219.739013049332, 5252.185911183773, 5039.496050394968, 5042.142835717937, 5086.440491545927, 5114.488551144893, 5120.257562653432, 5537.529310008614, 5544.77820887164, 5552.278203833475, 5564.582656300794, 5574.944250557483, 6016.037434010537, 6062.514998800129, 6063.211782468251, 6073.665948372838, 6093.969530152365, 5850.268716410449, 5961.850953726148, 6006.699665016732, 6030.155778191057, 6110.335195530717, 5620.100783874561, 5636.830895073154, 5646.943530564683, 5648.181013752983, 5686.061799803069, 6140.232470941132, 6142.662153581596, 6145.293291271471, 6145.784897528565, 6171.321154672983, 5787.739551720183, 5902.0, 5959.314678811992, 5961.642301461926, 6045.244344456943, 6096.4649490713355, 6105.564615568199, 6106.25420309353, 6106.328303886569, 6112.484056122425, 6063.211782468339, 6066.090086486974, 6068.302145253264, 6070.878582428327, 6092.847678807977, 5809.099589563624, 5899.233099697012, 5975.701214939236, 5979.820605381846, 6038.094285857069, 4620.145273124483, 4627.213373726475, 4635.281531362644, 4648.140094082605, 4655.930161047587, 4138.503379594407, 4151.875443736692, 4156.854510092132, 4160.45914031174, 4189.664826813848, 5572.526335261548, 5689.715514224355, 5749.1663708762, 5760.769569217268, 5762.510186634099, 6091.725872335755, 6094.146819445281, 6096.908546371719, 6098.481629061579, 6126.203593532812, 5768.7692492300675, 5795.0727883539275, 5801.6519008859605, 5802.767889284466, 5805.477507024388, 5019.0965626187635, 5164.457731938168, 5212.843614691489, 5216.947830521722, 5245.082110630625, 6262.530310226751, 6297.2128483939505, 6311.684415779193, 6314.494840412756, 6328.873422531524, 6018.127371531191, 6033.12519684652, 6083.1483592298, 6090.390960903874, 6090.725917333642, 6098.847528811815, 6258.749650013949, 6300.991841305369, 6328.367163283635, 6377.0, 6072.787452439144, 6073.848153796191, 6085.148079268817, 6086.360932102066, 6105.14527966094, 6127.785527506516, 6135.262573504107, 6182.783602573888, 6187.412195841388, 6187.84530386744, 5802.680852552981, 5876.647401155944, 5918.053111502139, 5941.168236446987, 5989.850253743692, 5946.07835785454, 5947.3457919627945, 5952.404759524013, 5953.374895636069, 5953.940460595339, 5771.942280577308, 5781.971090144522, 5823.563232757645, 5831.446012628793, 5846.356900741001, 5773.971130144322, 5865.677387743544, 5923.526117910643, 5927.644341339534, 6004.5796794225735, 5777.133429985536, 5811.56413269015, 5811.622244554091, 5824.388439213822, 5831.533477321889], "value": 5584.224840596791, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.354856754705, 600.3846057790765, 632.9999999999997, 640.8686219325039, 647.2524234509142, 45832.54167458324, 47412.0, 47424.76287618561, 47431.0, 51735.74132129343, 48828.75585622067, 49159.262611060774, 49333.26000110001, 49357.0, 49412.75293623536, 8467.745967620982, 9139.771505712344, 9278.582463789146, 9284.396514226588, 10935.453227338641, 10966.342019478818, 11426.200165988384, 11428.599999000036, 11430.199886007982, 11433.085353171726, 11507.539698412096, 11561.826572601418, 11562.653120406401, 11563.653090407259, 11572.305661660284, 8618.784530386727, 10994.615188468366, 11294.774104517946, 11412.20114591979, 11483.42582870853, 11171.273867198606, 11261.831072533918, 11265.26775759573, 11265.831012534818, 11269.323840569592, 45546.08907821825, 46009.30986035212, 46173.53826461726, 46219.306710399374, 46349.53650463486, 3768.208676178006, 3805.6574908258112, 3850.8074596270076, 3941.9802900985596, 4147.668186545069, 35748.64251357479, 35860.103497412514, 35862.641373586455, 35890.82054589737, 36192.457113143326, 4712.505186955389, 4736.455307639631, 4780.49804770501, 4812.085703716318, 4885.755712214411, 376125.0, 378355.10822445987, 378505.0, 378967.0, 379138.0, 37895.24209515794, 65143.674281628286, 65448.34551654423, 65538.67230663817, 65557.67221163864, 39041.60958390436, 40757.38863917044, 41292.1741565167, 41339.7933010333, 41346.1730765383, 16029.19854007295, 16199.352025918832, 16218.837811621968, 16232.59418514523, 16235.107069111313, 11450.37022963745, 12633.873661263453, 12800.423980920881, 12841.422136003903, 12894.484220631257, 9337.346385753068, 9348.672796452227, 9351.438913665066, 9399.060093990684, 9402.95298523503, 3449.2756521130846, 3540.8577810424836, 3540.964590354115, 3575.248128417109, 3620.420732682758, 1081.0648788797632, 1094.9014588687057, 1153.5616465743133, 1234.1467032559449, 1239.4670291774444, 35416.0, 35807.64192358095, 35954.46068308925, 35989.64010359915, 35995.64004359975, 1170.2218025013271, 1174.5536696055615, 1187.4419023059322, 1205.2045649871043, 1227.6071657069651, 18277.3602923897, 18363.265469381342, 18375.77323429315, 18477.630447391246, 18518.537036574195, 632510.0, 634789.0, 634876.0, 636047.0, 640906.0, 66721.6663916687, 67236.32763672301, 67368.6631566839, 67403.3259667397, 67423.32576674268, 1318.4660212613908, 1432.0119117808679, 1462.3419461242484, 1464.0483685604443, 1497.8726808221206, 34517.654823452445, 36357.45463818098, 36417.45373819342, 36475.27049459049, 36705.44941825928, 54406.455935440135, 54964.45035549593, 55104.72447637736, 55165.72417138049, 55328.72335638296, 455001.0, 455197.0, 455557.0, 457396.0, 457455.0, 30363.392732145676, 31592.210194745316, 31709.0, 33053.17367065843, 33205.16987075342, 52475.21287180619, 52609.736951315, 52662.42012739624, 52705.0, 52758.73620632026, 44244.336334955646, 44925.77537112294, 45218.32172517349, 45718.0, 47093.29360059533, 44347.33478997882, 45093.774531127136, 45115.37847333194, 45920.0, 46448.071038579714, 44018.0, 44863.0, 44906.55093449154, 45004.77497612622, 45245.77377113225, 38211.61788382081, 39417.40873886837, 39745.0, 39824.14690313066, 40555.39166912558, 29270.41459170762, 29950.170729770438, 30088.0, 30288.394232115676, 30312.242193945327], "value": 20998.265060800786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.3641491538959, 401.80713257636336, 416.41909536197005, 422.6407553579457, 427.1648926348988, 16130.516084517469, 19772.60454790903, 20321.695174572375, 20565.794342056575, 21872.0, 19685.50786230345, 20231.696524552106, 20496.282630107948, 21140.57718845622, 21385.893070534665, 5314.654547454423, 5477.1510415885605, 5504.146857237135, 5538.75075621596, 5544.500994910457, 4149.2531344358, 5426.077566813651, 5492.615516913797, 5493.423190564973, 5493.478119578634, 4326.0, 6628.668566571652, 6662.1339225900565, 6748.156480439945, 6764.59412435253, 6079.817605471843, 6152.046432802923, 6518.18522684662, 6758.391744742972, 6767.052612634235, 5969.940300597025, 5979.252593425822, 5985.0, 5990.400959904018, 5998.580099393044, 16396.75404868928, 24797.380065498506, 25126.12058577941, 25221.243362698966, 25652.35869103269, 2342.4862705941086, 2410.288964755401, 2472.0606169655416, 2527.2544599343055, 2587.6452428290354, 10667.78664426715, 10668.253222274443, 10718.303310284884, 11015.449227538591, 11178.944105279503, 2048.7131801547794, 2070.710100585919, 2073.5645514441985, 2077.096463038568, 2128.31893793986, 57465.71267143638, 86266.0, 86329.56835215847, 87581.56209218912, 88499.55750221208, 11291.040261577858, 11344.943275283571, 11429.142814288962, 11608.187426880037, 11776.882231177578, 16453.42413015539, 16459.670806583803, 16476.588085297724, 16505.33978640865, 16520.339186432648, 8043.678252869937, 8279.337652987868, 8336.874946875801, 8435.662573496991, 8480.830383392298, 7837.412194085467, 7855.13593504714, 7904.446688731849, 8020.438569300093, 8032.839343213103, 4942.654014219042, 4948.851534453972, 5026.472220416804, 5027.924581131286, 5135.512126347991, 2022.595480903825, 2065.793420657952, 2085.9478513037297, 2093.5394213273057, 2100.1308835136742, 473.54303097510376, 806.9072056713553, 938.6057855700623, 960.2366118935463, 978.0268632710356, 11937.224080434744, 12710.0, 13097.738045239043, 13139.34303284832, 13223.537176198786, 493.79260710500955, 494.47091611974844, 495.4549995005537, 500.41451501743046, 501.9648624596316, 6708.127943367429, 6850.280720524373, 6991.370776630026, 7068.964655176691, 7183.640817959081, 529053.0, 540793.0, 543853.0, 556504.0, 572166.0, 97541.0, 99842.50078749559, 100387.0, 103330.48334758429, 106502.46748766207, 458.41094193961266, 489.41514889707173, 496.9652124351333, 498.16805934090155, 498.9925151122736, 10303.53634086453, 12438.502459901865, 12484.0, 12485.687857803265, 12748.171368860994, 47203.0, 47247.29129063136, 47458.28812567883, 47930.520694794, 48077.75961120312, 66052.33947660463, 66399.0, 67434.3256567428, 67636.0, 67954.66022669854, 8251.95874020626, 8568.91431085706, 8874.822503550022, 8923.063078376677, 9001.729948101567, 7213.278672132745, 10256.794864102825, 13594.912407007218, 13711.177329360271, 14073.085249458753, 12071.094667899766, 12167.087468440079, 12311.75376492483, 12518.0, 12611.30637814929, 8534.762459443471, 11263.098952083978, 11594.826077609012, 11849.703757406134, 12038.75922481563, 12278.447469863699, 12292.81560776607, 12506.812397814221, 12561.183523070971, 12578.811317830057, 8915.910840891767, 8995.055519170393, 9013.0, 9141.177294043568, 9149.22231610294, 10208.489575521342, 12153.0, 12194.878051219373, 12316.261024338568, 12318.568850090203], "value": 8833.093912569224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.072491142858, 1598.896071755336, 1746.7205247160457, 1839.84361329287, 1901.3725470594713, 9221.63113475461, 9975.351602145856, 9979.50102494876, 9979.600815967353, 9981.151602113829, 8377.078521362646, 9086.909130908689, 9358.953205233964, 9389.859152112707, 9695.127438530531, 2483.1557270527974, 2513.710923243832, 2516.899324027046, 2517.622356646499, 2520.861352625605, 2462.802975761944, 2537.7716005559496, 2539.073238268037, 2542.427953710419, 2545.4018305698146, 2467.6668649732237, 2473.678421805163, 2508.234988328559, 2515.019142534413, 2517.773400393964, 2413.4087148648605, 2428.429319110008, 2428.781409673129, 2432.6959130108735, 2436.11081955086, 2389.9522009559887, 2391.5575618510456, 2404.1465279825584, 2410.783029527351, 2443.9389015274583, 9854.901450985542, 9919.652812151613, 9941.850872236922, 9954.0, 9961.053699898497, 5444.128939369722, 5659.207710920475, 5672.205891175238, 5673.773049078032, 5675.035244008528, 3666.5600127984544, 3745.887623371276, 3781.3193625147464, 3785.6971442284553, 3802.296575133609, 1131.1459847814929, 1169.1698893785422, 1189.8631657359342, 1193.773183095209, 1199.5741511763285, 13789.655258618515, 13801.240931748749, 13814.930925345308, 13823.377947992365, 13827.792583111363, 4762.452317983476, 4827.396575428072, 4839.975800120977, 4945.406551213878, 5005.574526165244, 11477.540898363972, 11504.884951150549, 11507.19449638534, 11517.0, 11527.365994870195, 5640.407757185437, 5661.377248502661, 5664.575156863251, 5676.035074037423, 5684.602077854511, 4462.085272519126, 4963.759060234941, 5094.6943183408375, 5131.384233891957, 5220.034293655686, 4263.488381394252, 4309.892252693708, 4364.258076127066, 4392.868213953587, 4406.9779651101535, 1235.3452670084869, 1236.863944966053, 1246.3581255653346, 1261.470182523324, 1263.2231177825572, 1041.5833666533447, 1043.7025447747355, 1069.5775168808277, 1070.6573896353093, 1076.9030787229187, 6062.484688801413, 6194.628322300677, 6288.52836037299, 6378.107065010902, 6576.605403675795, 984.5667906121427, 997.7006897930592, 997.8403455447094, 1007.3653598233067, 1023.3194925374543, 4111.8560850370095, 4125.1131006833675, 4221.493420789525, 4225.471816022997, 4315.913681726348, 10995.120390368751, 11574.594889178838, 11614.361210133524, 11623.6512904613, 11643.301401915744, 11329.0, 11499.712507187387, 11545.307281563133, 11550.018248448809, 11553.826692599618, 9098.181163695166, 9222.769430764285, 9236.168744813, 9241.768955776159, 9243.121903419298, 5919.6744179070565, 5989.101634754822, 6030.547708921936, 6086.178365920544, 6120.7551697931585, 10874.94562527214, 11139.610113645984, 11191.0, 11373.488193031168, 11446.198766086294, 11292.887071129513, 11456.885431145914, 11531.596394126165, 11576.0, 11584.594539191088, 7361.521501102411, 7601.125870524739, 7606.391488680782, 8129.105798362294, 8237.588120594066, 8855.822883542422, 9149.542522873962, 9439.103285187895, 9490.525473726424, 9764.365316254421, 8645.221930026328, 8664.723264120215, 8757.0, 8819.162179592928, 8862.689805856764, 8512.446690965067, 8529.786755331166, 8588.828223435372, 8661.740147795575, 8731.738047858575, 7779.649915753692, 7793.06483222017, 7843.372530197685, 7848.293653571206, 7848.9215107848195, 5521.613487055867, 5614.63504872182, 5779.335376431596, 5800.0, 5842.2405087338375, 5500.367457742411, 5563.499285064364, 5649.12438572022, 5694.515966142759, 5741.741621626953], "value": 5008.258093860618, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.5578093711455, 353.58453816765297, 357.07339454116567, 357.14820153932897, 361.71424574586064, 5550.306211723535, 5550.972245138773, 5556.638818476797, 5564.360098588663, 5574.714367109486, 5475.753591088401, 5484.232207490954, 5494.340679118502, 5494.80768173114, 5530.0, 3859.691224702031, 4075.7829977628685, 4326.935095973563, 4334.349847522865, 4351.934720979188, 4309.224339618867, 4314.3097104463295, 4321.243782338094, 4323.0, 4342.283523218681, 4284.33592793116, 4314.956850431487, 4318.52496225415, 4325.832025353153, 4329.3332067154215, 4132.194222126686, 4237.872863814106, 4266.274733295348, 4333.349997500401, 4335.653147748172, 3844.884653460401, 3856.9807150964066, 3858.6527212550723, 3862.806859657006, 3863.9227215455535, 5346.385165705916, 5347.6256662033675, 5352.197170424468, 5393.78424863005, 5425.9186112208345, 4730.526947305242, 4918.188498897677, 4923.606111511071, 4927.482614325516, 4945.5163450964565, 5637.069883469223, 5654.886902261932, 5661.830145095612, 5670.234518340013, 5676.460736230051, 4207.642350400189, 4217.746935183868, 4220.957790422118, 4226.577342265779, 4239.7492739641875, 5603.803866864681, 5656.171914042979, 5756.683382413966, 5830.300363956352, 5860.472557469806, 4266.402703621496, 4283.443152390231, 4296.484421869396, 4325.178216138924, 4327.913441731148, 4359.455068116486, 4381.5618438155925, 4398.252297109435, 4406.867793966187, 4412.514623391425, 4836.226203807374, 4909.852704418873, 4922.310876477296, 4935.136351138536, 4956.107900577858, 5699.829005129853, 5704.657720536698, 5707.315122185364, 5747.453991870766, 5764.365919748824, 1475.0, 1501.5195137556095, 1505.5784380373514, 1507.2388443835794, 1508.1705062215738, 924.6347692661361, 964.6286179820679, 971.8580667715406, 972.5234635028927, 981.4651015196735, 4861.781219845116, 4876.4392094909535, 4901.926471102936, 4910.337104490848, 4944.554990050912, 5146.228065790161, 5154.144507977126, 5162.6128040397125, 5168.250603662448, 5172.422411164555, 4585.403897493305, 4834.0, 4953.869835970568, 4958.429780575208, 4981.9252711209365, 2731.9043833465735, 2753.9810270199755, 2761.8538306602936, 2762.557990721475, 2769.850512037522, 5311.946880531145, 5325.09473389517, 5348.930213957224, 5352.892942141136, 5375.865603359947, 5193.974030129825, 5211.895762084813, 5559.332880054339, 6056.818295451144, 6082.361352058058, 6154.292256390572, 6168.422315057256, 6176.567640265138, 6201.56589038772, 6216.99999999991, 5718.542516598579, 5983.743413883133, 5984.730687119177, 6021.187139736079, 6039.939600603938, 5899.17411562373, 5937.139114828412, 5958.136070269873, 5959.433853783884, 5959.940400595939, 5884.057971014493, 6014.669193194416, 6046.274447066205, 6063.939360606338, 6221.968890155701, 4120.587941205856, 4204.558521355336, 4254.87235382939, 4308.935365969575, 4317.676174286878, 5748.655080695172, 5968.373320801427, 5968.522518198447, 5974.581779275582, 5986.2217911671205, 5762.0, 5770.355448697102, 5788.060999565193, 5798.1302804579655, 5802.0, 5743.391850281928, 5767.769289228384, 5774.855628609318, 5780.913286300793, 5791.942080579141, 5488.643238189505, 5687.43125687428, 5699.373068962494, 5724.713764311685, 5725.6850873202375, 5720.570957178312, 5774.0761478163295, 5802.912956305743, 5815.76736930518, 5826.94173058264, 5619.831405057855, 5680.2047713321, 5682.403347648437, 5693.800716974886, 5720.599558030898], "value": 4329.171740482281, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.5795386399072, 841.6044459104222, 870.3428911172064, 887.8890138732658, 923.3167456082499, 16620.25208865601, 16633.334666613337, 16652.58368540787, 16678.166091695413, 16755.74866377004, 16285.430009949654, 16306.510804675878, 16470.83529164708, 16480.42318518852, 17215.655686886254, 892.4823602310661, 921.3228277216241, 921.7188757428981, 925.1950802801572, 925.3707478914336, 892.6072528087653, 895.0557162193883, 896.7937374403891, 896.8296023755498, 896.9865452018225, 965.1844191658038, 970.8009857979133, 971.6696323250094, 973.4013581647272, 974.2644303550844, 967.666155176463, 967.7725734452433, 968.2544440780595, 968.6706519783237, 968.6706519783272, 705.5696025424487, 709.0250905005618, 710.2400431538256, 710.4494017136709, 710.7725527831096, 17984.37054703092, 18093.7285940711, 18128.90935545327, 18183.909080454512, 18203.908980455144, 15699.529014129594, 16312.184390780532, 16492.587685307844, 16700.331986720517, 17099.74350384745, 12768.361581920866, 12947.22316660994, 13204.0, 13398.19610823344, 13403.530876419278, 4038.474998250238, 4093.5906409359122, 4116.423700681907, 4163.167366526707, 4185.162967406501, 65670.3432965669, 68866.31133688649, 72464.6376768118, 78203.21796782073, 99711.0, 12290.508379664714, 12698.238105713686, 12707.174033687781, 12789.552365667343, 12864.935675321563, 22311.219107331166, 22339.55320893573, 22468.55062898733, 22500.437489062577, 22671.319860404215, 8908.688195913111, 9298.256139508823, 9317.813643727222, 9337.673181438617, 9553.713388598353, 4925.11347957364, 5121.667091639106, 5128.974355128201, 5267.920981185285, 5313.54834839043, 3775.452559378875, 3895.0651843557907, 3912.4131380292615, 3940.054386947112, 3943.211357728466, 684.6918886501095, 695.5652717051843, 699.1156187422914, 711.3384552366246, 713.9143302803697, 654.9214094308713, 659.7525927777084, 683.5898460923505, 706.3642721550646, 716.4948711158621, 9907.900920990842, 10193.388396696222, 10329.793404132026, 10344.896551034544, 10376.273660843819, 617.2777849915609, 725.6661935509725, 726.5422783646269, 759.7151068349369, 765.6478020110811, 4974.726390048582, 5100.923486147711, 5146.408163061221, 5169.53474187325, 5198.428172900978, 129321.0, 138074.0, 139436.0, 140162.0, 144426.27786861, 119130.0, 121163.0, 121533.0, 123908.38045809894, 125110.0, 62030.0, 88183.55908220418, 99968.50015749874, 102203.48898255461, 104718.476407619, 9163.816723665355, 9782.168515676247, 9795.9020409795, 9817.901820981699, 9822.807587533936, 47008.29487557621, 54544.0, 59484.405155949615, 59548.40451595429, 64837.05488835343, 89944.5502772482, 90725.54637226772, 95089.0, 97074.0, 102322.0, 15102.924485377504, 15125.0, 15974.201289935225, 16071.0, 16100.27548760332, 15622.453214137451, 16040.278187481357, 16808.24362903648, 17649.735253970943, 18304.633907322044, 15488.148151851756, 15740.763888541911, 15851.0, 17002.829971700125, 17023.659526809643, 13809.654758631115, 14980.250987450367, 15072.09567425914, 15233.162176080423, 15430.382784688487, 13974.790378144133, 14644.19456929879, 14921.048819353939, 15073.397064117313, 15130.848691512943, 10763.623273185402, 11007.339559626127, 11208.159388045766, 12012.579559715368, 12276.386180690752, 10814.646749858555, 10959.397233151934, 10993.395363255098, 11263.436828158723, 11668.124890633067], "value": 8352.679153037465, "unit": "score"}], "name": "dromaeo_css", "value": 7566.06006896376, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 112.0, 111.0, 112.0, 110.0, 111.0, 110.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 115.0, 175.0, 114.0, 116.0, 157.0, 124.0, 150.0, 115.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 172.0, 161.0, 161.0, 161.0, 162.0, 159.0, 160.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 106.0, 75.0, 76.0, 73.0, 74.0, 105.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 125.0, 82.0, 83.0, 83.0, 114.0, 83.0, 83.0, 132.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 49.0, 51.0, 51.0, 53.0, 50.0, 53.0, 51.0, 50.0, 52.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 71.0, 72.0, 72.0, 72.0, 72.0, 72.0, 72.0, 77.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 115.0, 130.0, 117.0, 120.0, 121.0, 116.0, 127.0, 152.0, 127.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 155.0, 151.0, 150.0, 153.0, 150.0, 152.0, 158.0, 155.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 64.0, 61.0, 61.0, 60.0, 62.0, 63.0, 60.0, 63.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1460.8, "alertThreshold": 2.0}]} 15:14:46 INFO - Return code: 0 15:14:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:14:46 INFO - # TBPL SUCCESS # 15:14:46 INFO - Running post-action listener: _resource_record_post_action 15:14:46 INFO - [mozharness: 2016-08-25 22:14:46.602823Z] Finished run-tests step (success) 15:14:46 INFO - Running post-run listener: _resource_record_post_run 15:14:46 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 274432; Write bytes: 328204288; Read time: 348; Write time: 181116 15:14:46 INFO - TinderboxPrint: CPU usage
12.9% 15:14:46 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 348 15:14:46 INFO - TinderboxPrint: I/O write bytes / time
328,204,288 / 181,116 15:14:46 INFO - TinderboxPrint: CPU idle
8,268.3 (87.0%) 15:14:46 INFO - TinderboxPrint: CPU user
1,184.8 (12.5%) 15:14:46 INFO - TinderboxPrint: Swap in / out
0 / 0 15:14:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 77049856; Read time: 160; Write time: 41136 15:14:46 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 250494976; Read time: 164; Write time: 139920 15:14:47 INFO - Running post-run listener: _upload_blobber_files 15:14:47 INFO - Blob upload gear active. 15:14:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:14:47 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 15:14:47 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'] 15:14:47 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 15:14:47 INFO - (blobuploader) - INFO - Open directory for files ... 15:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:14:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:48 INFO - (blobuploader) - INFO - Done attempting. 15:14:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:14:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:49 INFO - (blobuploader) - INFO - Done attempting. 15:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:53 INFO - (blobuploader) - INFO - Done attempting. 15:14:53 INFO - (blobuploader) - INFO - Iteration through files over. 15:14:53 INFO - Return code: 0 15:14:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:14:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:14:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbeb97c6b6466f4b3d74b8b9f1964c43458ecea28cc5ce478ed40e209b9d1b1430ae93d1dd76f48eeb22584ddc19619b1cff53c22ee186d310370d126946a7c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ea6f3dd5c0fd2081af54d5425f376308001cca39cfe07fa67df74172f681a6dccf7c9c84f8186d839c91f6a50d2de914a138f82d7521477c3efa012b248f5f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/73c8574d89c66cc3bc328ce7a768e48c65758bafaa2822df7ff874ef8536e85039346f6665fca7dbdbd8109375fd587d2f485a23c0269c7fcb1a496a097285d2"} 15:14:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:14:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:14:53 INFO - Contents: 15:14:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbeb97c6b6466f4b3d74b8b9f1964c43458ecea28cc5ce478ed40e209b9d1b1430ae93d1dd76f48eeb22584ddc19619b1cff53c22ee186d310370d126946a7c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ea6f3dd5c0fd2081af54d5425f376308001cca39cfe07fa67df74172f681a6dccf7c9c84f8186d839c91f6a50d2de914a138f82d7521477c3efa012b248f5f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/73c8574d89c66cc3bc328ce7a768e48c65758bafaa2822df7ff874ef8536e85039346f6665fca7dbdbd8109375fd587d2f485a23c0269c7fcb1a496a097285d2"} 15:14:53 INFO - Running post-run listener: copy_logs_to_upload_dir 15:14:53 INFO - Copying logs to upload dir... 15:14:53 INFO - mkdir: /builds/slave/test/build/upload/logs 15:14:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.479486 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-25 15:14:53.297284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 15:14:53.300525) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 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/cbeb97c6b6466f4b3d74b8b9f1964c43458ecea28cc5ce478ed40e209b9d1b1430ae93d1dd76f48eeb22584ddc19619b1cff53c22ee186d310370d126946a7c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ea6f3dd5c0fd2081af54d5425f376308001cca39cfe07fa67df74172f681a6dccf7c9c84f8186d839c91f6a50d2de914a138f82d7521477c3efa012b248f5f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/73c8574d89c66cc3bc328ce7a768e48c65758bafaa2822df7ff874ef8536e85039346f6665fca7dbdbd8109375fd587d2f485a23c0269c7fcb1a496a097285d2"} build_url:https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/K8tyLiMzQLy8I6DzAztlOQ/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/cbeb97c6b6466f4b3d74b8b9f1964c43458ecea28cc5ce478ed40e209b9d1b1430ae93d1dd76f48eeb22584ddc19619b1cff53c22ee186d310370d126946a7c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ea6f3dd5c0fd2081af54d5425f376308001cca39cfe07fa67df74172f681a6dccf7c9c84f8186d839c91f6a50d2de914a138f82d7521477c3efa012b248f5f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/73c8574d89c66cc3bc328ce7a768e48c65758bafaa2822df7ff874ef8536e85039346f6665fca7dbdbd8109375fd587d2f485a23c0269c7fcb1a496a097285d2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 15:14:53.326446) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 15:14:53.326812) ========= 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=bb9b54edd4a927dc735d112f000001d9-1472161603.71959-375878520 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 15:14:53.412285) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 15:14:53.412706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 15:14:53.413064) ========= ========= Total master_lag: 0.18 =========