builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1473447009.8 results: success (0) buildid: 20160909090454 builduid: e6d7915ae0d54378b103cd8a49f0b257 revision: 2997ae20bac0d7cebeb238dc919b63d86100e9da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.797093) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.797562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.797856) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.831924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.832210) ========= 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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-09 11:50:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-09-09 11:50:10 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118833 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.965042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.965345) ========= 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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015196 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.014713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.015041) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2997ae20bac0d7cebeb238dc919b63d86100e9da --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 2997ae20bac0d7cebeb238dc919b63d86100e9da --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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-09 11:50:10,201 truncating revision to first 12 chars 2016-09-09 11:50:10,201 Setting DEBUG logging. 2016-09-09 11:50:10,201 attempt 1/10 2016-09-09 11:50:10,201 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2997ae20bac0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 11:50:10,511 unpacking tar archive at: mozilla-aurora-2997ae20bac0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655324 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.685011) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.685283) ========= script_repo_revision: 2997ae20bac0d7cebeb238dc919b63d86100e9da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.685675) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.685937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.699216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-09 11:50:10.699491) ========= /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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:50:10 INFO - MultiFileLogger online at 20160909 11:50:10 in /builds/slave/test 11:50:10 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 11:50:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:50:10 INFO - {'append_to_log': False, 11:50:10 INFO - 'base_work_dir': '/builds/slave/test', 11:50:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:50:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:50:10 INFO - 'branch': 'Mozilla-Aurora', 11:50:10 INFO - 'buildbot_json_path': 'buildprops.json', 11:50:10 INFO - 'config_files': ('talos/linux_config.py',), 11:50:10 INFO - 'default_actions': ('clobber', 11:50:10 INFO - 'read-buildbot-config', 11:50:10 INFO - 'download-and-extract', 11:50:10 INFO - 'populate-webroot', 11:50:10 INFO - 'create-virtualenv', 11:50:10 INFO - 'install', 11:50:10 INFO - 'run-tests'), 11:50:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:50:10 INFO - 'download_minidump_stackwalk': True, 11:50:10 INFO - 'download_symbols': 'ondemand', 11:50:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:50:10 INFO - 'tooltool.py': '/tools/tooltool.py', 11:50:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:50:10 INFO - '/tools/misc-python/virtualenv.py')}, 11:50:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:50:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:50:10 INFO - 'installer_path': 'installer.exe', 11:50:10 INFO - 'log_level': 'info', 11:50:10 INFO - 'log_name': 'talos', 11:50:10 INFO - 'log_to_console': True, 11:50:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:50:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:50:10 INFO - 'opt_config_files': (), 11:50:10 INFO - 'pip_index': False, 11:50:10 INFO - 'sps_profile': False, 11:50:10 INFO - 'sps_profile_interval': 0, 11:50:10 INFO - 'suite': 'dromaeojs', 11:50:10 INFO - 'system_bits': '32', 11:50:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:50:10 INFO - 'title': 'talos-linux64-ix-017', 11:50:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:50:10 INFO - 'use_talos_json': True, 11:50:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:50:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:50:10 INFO - 'work_dir': 'build'} 11:50:10 INFO - [mozharness: 2016-09-09 18:50:10.983760Z] Running clobber step. 11:50:10 INFO - Running pre-action listener: _resource_record_pre_action 11:50:10 INFO - Running main action method: clobber 11:50:10 INFO - rmtree: /builds/slave/test/build 11:50:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:50:11 INFO - Running post-action listener: _resource_record_post_action 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.439728Z] Finished clobber step (success) 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.439833Z] Running read-buildbot-config step. 11:50:11 INFO - Running pre-action listener: _resource_record_pre_action 11:50:11 INFO - Running main action method: read_buildbot_config 11:50:11 INFO - Using buildbot properties: 11:50:11 INFO - { 11:50:11 INFO - "project": "", 11:50:11 INFO - "product": "firefox", 11:50:11 INFO - "script_repo_revision": "production", 11:50:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 11:50:11 INFO - "repository": "", 11:50:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 11:50:11 INFO - "buildid": "20160909090454", 11:50:11 INFO - "pgo_build": "True", 11:50:11 INFO - "basedir": "/builds/slave/test", 11:50:11 INFO - "buildnumber": 743, 11:50:11 INFO - "slavename": "talos-linux64-ix-017", 11:50:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:50:11 INFO - "platform": "ubuntu64_hw", 11:50:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 11:50:11 INFO - "branch": "mozilla-aurora", 11:50:11 INFO - "repo_path": "releases/mozilla-aurora", 11:50:11 INFO - "revision": "2997ae20bac0d7cebeb238dc919b63d86100e9da", 11:50:11 INFO - "stage_platform": "linux64-pgo", 11:50:11 INFO - "builduid": "e6d7915ae0d54378b103cd8a49f0b257", 11:50:11 INFO - "slavebuilddir": "test" 11:50:11 INFO - } 11:50:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 11:50:11 INFO - Running post-action listener: _resource_record_post_action 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.442287Z] Finished read-buildbot-config step (success) 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.442376Z] Running download-and-extract step. 11:50:11 INFO - Running pre-action listener: _resource_record_pre_action 11:50:11 INFO - Running main action method: download_and_extract 11:50:11 INFO - mkdir: /builds/slave/test/build/tests 11:50:11 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')]} 11:50:11 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:50:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:50:11 INFO - retry: Failed, sleeping 30 seconds before retrying 11:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:50:41 INFO - retry: Failed, sleeping 60 seconds before retrying 11:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:41 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:42 INFO - Downloaded 1361 bytes. 11:51:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:42 INFO - Using the following test package requirements: 11:51:42 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 11:51:42 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 11:51:42 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 11:51:42 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'jsshell-linux-x86_64.zip'], 11:51:42 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 11:51:42 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 11:51:42 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 11:51:42 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 11:51:42 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 11:51:42 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 11:51:42 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:51: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')]} 11:51:42 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:51:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:51:42 INFO - retry: Failed, sleeping 30 seconds before retrying 11:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:52:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:52:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:52:12 INFO - retry: Failed, sleeping 60 seconds before retrying 11:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:12 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:13 INFO - Downloaded 21769137 bytes. 11:53:13 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'] 11:53:13 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 11:53:14 INFO - Return code: 0 11:53:14 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 11:53:14 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')]} 11:53:14 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:53:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:53:14 INFO - retry: Failed, sleeping 30 seconds before retrying 11:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:53:44 INFO - retry: Failed, sleeping 60 seconds before retrying 11:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:54:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:54:44 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:46 INFO - Downloaded 21769137 bytes. 11:54:46 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'] 11:54:46 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 11:54:47 INFO - Return code: 0 11:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:47 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:54:47 INFO - retry: Failed, sleeping 30 seconds before retrying 11:55:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:55:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:55:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:55:17 INFO - retry: Failed, sleeping 60 seconds before retrying 11:56:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:56:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:56:17 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:18 INFO - Downloaded 14413740 bytes. 11:56:18 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'] 11:56:18 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 11:56:19 INFO - Return code: 0 11:56:19 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')]} 11:56:19 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:56:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:56:19 INFO - retry: Failed, sleeping 30 seconds before retrying 11:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:56:49 INFO - retry: Failed, sleeping 60 seconds before retrying 11:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:49 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:52 INFO - Downloaded 63908978 bytes. 11:57:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:52 INFO - mkdir: /builds/slave/test/properties 11:57:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:52 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:52 INFO - Contents: 11:57:52 INFO - build_url:https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:52 INFO - Running post-action listener: _resource_record_post_action 11:57:52 INFO - Running post-action listener: set_extra_try_arguments 11:57:52 INFO - [mozharness: 2016-09-09 18:57:52.945204Z] Finished download-and-extract step (success) 11:57:52 INFO - [mozharness: 2016-09-09 18:57:52.945436Z] Running populate-webroot step. 11:57:52 INFO - Running pre-action listener: _resource_record_pre_action 11:57:52 INFO - Running main action method: populate_webroot 11:57:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:52 INFO - '--tppagecycles', 11:57:52 INFO - '7'], 11:57:52 INFO - 'tests': ['tsvgm']}}, 11:57:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['damp', 'tps']}, 11:57:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'tests': ['damp', 'tps']}, 11:57:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['dromaeo_dom']}, 11:57:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['basic_compositor_video']}, 11:57:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['a11yr', 11:57:52 INFO - 'ts_paint', 11:57:52 INFO - 'tpaint', 11:57:52 INFO - 'sessionrestore', 11:57:52 INFO - 'sessionrestore_no_auto_restore', 11:57:52 INFO - 'tabpaint']}, 11:57:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['tsvgx', 11:57:52 INFO - 'tsvgr_opacity', 11:57:52 INFO - 'tart', 11:57:52 INFO - 'tscrollx', 11:57:52 INFO - 'cart']}, 11:57:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:52 INFO - 'tsvgr_opacity', 11:57:52 INFO - 'tart', 11:57:52 INFO - 'tscrollx', 11:57:52 INFO - 'cart']}, 11:57:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'talos_options': ['--disable-e10s'], 11:57:52 INFO - 'tests': ['tp5o']}, 11:57:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'tests': ['tp5o']}, 11:57:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'talos_options': ['--disable-e10s', 11:57:52 INFO - '--xperf_path', 11:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:52 INFO - 'tests': ['tp5n']}, 11:57:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:52 INFO - 'talos_options': ['--xperf_path', 11:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:52 INFO - 'tests': ['tp5n']}}, 11:57:52 INFO - 'talos.zip': {'path': '', 11:57:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:52 INFO - Running post-action listener: _resource_record_post_action 11:57:52 INFO - [mozharness: 2016-09-09 18:57:52.967801Z] Finished populate-webroot step (success) 11:57:52 INFO - [mozharness: 2016-09-09 18:57:52.967892Z] Running create-virtualenv step. 11:57:52 INFO - Running pre-action listener: _resource_record_pre_action 11:57:52 INFO - Running main action method: create_virtualenv 11:57:52 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:52 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 11:57:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:53 INFO - Using real prefix '/usr' 11:57:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:53 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:55 INFO - Installing pip.................done. 11:57:55 INFO - Return code: 0 11:57:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:55 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:57:55 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 11:57:55 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 11:57:55 INFO - Using env: {'DISPLAY': ':0', 11:57:55 INFO - 'HOME': '/home/cltbld', 11:57:55 INFO - 'LANG': 'en_US.UTF-8', 11:57:55 INFO - 'LANGUAGE': 'en_US:en', 11:57:55 INFO - 'LOGNAME': 'cltbld', 11:57:55 INFO - 'MAIL': '/var/mail/cltbld', 11:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:55 INFO - 'MOZ_NO_REMOTE': '1', 11:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:55 INFO - 'NO_EM_RESTART': '1', 11:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:55 INFO - 'PWD': '/builds/slave/test', 11:57:55 INFO - 'SHELL': '/bin/bash', 11:57:55 INFO - 'SHLVL': '1', 11:57:55 INFO - 'TERM': 'linux', 11:57:55 INFO - 'TMOUT': '86400', 11:57:55 INFO - 'USER': 'cltbld', 11:57:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:55 INFO - '_': '/tools/buildbot/bin/python'} 11:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:55 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 11:57:55 INFO - Cleaning up... 11:57:55 INFO - Return code: 0 11:57:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:55 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:57:55 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 11:57:55 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 11:57:55 INFO - Using env: {'DISPLAY': ':0', 11:57:55 INFO - 'HOME': '/home/cltbld', 11:57:55 INFO - 'LANG': 'en_US.UTF-8', 11:57:55 INFO - 'LANGUAGE': 'en_US:en', 11:57:55 INFO - 'LOGNAME': 'cltbld', 11:57:55 INFO - 'MAIL': '/var/mail/cltbld', 11:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:55 INFO - 'MOZ_NO_REMOTE': '1', 11:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:55 INFO - 'NO_EM_RESTART': '1', 11:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:55 INFO - 'PWD': '/builds/slave/test', 11:57:55 INFO - 'SHELL': '/bin/bash', 11:57:55 INFO - 'SHLVL': '1', 11:57:55 INFO - 'TERM': 'linux', 11:57:55 INFO - 'TMOUT': '86400', 11:57:55 INFO - 'USER': 'cltbld', 11:57:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:55 INFO - '_': '/tools/buildbot/bin/python'} 11:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:55 INFO - Downloading/unpacking psutil>=3.1.1 11:57:55 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 11:57:55 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 11:57:55 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 11:57:55 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 11:57:55 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 11:57:55 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 11:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:57 INFO - Installing collected packages: psutil 11:57:57 INFO - Running setup.py install for psutil 11:57:58 INFO - building 'psutil._psutil_linux' extension 11:57:58 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 11:57:58 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 11:57:58 INFO - building 'psutil._psutil_posix' extension 11:57:58 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 11:57:58 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 11:57:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:58 INFO - Successfully installed psutil 11:57:58 INFO - Cleaning up... 11:57:58 INFO - Return code: 0 11:57:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:57:58 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 11:57:58 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 11:57:58 INFO - Using env: {'DISPLAY': ':0', 11:57:58 INFO - 'HOME': '/home/cltbld', 11:57:58 INFO - 'LANG': 'en_US.UTF-8', 11:57:58 INFO - 'LANGUAGE': 'en_US:en', 11:57:58 INFO - 'LOGNAME': 'cltbld', 11:57:58 INFO - 'MAIL': '/var/mail/cltbld', 11:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:58 INFO - 'MOZ_NO_REMOTE': '1', 11:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:58 INFO - 'NO_EM_RESTART': '1', 11:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:58 INFO - 'PWD': '/builds/slave/test', 11:57:58 INFO - 'SHELL': '/bin/bash', 11:57:58 INFO - 'SHLVL': '1', 11:57:58 INFO - 'TERM': 'linux', 11:57:58 INFO - 'TMOUT': '86400', 11:57:58 INFO - 'USER': 'cltbld', 11:57:58 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:58 INFO - '_': '/tools/buildbot/bin/python'} 11:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:58:00 INFO - Installing collected packages: mozsystemmonitor 11:58:00 INFO - Running setup.py install for mozsystemmonitor 11:58:00 INFO - Successfully installed mozsystemmonitor 11:58:00 INFO - Cleaning up... 11:58:00 INFO - Return code: 0 11:58:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:58:00 INFO - Using env: {'DISPLAY': ':0', 11:58:00 INFO - 'HOME': '/home/cltbld', 11:58:00 INFO - 'LANG': 'en_US.UTF-8', 11:58:00 INFO - 'LANGUAGE': 'en_US:en', 11:58:00 INFO - 'LOGNAME': 'cltbld', 11:58:00 INFO - 'MAIL': '/var/mail/cltbld', 11:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:00 INFO - 'MOZ_NO_REMOTE': '1', 11:58:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:00 INFO - 'NO_EM_RESTART': '1', 11:58:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:00 INFO - 'PWD': '/builds/slave/test', 11:58:00 INFO - 'SHELL': '/bin/bash', 11:58:00 INFO - 'SHLVL': '1', 11:58:00 INFO - 'TERM': 'linux', 11:58:00 INFO - 'TMOUT': '86400', 11:58:00 INFO - 'USER': 'cltbld', 11:58:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:00 INFO - '_': '/tools/buildbot/bin/python'} 11:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:01 INFO - Downloading/unpacking jsonschema==2.5.1 11:58:01 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 11:58:01 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 11:58:01 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 11:58:01 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 11:58:01 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 11:58:01 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 11:58:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:58:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:58:02 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 11:58:02 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 11:58:02 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 11:58:02 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 11:58:02 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 11:58:02 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 11:58:03 INFO - Downloading functools32-3.2.3-2.tar.gz 11:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:58:03 INFO - warning: no files found matching '*.txt' 11:58:03 INFO - no previously-included directories found matching 'build' 11:58:03 INFO - no previously-included directories found matching 'dist' 11:58:03 INFO - no previously-included directories found matching '.git*' 11:58:03 INFO - Installing collected packages: jsonschema, functools32 11:58:03 INFO - Running setup.py install for functools32 11:58:03 INFO - warning: no files found matching '*.txt' 11:58:03 INFO - no previously-included directories found matching 'build' 11:58:03 INFO - no previously-included directories found matching 'dist' 11:58:03 INFO - no previously-included directories found matching '.git*' 11:58:03 INFO - Successfully installed jsonschema functools32 11:58:03 INFO - Cleaning up... 11:58:03 INFO - Return code: 0 11:58:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:03 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 11:58:03 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 11:58:03 INFO - Using env: {'DISPLAY': ':0', 11:58:03 INFO - 'HOME': '/home/cltbld', 11:58:03 INFO - 'LANG': 'en_US.UTF-8', 11:58:03 INFO - 'LANGUAGE': 'en_US:en', 11:58:03 INFO - 'LOGNAME': 'cltbld', 11:58:03 INFO - 'MAIL': '/var/mail/cltbld', 11:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:03 INFO - 'MOZ_NO_REMOTE': '1', 11:58:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:03 INFO - 'NO_EM_RESTART': '1', 11:58:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:03 INFO - 'PWD': '/builds/slave/test', 11:58:03 INFO - 'SHELL': '/bin/bash', 11:58:03 INFO - 'SHLVL': '1', 11:58:03 INFO - 'TERM': 'linux', 11:58:03 INFO - 'TMOUT': '86400', 11:58:03 INFO - 'USER': 'cltbld', 11:58:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:03 INFO - '_': '/tools/buildbot/bin/python'} 11:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:58:03 INFO - Cleaning up... 11:58:03 INFO - Return code: 0 11:58:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:03 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 11:58:03 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 11:58:03 INFO - Using env: {'DISPLAY': ':0', 11:58:03 INFO - 'HOME': '/home/cltbld', 11:58:03 INFO - 'LANG': 'en_US.UTF-8', 11:58:03 INFO - 'LANGUAGE': 'en_US:en', 11:58:03 INFO - 'LOGNAME': 'cltbld', 11:58:03 INFO - 'MAIL': '/var/mail/cltbld', 11:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:03 INFO - 'MOZ_NO_REMOTE': '1', 11:58:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:03 INFO - 'NO_EM_RESTART': '1', 11:58:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:03 INFO - 'PWD': '/builds/slave/test', 11:58:03 INFO - 'SHELL': '/bin/bash', 11:58:03 INFO - 'SHLVL': '1', 11:58:03 INFO - 'TERM': 'linux', 11:58:03 INFO - 'TMOUT': '86400', 11:58:03 INFO - 'USER': 'cltbld', 11:58:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:03 INFO - '_': '/tools/buildbot/bin/python'} 11:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:03 INFO - Downloading/unpacking blobuploader==1.2.4 11:58:03 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 11:58:03 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 11:58:03 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 11:58:03 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 11:58:03 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 11:58:03 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 11:58:05 INFO - Downloading blobuploader-1.2.4.tar.gz 11:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:58:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:58:05 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 11:58:05 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 11:58:05 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 11:58:05 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 11:58:05 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 11:58:05 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 11:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:58:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:58:06 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 11:58:06 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 11:58:06 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 11:58:06 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 11:58:06 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 11:58:06 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 11:58:06 INFO - Downloading docopt-0.6.1.tar.gz 11:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:58:06 INFO - Installing collected packages: blobuploader, requests, docopt 11:58:06 INFO - Running setup.py install for blobuploader 11:58:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:58:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:58:06 INFO - Running setup.py install for requests 11:58:06 INFO - Running setup.py install for docopt 11:58:06 INFO - Successfully installed blobuploader requests docopt 11:58:06 INFO - Cleaning up... 11:58:07 INFO - Return code: 0 11:58:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:07 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:07 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 11:58:07 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 11:58:07 INFO - Using env: {'DISPLAY': ':0', 11:58:07 INFO - 'HOME': '/home/cltbld', 11:58:07 INFO - 'LANG': 'en_US.UTF-8', 11:58:07 INFO - 'LANGUAGE': 'en_US:en', 11:58:07 INFO - 'LOGNAME': 'cltbld', 11:58:07 INFO - 'MAIL': '/var/mail/cltbld', 11:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:07 INFO - 'MOZ_NO_REMOTE': '1', 11:58:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:07 INFO - 'NO_EM_RESTART': '1', 11:58:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:07 INFO - 'PWD': '/builds/slave/test', 11:58:07 INFO - 'SHELL': '/bin/bash', 11:58:07 INFO - 'SHLVL': '1', 11:58:07 INFO - 'TERM': 'linux', 11:58:07 INFO - 'TMOUT': '86400', 11:58:07 INFO - 'USER': 'cltbld', 11:58:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:07 INFO - '_': '/tools/buildbot/bin/python'} 11:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:07 INFO - Running setup.py (path:/tmp/pip-UAkP0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:07 INFO - Running setup.py (path:/tmp/pip-0x_79I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:07 INFO - Running setup.py (path:/tmp/pip-2WIfOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:07 INFO - Running setup.py (path:/tmp/pip-J9Xnrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:07 INFO - Running setup.py (path:/tmp/pip-T0egpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:07 INFO - Running setup.py (path:/tmp/pip-7jkhIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:07 INFO - Running setup.py (path:/tmp/pip-1ofM4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:07 INFO - Running setup.py (path:/tmp/pip-YLicSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:07 INFO - Running setup.py (path:/tmp/pip-u2qkVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:07 INFO - Running setup.py (path:/tmp/pip-l4_kQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:07 INFO - Running setup.py (path:/tmp/pip-AC1Fby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:08 INFO - Running setup.py (path:/tmp/pip-16ju_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:08 INFO - Running setup.py (path:/tmp/pip-TdNzrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:08 INFO - Running setup.py (path:/tmp/pip-Gf7ItC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:08 INFO - Running setup.py (path:/tmp/pip-i4mZKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:08 INFO - Running setup.py (path:/tmp/pip-p5Bc2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:08 INFO - Running setup.py (path:/tmp/pip-nPtcg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:58:08 INFO - Running setup.py install for manifestparser 11:58:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Running setup.py install for mozcrash 11:58:08 INFO - Running setup.py install for mozdebug 11:58:08 INFO - Running setup.py install for mozdevice 11:58:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Running setup.py install for mozfile 11:58:09 INFO - Running setup.py install for mozhttpd 11:58:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozinfo 11:58:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozInstall 11:58:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozleak 11:58:09 INFO - Running setup.py install for mozlog 11:58:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for moznetwork 11:58:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozprocess 11:58:09 INFO - Running setup.py install for mozprofile 11:58:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozrunner 11:58:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:58:09 INFO - Running setup.py install for mozscreenshot 11:58:10 INFO - Running setup.py install for moztest 11:58:10 INFO - Running setup.py install for mozversion 11:58:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:58:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:58:10 INFO - Cleaning up... 11:58:10 INFO - Return code: 0 11:58:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:10 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:10 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 11:58:10 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 11:58:10 INFO - Using env: {'DISPLAY': ':0', 11:58:10 INFO - 'HOME': '/home/cltbld', 11:58:10 INFO - 'LANG': 'en_US.UTF-8', 11:58:10 INFO - 'LANGUAGE': 'en_US:en', 11:58:10 INFO - 'LOGNAME': 'cltbld', 11:58:10 INFO - 'MAIL': '/var/mail/cltbld', 11:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:10 INFO - 'MOZ_NO_REMOTE': '1', 11:58:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:10 INFO - 'NO_EM_RESTART': '1', 11:58:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:10 INFO - 'PWD': '/builds/slave/test', 11:58:10 INFO - 'SHELL': '/bin/bash', 11:58:10 INFO - 'SHLVL': '1', 11:58:10 INFO - 'TERM': 'linux', 11:58:10 INFO - 'TMOUT': '86400', 11:58:10 INFO - 'USER': 'cltbld', 11:58:10 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:10 INFO - '_': '/tools/buildbot/bin/python'} 11:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:10 INFO - Running setup.py (path:/tmp/pip-CVQlio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:10 INFO - Running setup.py (path:/tmp/pip-RhVw1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:10 INFO - Running setup.py (path:/tmp/pip-ejvL0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:10 INFO - Running setup.py (path:/tmp/pip-XmZOYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:10 INFO - Running setup.py (path:/tmp/pip-uPoGyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:10 INFO - Running setup.py (path:/tmp/pip-GVbsu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:10 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)) 11:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:10 INFO - Running setup.py (path:/tmp/pip-6OBK2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:11 INFO - Running setup.py (path:/tmp/pip-fJ3KK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:11 INFO - Running setup.py (path:/tmp/pip-S4LBdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:11 INFO - Running setup.py (path:/tmp/pip-sO2UTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:11 INFO - Running setup.py (path:/tmp/pip-jEt0Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:11 INFO - Running setup.py (path:/tmp/pip-OL9bNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:11 INFO - Running setup.py (path:/tmp/pip-cHIcpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:11 INFO - Running setup.py (path:/tmp/pip-igIao6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:11 INFO - Running setup.py (path:/tmp/pip-BZfmCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:11 INFO - Running setup.py (path:/tmp/pip-Y9hv3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:11 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)) 11:58:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:11 INFO - Running setup.py (path:/tmp/pip-uC1CGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:11 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)) 11:58:11 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)) 11:58:11 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)) 11:58:11 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)) 11:58:11 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)) 11:58:11 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)) 11:58:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:58:11 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 11:58:11 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 11:58:11 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 11:58:11 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 11:58:11 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 11:58:11 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 11:58:13 INFO - Downloading blessings-1.6.tar.gz 11:58:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:58:13 INFO - Installing collected packages: blessings 11:58:13 INFO - Running setup.py install for blessings 11:58:13 INFO - Successfully installed blessings 11:58:13 INFO - Cleaning up... 11:58:13 INFO - Return code: 0 11:58:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:58:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:14 INFO - Reading from file tmpfile_stdout 11:58:14 INFO - Current package versions: 11:58:14 INFO - argparse == 1.2.1 11:58:14 INFO - blessings == 1.6 11:58:14 INFO - blobuploader == 1.2.4 11:58:14 INFO - docopt == 0.6.1 11:58:14 INFO - functools32 == 3.2.3-2 11:58:14 INFO - jsonschema == 2.5.1 11:58:14 INFO - manifestparser == 1.1 11:58:14 INFO - mozInstall == 1.12 11:58:14 INFO - mozcrash == 0.17 11:58:14 INFO - mozdebug == 0.1 11:58:14 INFO - mozdevice == 0.48 11:58:14 INFO - mozfile == 1.2 11:58:14 INFO - mozhttpd == 0.7 11:58:14 INFO - mozinfo == 0.9 11:58:14 INFO - mozleak == 0.1 11:58:14 INFO - mozlog == 3.3 11:58:14 INFO - moznetwork == 0.27 11:58:14 INFO - mozprocess == 0.23 11:58:14 INFO - mozprofile == 0.28 11:58:14 INFO - mozrunner == 6.12 11:58:14 INFO - mozscreenshot == 0.1 11:58:14 INFO - mozsystemmonitor == 0.3 11:58:14 INFO - moztest == 0.7 11:58:14 INFO - mozversion == 1.4 11:58:14 INFO - psutil == 3.1.1 11:58:14 INFO - requests == 1.2.3 11:58:14 INFO - wsgiref == 0.1.2 11:58:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:14 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')]} 11:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:14 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')]} 11:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:14 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:14 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 11:58:14 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 11:58:14 INFO - Using env: {'DISPLAY': ':0', 11:58:14 INFO - 'HOME': '/home/cltbld', 11:58:14 INFO - 'LANG': 'en_US.UTF-8', 11:58:14 INFO - 'LANGUAGE': 'en_US:en', 11:58:14 INFO - 'LOGNAME': 'cltbld', 11:58:14 INFO - 'MAIL': '/var/mail/cltbld', 11:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:14 INFO - 'MOZ_NO_REMOTE': '1', 11:58:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:14 INFO - 'NO_EM_RESTART': '1', 11:58:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:14 INFO - 'PWD': '/builds/slave/test', 11:58:14 INFO - 'SHELL': '/bin/bash', 11:58:14 INFO - 'SHLVL': '1', 11:58:14 INFO - 'TERM': 'linux', 11:58:14 INFO - 'TMOUT': '86400', 11:58:14 INFO - 'USER': 'cltbld', 11:58:14 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:14 INFO - '_': '/tools/buildbot/bin/python'} 11:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 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)) 11:58:14 INFO - Cleaning up... 11:58:14 INFO - Return code: 0 11:58:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:58:14 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')]} 11:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:14 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')]} 11:58:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:14 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 0x1acc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c2f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d8d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, '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': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', '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 11:58:14 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 11:58:14 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 11:58:14 INFO - Using env: {'DISPLAY': ':0', 11:58:14 INFO - 'HOME': '/home/cltbld', 11:58:14 INFO - 'LANG': 'en_US.UTF-8', 11:58:14 INFO - 'LANGUAGE': 'en_US:en', 11:58:14 INFO - 'LOGNAME': 'cltbld', 11:58:14 INFO - 'MAIL': '/var/mail/cltbld', 11:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:14 INFO - 'MOZ_NO_REMOTE': '1', 11:58:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:14 INFO - 'NO_EM_RESTART': '1', 11:58:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:14 INFO - 'PWD': '/builds/slave/test', 11:58:14 INFO - 'SHELL': '/bin/bash', 11:58:14 INFO - 'SHLVL': '1', 11:58:14 INFO - 'TERM': 'linux', 11:58:14 INFO - 'TMOUT': '86400', 11:58:14 INFO - 'USER': 'cltbld', 11:58:14 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:14 INFO - '_': '/tools/buildbot/bin/python'} 11:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:58:14 INFO - Cleaning up... 11:58:14 INFO - Return code: 0 11:58:14 INFO - Running post-action listener: _resource_record_post_action 11:58:14 INFO - Running post-action listener: _start_resource_monitoring 11:58:14 INFO - Starting resource monitoring. 11:58:14 INFO - [mozharness: 2016-09-09 18:58:14.553195Z] Finished create-virtualenv step (success) 11:58:14 INFO - [mozharness: 2016-09-09 18:58:14.553670Z] Running install step. 11:58:14 INFO - Running pre-action listener: _resource_record_pre_action 11:58:14 INFO - Running main action method: install 11:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:14 INFO - Reading from file tmpfile_stdout 11:58:14 INFO - Detecting whether we're running mozinstall >=1.0... 11:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:58:14 INFO - Reading from file tmpfile_stdout 11:58:14 INFO - Output received: 11:58:14 INFO - Usage: mozinstall [options] installer 11:58:14 INFO - Options: 11:58:14 INFO - -h, --help show this help message and exit 11:58:14 INFO - -d DEST, --destination=DEST 11:58:14 INFO - Directory to install application into. [default: 11:58:14 INFO - "/builds/slave/test"] 11:58:14 INFO - --app=APP Application being installed. [default: firefox] 11:58:14 INFO - mkdir: /builds/slave/test/build/application 11:58:14 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'] 11:58:14 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 11:58:31 INFO - Reading from file tmpfile_stdout 11:58:31 INFO - Output received: 11:58:31 INFO - /builds/slave/test/build/application/firefox/firefox 11:58:31 INFO - Running post-action listener: _resource_record_post_action 11:58:31 INFO - [mozharness: 2016-09-09 18:58:31.443568Z] Finished install step (success) 11:58:31 INFO - [mozharness: 2016-09-09 18:58:31.443903Z] Running run-tests step. 11:58:31 INFO - Running pre-action listener: _resource_record_pre_action 11:58:31 INFO - Running main action method: run_tests 11:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:58:31 INFO - Python 2.7.3 11:58:31 INFO - Return code: 0 11:58:31 INFO - grabbing minidump binary from tooltool 11:58: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')]} 11:58:31 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 0x1d8b3b0>, '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 0x1d8c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:58:31 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 11:58:31 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 11:58:31 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 11:58:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:58:31 INFO - Return code: 0 11:58:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:58:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:58:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:58:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:58:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:58:31 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 11:58:31 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 11:58:31 INFO - Using env: {'DISPLAY': ':0', 11:58:31 INFO - 'HOME': '/home/cltbld', 11:58:31 INFO - 'LANG': 'en_US.UTF-8', 11:58:31 INFO - 'LANGUAGE': 'en_US:en', 11:58:31 INFO - 'LOGNAME': 'cltbld', 11:58:31 INFO - 'MAIL': '/var/mail/cltbld', 11:58:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:31 INFO - 'MOZ_NO_REMOTE': '1', 11:58:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:31 INFO - 'NO_EM_RESTART': '1', 11:58:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:31 INFO - 'PWD': '/builds/slave/test', 11:58:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:31 INFO - 'SHELL': '/bin/bash', 11:58:31 INFO - 'SHLVL': '1', 11:58:31 INFO - 'TERM': 'linux', 11:58:31 INFO - 'TMOUT': '86400', 11:58:31 INFO - 'USER': 'cltbld', 11:58:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339', 11:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:31 INFO - '_': '/tools/buildbot/bin/python'} 11:58:31 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 11:58:31 INFO - mozversion application_buildid: 20160909090454 11:58:31 INFO - mozversion application_changeset: 2997ae20bac0d7cebeb238dc919b63d86100e9da 11:58:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:58:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:58:31 INFO - mozversion application_name: Firefox 11:58:31 INFO - mozversion application_remotingname: firefox-dev 11:58:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:31 INFO - mozversion application_vendor: Mozilla 11:58:31 INFO - mozversion application_version: 50.0a2 11:58:31 INFO - mozversion platform_buildid: 20160909090454 11:58:31 INFO - mozversion platform_changeset: 2997ae20bac0d7cebeb238dc919b63d86100e9da 11:58:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:31 INFO - mozversion platform_version: 50.0a2 11:58:31 INFO - using testdate: 1473447511 11:58:31 INFO - actual date: 1473447511 11:58:31 INFO - starting webserver on 'localhost:41969' 11:58:31 INFO - SUITE-START | Running 2 tests 11:58:31 INFO - TEST-START | dromaeo_css 11:58:31 INFO - operating with platform_type : linux_ 11:58:31 INFO - Initialising browser for dromaeo_css test... 11:58:31 INFO - TEST-INFO | started process 29617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1IyYJ/profile http://localhost:41969/getInfo.html) 11:58:35 INFO - PROCESS | 29617 | __metrics Screen width/height:1600/1200 11:58:35 INFO - PROCESS | 29617 | colorDepth:24 11:58:35 INFO - PROCESS | 29617 | Browser inner width/height: 1024/697 11:58:35 INFO - PROCESS | 29617 | __metrics 11:58:36 INFO - PROCESS | 29617 | 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" 11:58:36 INFO - PROCESS | 29617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:58:37 INFO - TEST-INFO | 29617: exit 0 11:58:37 INFO - Browser initialized. 11:58:37 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:37 INFO - TEST-INFO | started process 29707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1IyYJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:48 INFO - PROCESS | 29707 | RSS: Main: 146313216 11:58:48 INFO - PROCESS | 29707 | 11:59:39 INFO - PROCESS | 29707 | 1473447579462 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] 12:01:48 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41969/tests/dromaeo/cssquery-ext.html) 12:01:49 INFO - PROCESS | 29707 | RSS: Main: 276566016 12:01:49 INFO - PROCESS | 29707 | 12:04:51 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-ext.html (next: http://localhost:41969/tests/dromaeo/cssquery-jquery.html) 12:04:52 INFO - PROCESS | 29707 | RSS: Main: 231641088 12:04:52 INFO - PROCESS | 29707 | 12:07:54 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41969/tests/dromaeo/cssquery-mootools.html) 12:07:55 INFO - PROCESS | 29707 | RSS: Main: 176463872 12:07:55 INFO - PROCESS | 29707 | 12:10:57 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41969/tests/dromaeo/cssquery-prototype.html) 12:10:57 INFO - PROCESS | 29707 | RSS: Main: 229527552 12:10:57 INFO - PROCESS | 29707 | 12:14:00 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41969/tests/dromaeo/cssquery-yui.html) 12:14:00 INFO - PROCESS | 29707 | RSS: Main: 177876992 12:14:00 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | Cycle 1(1): loaded http://localhost:41969/tests/dromaeo/cssquery-yui.html (next: http://localhost:41969/tests/dromaeo/cssquery-dojo.html) 12:17:03 INFO - PROCESS | 29707 | RSS: Main: 265666560 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | __start_tp_report 12:17:03 INFO - PROCESS | 29707 | _x_x_mozilla_page_load 12:17:03 INFO - PROCESS | 29707 | _x_x_mozilla_page_load_details 12:17:03 INFO - PROCESS | 29707 | |i|pagename|runs| 12:17:03 INFO - PROCESS | 29707 | |0;dojo.html;3205.4069997050533;3208.246062175389;3216.131644455997;3224.758143139265;3247.6265229498613;5496.367917689467;5537.197106419567;5558.221848941152;5561.805336813212;5568.415316391776;5504.2018907258525;5575.888482230353;5577.52591029762;5596.328440587125;5638.520725738317;4976.776045077981;4998.6750861193905;5066.873328166797;5106.361704786902;5139.768710408022;5017.799288028475;5112.36095488064;5116.258142569325;5117.104506711329;5120.103981803189;5066.417362003362;5110.821121260757;5120.231965205212;5122.257272695457;5154.716490593016;5192.870178245536;5201.817936372227;5215.295935048795;5217.165253559412;5242.816501422432;4945.357103576512;4962;4990.725510096943;5109.105906466353;5110.693358398471;5397.298351214317;5419.756110975016;5435.076037073667;5436.075867102602;5445.074337362618;5907.847969645921;5914.704264786744;5917.763289468416;5923.229980102602;5928.822135335947;5863.384344643836;5924.97037514814;5928.377520360386;5943.70281485924;5986.790462333841;5567.415421380737;5577.44225577439;5591;5596.216529685807;5600.103983362643;5821.27234095738;6101.054336577838;6101.145839582373;6105.633661980295;6113.113598528189;5687.687177205211;5758.913616295759;5763.510101641407;5820.24336836217;5863.970680146572;5868.2664666916635;5869;5870.7945221917025;5886.0288052471715;5886.470217680344;5779.133130030529;5872.618279811884;5883.852903677443;5889.322727886348;5891.587588868738;5855.736491857791;5914.940850591525;5919.4080591940465;5962.25471816023;5998.040313549811;4585.541445855388;4588.564086411786;4589.403377560896;4591.127685739699;4627.745473998963;4034.3948407739076;4095.2423801596206;4098;4099.8975025624;4128.6284234419045;5501.889962200734;5548.833534993957;5595.440455954372;5612.214289999403;5674.404187560329;5917.201177841022;5951.4941229995065;5954.672493012839;5958.285005799332;5985.16207730918;5656.60403771732;5659.717014149276;5666.15007748841;5670.744816483269;5673.574481913873;4985.825496107619;5078.669886457368;5150.974245128826;5155.716435596079;5156.690598564098;6166.414190651881;6178.443940045508;6180.072989051679;6183.783567575113;6184.196054512884;5972.25346831646;5979.13302571116;5988.910166347421;5991.610545314715;6034.275886893601;5982.162497250475;6058.666773327509;6093.964026115483;6098.146259523759;6119.173911522065;5848.152017957457;5871.412858714094;5876.706164691663;5888.558358123072;5924.229850119457;5977.790777322773;5983.910241346296;5984.700764961822;5986.610870293417;5989.940100599112;5617.129344951539;5806.912896306643;5823.505002074871;5846.269216347957;5862.648241105548;5851.21008663825;5868.970655146697;5870.148828419769;5871.2073870027;5890.7054647266605;5777.3933736957015;5818.0981947798155;5827.59206855533;5831.037878750129;5835.037218858841;5633.295838020247;5808.332041815245;5818.94181058201;5863.882722345615;5877.529797616266;5718.971405142947;5727.513161381329;5733.2260144881575;5748.597598168088;5776.104703770922 12:17:03 INFO - PROCESS | 29707 | |1;ext.html;563.5181919458862;626.1828314050164;639.9744010239591;648.306312245897;650.4373716735025;34495.82752086239;38305.233895322075;38322.23355532888;38353.42469862954;38357.232855342874;48256.51743482564;48654;48743.75628121863;53318;53378;9145.085491450869;9269.397489163217;9277.396969197014;9285.582148803287;9293.58178881952;8493.235608795207;8711.651533938635;9245.72262832116;9324.953375233148;9337.906620933805;8841.38110332277;8893.77765555863;11104.944475277613;11422.885771142306;11437.370944598044;11226.15803814713;11282.153838462065;11288.887111128948;11290.548378064868;11301.208915375968;11057.944710276477;11126.666200014013;11126.777464450746;11134.888651113466;11140.610078647209;46807.29789053167;46984.295235571495;47196.2920556192;47197.52802471966;47209.763951180365;3924.4701965234613;3938.8030598469963;3972.801359931992;4134.152498737752;4310.094880075188;35787.82106089453;35840.82079589585;36030.8198459006;36568.45147322792;37155.25689486222;4690.484046754854;4754;4814.06125805468;4922;4933.358663373739;260178;260887;261429;261642;261897;38544.6145538541;39542.20915581672;39553;67357.32642673608;67815.66092169507;40678.593214068074;40739.38890916639;40902;40940.38589421161;40969.79515102405;17326.39357622477;17339.82660173382;17414.564635884206;17443.389481368093;17474.65050698979;13982.44070237179;14002.649933751534;14002.929985350009;14006.229657368738;14007.789883151761;8580.785480363042;9248.583813728399;9266.860997085048;9338.159565639126;9367.718968430958;3422.349753546818;3529.0118766745354;3550.1539199616054;3553.875614353485;3612.223371975038;1083.7453198498347;1144.1247445704043;1157.670064031747;1213.6177104212322;1231.6551365617463;34913.650863491544;35448.29103417969;35529.644703552636;35735.64264357375;35988;1176.7940610393148;1180.9489554296683;1184.1829137894824;1242.7328124453104;1293.1723696834033;18683.15925783343;18822.81177188238;18904.81095189058;18924;18937.90531047336;570759;719037;727574;734155;735233;59630.70184649049;66127.33872661211;66817;67421.66289168621;67544.32455675381;1466.4134346261367;1478.135290854862;1485.7251408489249;1499.4302165177162;1501.6095815087938;33054;34675.4798678015;34981.30037399289;35734.827449425036;36078.27843443169;62778;64401;64582;70226.64886675739;70397.29602703908;520924;527242;562919;580830;583078;48801;49146;49353.50646493633;49812.501874980786;49859.25211121763;55087;55308;55463.72268138634;55932;56045;45221;47429;47442.52557474381;47744.7612761934;48597.757011216134;45856.541434586565;46665.53334466612;47117;47344;48599.02801944012;44988.77505612451;45533.31700024568;45961.77019114883;46643.533564665275;46693.299600506696;36707.63292367042;39771.204575907745;41214.38178427266;43328.78335608302;43544;30327.545086824157;30386.356416772975;30424.84787576048;30650.846745766128;30845.691543084282 12:17:03 INFO - PROCESS | 29707 | |2;jquery.html;390.264351697051;402.7020005196156;427.21179423962786;433.65307753796975;446.41965444921595;15473.535793926172;20536.486587835312;20550.794492055094;20716;21768.238111666094;20092.497687557818;20187.39437816868;20208.39374818754;20277.69583456249;20513.17947282107;4007.5992400759983;5637.492625663689;5650.0959846424585;5738.856528586777;5886.058230683092;5774.335951365605;5816.33112192097;5887.116932460144;5894.911576326359;5897.734601942923;5886.970565147169;5891.410858914096;6202.875942481171;6573.046908198308;6580.14458120446;6232.501399887998;6675.766348177791;6714.261431242535;6717.059611654372;6719.93280067198;5731;5773.076307790775;5784.739686714108;5785.247917770705;5787.218725472049;13457.125286856322;13517.32413379327;13540.593782186452;13567.660808479768;13695.520656777064;2431.4043059450546;2447.3392184110367;2489.3403248139257;2630.7632313091913;2918.4454953558757;10061.094501494901;10290.977308559688;10346.068853803194;10486.265961382705;10585;1934.864559480837;1978.0999645161417;2004.4788355027652;2016.0524553459998;2059.7528296604355;83458;84236.57881710552;84588.57705711432;85657.57171214104;85974.14025859724;10714.41070741101;10735.302205356627;11091.611793587346;11308.71728206785;11526.366049867338;16433.09617970999;16442.177891105395;16448.671026579403;16462.506124816275;16466.176691165394;8529;8667.783305417415;8684.78288042804;8719.171678690514;8761.518116503526;8398.622062007224;8434.831303373898;8455.281301089353;8619.310455163572;8661.566921653892;4230.598093181143;4957.182064959314;4963.6525443218625;5014;5043.193089105725;1774.7374506214571;1923.5287354598213;2093.235968871351;2167.956640867174;2170.6092903277563;920.061537232033;953.4041224234853;1021.5760459409262;1088.3905013192636;1097.078454098545;19722;20652.58581144421;20772.79227207739;20838;20921.476963076046;1060.9946950265353;1065.6217042949797;1068.9732756681144;1087.4671411008549;1102.415719668577;10802.729931751612;11695;11752.823707644393;11799;11834.230774999598;525180;554921;556316;580371;580398;91035.54482227546;91607;93238.53380733189;95579.52210238905;99269.50365248127;1064.5050051725984;1102.101787043573;1108.894655007773;1111.5887121764838;1120.0983208517223;18662.626747465245;18990.525236869187;19139.23443062262;19344.806551934864;19494.61010779805;44823.327650084626;45594.544054560356;46015.07969840518;46969.765151174026;47180.056398871144;64760.67619661872;65748.34251657422;65829.34170658424;67298;67598;8285.710000149966;8417.410781245255;8467.49195048299;8538.231559159705;8604.440711353742;11795.705107372385;14314.284285785461;14436.855631443552;14484.203368814817;14722;12125.81811272814;12239.082068844695;12419.19275247106;12504.249745015328;12785.16896401731;11749.823752643888;11755.88244117548;11873.584424545099;11965.641030769091;12314.876851231373;11492.82760758606;11959.820602690792;12506.874931250572;12650.430730617329;13124;8467.745967620982;8593.742187734379;8622.396432249683;8708.78228044304;8901.777455563662;10582.735431614272;11708.765824683629;11725.648230553097;12115.15193936416;12254.264744115382 12:17:03 INFO - PROCESS | 29707 | |3;mootools.html;1537.861592456679;1571.2300972523462;1718.1495159895853;1832.8442082422994;1868.355417381003;8449.999999999993;9135.04082071382;9140.862887056683;9150.313726470507;9158.679446219392;7894.565798881059;8496.745097647081;8676.696315628955;8726.738197854058;8954.149355811187;2340.847844890077;2357.846739961897;2364.7398786133513;2380.75002124777;2382.3924899150693;2317.9884100579475;2322.6632138339933;2388.7850093491584;2390.916317928873;2401.6157414813633;2359.49270906755;2364.8817559122062;2369.360272726363;2371.4664200554907;2374.6675465434855;2290.335802617239;2300.1719381022817;2304.228083592007;2306.884655767213;2330.4057465346395;2269;2277.4989502309463;2282.8630282182958;2283.7259528856644;2284.942876428086;8820.8676869847;8934.06192349807;8968.775780605538;8990.640374385017;8991.640334386617;5126.974365128188;5245.554127899133;5318.414974352818;5363.329583802025;5366;3169.3185965017633;3238.4008958342783;3328.434166191729;3328.833558322074;3328.8335583220983;1080.3247970018738;1082.577794660083;1088.820344643133;1092.1972350322508;1117.435694974033;12421.875781242163;12458.501659933592;12470.314132722697;12483;12498;4475.373447717322;4594.65540084495;4681.976590117028;4799.18413869643;4835.44392394872;10540.578376864993;10545.261831671864;10566.630167944086;10574.365538067741;10578;5392.946070539323;5410.107332290128;5421.349438067457;5422.7798745282;5424.403315635277;4291.442112525352;4783.976080119577;4895.31465594817;4946.282788995647;5005.1491246488185;4091.460769451993;4128.958710412918;4190.308599081118;4219.07180420307;4223.640990515779;1357.3484727330936;1378.8827949624194;1383.9169649821042;1388.5348408283137;1393.8048673185763;865.8398196333699;915.8671992561042;964.1948972607951;970.9271804614682;971.5336638413601;6054.66699331541;6075.756969721162;6125;6165.475934545524;6284.937150628527;979.1139019187647;1043.1706793099504;1059.9364038157735;1064.4784055812595;1081.221520505235;4113.91772164555;4243.469566304212;4252.617264446215;4278.187144442546;4379.1241751649795;11223.214374993671;11569.76860462786;11570.363630000431;11575.536978520933;11586.594469193538;11233;11335.886641133648;11407.828882566768;11508.367039812723;11613;8472.19514146155;8541.658333666708;8614.827703445895;8622.137786221454;8628.35287353451;6183.443490085914;6184.165137706352;6209.348018457997;6215;6268.153799237227;10580.470976450993;10596.993285637853;10610.787784244427;10660.307080039773;10662.147028237878;10256.53845576936;10657.786844262917;10799;10881.238313317992;10921.071708904832;7036.683349249193;7236.963815181101;7411.0736157980255;7642.312191902755;7824.84350313002;8756.343274254585;8851.380403371702;8853.867191992254;8869.379143459895;8869.556522173994;7307.6349166873915;7502.474826762074;7560.168381478143;7831.569263690551;7854.096778870503;7459.2540745927145;7602.581857997863;7759.844803104019;7770.728024519115;7976;7218.819529511804;7320.048393708997;7324.487285889936;7339.266073392618;7344.522606030591;5131.615128865275;5213.061648903082;5230.7384630769075;5230.790768369223;5443.755031023535;5106.642535022661;5131.050755610224;5198.792048318025;5261.263423120843;5284.894302113859 12:17:03 INFO - PROCESS | 29707 | |4;prototype.html;327.6034841594081;338.3520558131177;338.5209927951948;339.39587534189144;343.18151209365664;5506.201600767887;5522.66863988161;5533.529649979757;5546.251256080428;5553.944460555394;5398.379186393556;5407.107827208516;5436.429174936634;5446.15584584389;5447.093517268954;4297.1190905864305;4312.654987600984;4313.892152696177;4317.633001194902;4324.135172965403;3859.5947425520335;4124.876253712393;4292.561991732758;4293.162833247518;4297.914041719164;4262.97868510657;4265.381519679645;4266.2320782259185;4268.423762792015;4269.509006464265;4055.7566546007038;4260.44614200152;4269.6550586565445;4278.165757677254;4309.741415515064;3699.5375578052744;3727.2172843702583;3761.9811900940595;3773.3773927301963;3776.093737503007;5325.467453254643;5343.145096784496;5347.598930080259;5349.786008559653;5352.197170424429;4706.623470122382;4856.174450343415;4872.269159626049;4873.317735516995;4882.902341953141;5643.717814109278;5656.575756818214;5663.7451314690525;5679.460451257124;5685.583626958526;4099.672026237894;4118.320477121271;4119;4121.62905338521;4125.195586860564;5385.569154467594;5564.749586268627;5950.43470870271;5956.761729530814;5966.761329546813;4196.748195108304;4205.558416366299;4207.368894665825;4218.282891908383;4225.535191128974;4236.639885609696;4252.1928180523155;4267.914641707149;4273.35899615054;4277.529471758166;4856.295837103671;4856.975715121473;4858.149823780825;4867.172580661296;4887.266909963463;5556.277683901067;5663.405342439067;5678.261825962599;5686.232358631614;5703.17303990919;1467.9045862018934;1478.0245038274902;1478.7190433817755;1482.9332680029424;1484.094702231638;919.8764989716382;948.6529128637352;960.5725452173716;960.9279304052224;965.652365148546;4935.889424879445;4944.59079162437;4950.6782059166035;4965.280034394992;4984.551390374883;5171.50870667286;5173.560247379015;5180.7150606717;5194.818181363634;5219.973900130475;4577.542245775396;4911.238757992517;4935.926910697515;4942.777575009133;4965.90068198634;2766.695663477016;2767.6955534891144;2779.8332100074063;2788.6653601567536;2796.1611516544954;5330.333708286465;5343.786248550092;5343.786248550092;5344.946550534522;5356.7323932401205;5231.790728370898;5267.920981185285;5585.860353491195;5683.380236632623;5996.220491336098;5968.582199246011;6095;6095.573309868267;6105.572609917263;6134.263888333428;5853.9121913172185;5934.703264836828;5961.970190149022;5965.582409231486;5986.580939334205;5610.691411972381;5852.356240813422;5893.528517718658;5902.852428689145;5905.5570832186895;6149.323574406902;6158.568900176944;6161.876762464815;6175;6184.783532576338;3964.3657014878636;4247.5752424757275;4275.957240427556;4278.614924656796;4289.506706728766;5867.882642347044;5902.5277977762535;5903.616264942765;5905.143754155709;5920.158324004416;5723.484886360247;5784.855378615568;5798.536117110537;5801.3908539602735;5809.709514524341;5625.774969001194;5730.111832665943;5731.965152370848;5757.91363129545;5762.337331206965;5710.829279997673;5711.400302968129;5721.170430287668;5724.141378793048;5734.627249228787;5544.916826247691;5784.50831679312;5788.739506722123;5795.855103622274;5802;5606.327240731139;5633.324001119892;5645.576581756302;5651.632643878134;5655.077273726953 12:17:03 INFO - PROCESS | 29707 | |5;yui.html;851.6508231625035;902.7201567514071;907.8138981508791;924.7364501117183;951.158224970901;16959.660806783857;17829.91085044576;17843.107844607766;17870.731939020927;17888.64222715548;16213.432529861428;16244.187790610482;16249.106299153542;16373.83626163738;16400.097994610293;722.9313215244556;864.1056506515727;888;895.1496078725205;901.9549022548879;869.652139144341;871.8517851965149;872.2672954718035;877.7586163804941;878.2403221213636;916.8533034714447;918.811643613061;919.0992827029515;920.1534588178891;925.4909799610222;759.2483441393036;905.529124855075;928.187835643815;929.3819609959369;934.5093825741473;685.1333063674456;690.4303949241895;691.2085661917076;691.3190507350256;691.9966049228639;16009.359625614961;16046.35814567416;16056.598585035468;16071.91964040184;16080.43718469848;15461.226938653022;15887.126208058553;15921.203939802963;15969;16059.357625694958;12767.489300427971;12900.80648790269;13074.215547067206;13104.737905241842;13854.099483533639;4786.976065119652;4845.757712114416;4869.586085182764;4925.138100832376;5027.296178535008;78047.60976195139;78197.60901195515;78514.21485785184;79239.60380198061;79358;11597.362145081932;11742.823857642143;11749.118816088827;11931.940340298243;12006.759864802654;20437.080331384826;20526.281580144623;20540.58918821645;20558.69161962572;20589.07349169291;8721.389502734748;8744.956275218583;8818.11818818113;8834;9050.140236677506;4998.900021999612;5055.620828437882;5168.664036837594;5169.069567477815;5323.813666521731;3422.127357523865;3498.772579782357;3529.8235088245483;3537.3102245061964;3540.6459354064386;616.442119881508;617.4319625944142;628.0485065126337;634.8222497700651;656.5174596671443;546.5709418106767;758.1773775453585;793.9722109726248;828.7871383406199;847.1401527449643;9656.22750179984;9926.404415734934;10235.488225588691;10435.895641043644;10687.519061642244;765.9468231182125;852.9147085291421;870.2341939093557;871.285545852395;872.0843114729508;4400.713953593006;4591.127685739699;4626.930596041062;4642.0715856828765;4649.604783593365;128191;131560;132259.33870330587;133506;135852;64313.35686643074;74590;91067.54466227627;106429.46785266024;107146.46426767972;57322.42677573171;58856.705716471144;82933.58533207295;89972;91476.54261728648;9917.5109613812;10130.53842746734;10602.151827853908;10653.520591573553;10720.303180293256;41389;53361.19958200552;53973.73013134909;54535.72732136314;56305.43694563002;77701.61149194218;80044;81289;82459;85130;13899.722005560034;14378;14492.276374086583;14649.340779665154;14958.102513849204;15593.454229101926;15614.921925390301;16139.273732682292;16772.91613541973;16910.57723556921;12891.935540322238;13817.654558636115;13833.584992450242;13957.371918263905;14223.359948802121;14644.853551464348;14883.10701357922;14940.626484337978;14980.925095374454;15025.94818362704;13644.113132646346;13769.655758606115;13905.37425815861;14058.226797526235;15065.698686025999;12194.878051219373;13138.211707297593;13381.130226535244;13491.460341586228;13510.729785404434;12390.876091239334;12581.308028058545;12588.811167832308;12981.610551683465;12996.155249908727 12:17:03 INFO - PROCESS | 29707 | __end_tp_report 12:17:03 INFO - PROCESS | 29707 | __start_cc_report 12:17:03 INFO - PROCESS | 29707 | _x_x_mozilla_cycle_collect,1145 12:17:03 INFO - PROCESS | 29707 | __end_cc_report 12:17:03 INFO - PROCESS | 29707 | __startTimestamp1473448623950__endTimestamp 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | ------- Summary: start ------- 12:17:03 INFO - PROCESS | 29707 | Number of tests: 6 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#0] dojo.html Cycles:165 Average:5525.34 Median:5758.91 stddev:613.69 (10.7%) stddev-sans-first:587.97 12:17:03 INFO - PROCESS | 29707 | Values: 3205.4 3208.2 3216.1 3224.8 3247.6 5496.4 5537.2 5558.2 5561.8 5568.4 5504.2 5575.9 5577.5 5596.3 5638.5 4976.8 4998.7 5066.9 5106.4 5139.8 5017.8 5112.4 5116.3 5117.1 5120.1 5066.4 5110.8 5120.2 5122.3 5154.7 5192.9 5201.8 5215.3 5217.2 5242.8 4945.4 4962.0 4990.7 5109.1 5110.7 5397.3 5419.8 5435.1 5436.1 5445.1 5907.8 5914.7 5917.8 5923.2 5928.8 5863.4 5925.0 5928.4 5943.7 5986.8 5567.4 5577.4 5591.0 5596.2 5600.1 5821.3 6101.1 6101.1 6105.6 6113.1 5687.7 5758.9 5763.5 5820.2 5864.0 5868.3 5869.0 5870.8 5886.0 5886.5 5779.1 5872.6 5883.9 5889.3 5891.6 5855.7 5914.9 5919.4 5962.3 5998.0 4585.5 4588.6 4589.4 4591.1 4627.7 4034.4 4095.2 4098.0 4099.9 4128.6 5501.9 5548.8 5595.4 5612.2 5674.4 5917.2 5951.5 5954.7 5958.3 5985.2 5656.6 5659.7 5666.2 5670.7 5673.6 4985.8 5078.7 5151.0 5155.7 5156.7 6166.4 6178.4 6180.1 6183.8 6184.2 5972.3 5979.1 5988.9 5991.6 6034.3 5982.2 6058.7 6094.0 6098.1 6119.2 5848.2 5871.4 5876.7 5888.6 5924.2 5977.8 5983.9 5984.7 5986.6 5989.9 5617.1 5806.9 5823.5 5846.3 5862.6 5851.2 5869.0 5870.1 5871.2 5890.7 5777.4 5818.1 5827.6 5831.0 5835.0 5633.3 5808.3 5818.9 5863.9 5877.5 5719.0 5727.5 5733.2 5748.6 5776.1 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#1] ext.html Cycles:180 Average:67302.05 Median:41092.09 stddev:144119.31 (350.7%) stddev-sans-first:144436.49 12:17:03 INFO - PROCESS | 29707 | Values: 563.5 626.2 640.0 648.3 650.4 34495.8 38305.2 38322.2 38353.4 38357.2 48256.5 48654.0 48743.8 53318.0 53378.0 9145.1 9269.4 9277.4 9285.6 9293.6 8493.2 8711.7 9245.7 9325.0 9337.9 8841.4 8893.8 11104.9 11422.9 11437.4 11226.2 11282.2 11288.9 11290.5 11301.2 11057.9 11126.7 11126.8 11134.9 11140.6 46807.3 46984.3 47196.3 47197.5 47209.8 3924.5 3938.8 3972.8 4134.2 4310.1 35787.8 35840.8 36030.8 36568.5 37155.3 4690.5 4754.0 4814.1 4922.0 4933.4 260178.0 260887.0 261429.0 261642.0 261897.0 38544.6 39542.2 39553.0 67357.3 67815.7 40678.6 40739.4 40902.0 40940.4 40969.8 17326.4 17339.8 17414.6 17443.4 17474.7 13982.4 14002.6 14002.9 14006.2 14007.8 8580.8 9248.6 9266.9 9338.2 9367.7 3422.3 3529.0 3550.2 3553.9 3612.2 1083.7 1144.1 1157.7 1213.6 1231.7 34913.7 35448.3 35529.6 35735.6 35988.0 1176.8 1180.9 1184.2 1242.7 1293.2 18683.2 18822.8 18904.8 18924.0 18937.9 570759.0 719037.0 727574.0 734155.0 735233.0 59630.7 66127.3 66817.0 67421.7 67544.3 1466.4 1478.1 1485.7 1499.4 1501.6 33054.0 34675.5 34981.3 35734.8 36078.3 62778.0 64401.0 64582.0 70226.6 70397.3 520924.0 527242.0 562919.0 580830.0 583078.0 48801.0 49146.0 49353.5 49812.5 49859.3 55087.0 55308.0 55463.7 55932.0 56045.0 45221.0 47429.0 47442.5 47744.8 48597.8 45856.5 46665.5 47117.0 47344.0 48599.0 44988.8 45533.3 45961.8 46643.5 46693.3 36707.6 39771.2 41214.4 43328.8 43544.0 30327.5 30386.4 30424.8 30650.8 30845.7 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#2] jquery.html Cycles:180 Average:31529.37 Median:2169.28 stddev:92133.48 (4247.2%) stddev-sans-first:92362.27 12:17:03 INFO - PROCESS | 29707 | Values: 390.3 402.7 427.2 433.7 446.4 15473.5 20536.5 20550.8 20716.0 21768.2 20092.5 20187.4 20208.4 20277.7 20513.2 4007.6 5637.5 5650.1 5738.9 5886.1 5774.3 5816.3 5887.1 5894.9 5897.7 5887.0 5891.4 6202.9 6573.0 6580.1 6232.5 6675.8 6714.3 6717.1 6719.9 5731.0 5773.1 5784.7 5785.2 5787.2 13457.1 13517.3 13540.6 13567.7 13695.5 2431.4 2447.3 2489.3 2630.8 2918.4 10061.1 10291.0 10346.1 10486.3 10585.0 1934.9 1978.1 2004.5 2016.1 2059.8 83458.0 84236.6 84588.6 85657.6 85974.1 10714.4 10735.3 11091.6 11308.7 11526.4 16433.1 16442.2 16448.7 16462.5 16466.2 8529.0 8667.8 8684.8 8719.2 8761.5 8398.6 8434.8 8455.3 8619.3 8661.6 4230.6 4957.2 4963.7 5014.0 5043.2 1774.7 1923.5 2093.2 2168.0 2170.6 920.1 953.4 1021.6 1088.4 1097.1 19722.0 20652.6 20772.8 20838.0 20921.5 1061.0 1065.6 1069.0 1087.5 1102.4 10802.7 11695.0 11752.8 11799.0 11834.2 525180.0 554921.0 556316.0 580371.0 580398.0 91035.5 91607.0 93238.5 95579.5 99269.5 1064.5 1102.1 1108.9 1111.6 1120.1 18662.6 18990.5 19139.2 19344.8 19494.6 44823.3 45594.5 46015.1 46969.8 47180.1 64760.7 65748.3 65829.3 67298.0 67598.0 8285.7 8417.4 8467.5 8538.2 8604.4 11795.7 14314.3 14436.9 14484.2 14722.0 12125.8 12239.1 12419.2 12504.2 12785.2 11749.8 11755.9 11873.6 11965.6 12314.9 11492.8 11959.8 12506.9 12650.4 13124.0 8467.7 8593.7 8622.4 8708.8 8901.8 10582.7 11708.8 11725.6 12115.2 12254.3 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#3] mootools.html Cycles:180 Average:5937.89 Median:4535.01 stddev:3403.70 (75.1%) stddev-sans-first:3397.18 12:17:03 INFO - PROCESS | 29707 | Values: 1537.9 1571.2 1718.1 1832.8 1868.4 8450.0 9135.0 9140.9 9150.3 9158.7 7894.6 8496.7 8676.7 8726.7 8954.1 2340.8 2357.8 2364.7 2380.8 2382.4 2318.0 2322.7 2388.8 2390.9 2401.6 2359.5 2364.9 2369.4 2371.5 2374.7 2290.3 2300.2 2304.2 2306.9 2330.4 2269.0 2277.5 2282.9 2283.7 2284.9 8820.9 8934.1 8968.8 8990.6 8991.6 5127.0 5245.6 5318.4 5363.3 5366.0 3169.3 3238.4 3328.4 3328.8 3328.8 1080.3 1082.6 1088.8 1092.2 1117.4 12421.9 12458.5 12470.3 12483.0 12498.0 4475.4 4594.7 4682.0 4799.2 4835.4 10540.6 10545.3 10566.6 10574.4 10578.0 5392.9 5410.1 5421.3 5422.8 5424.4 4291.4 4784.0 4895.3 4946.3 5005.1 4091.5 4129.0 4190.3 4219.1 4223.6 1357.3 1378.9 1383.9 1388.5 1393.8 865.8 915.9 964.2 970.9 971.5 6054.7 6075.8 6125.0 6165.5 6284.9 979.1 1043.2 1059.9 1064.5 1081.2 4113.9 4243.5 4252.6 4278.2 4379.1 11223.2 11569.8 11570.4 11575.5 11586.6 11233.0 11335.9 11407.8 11508.4 11613.0 8472.2 8541.7 8614.8 8622.1 8628.4 6183.4 6184.2 6209.3 6215.0 6268.2 10580.5 10597.0 10610.8 10660.3 10662.1 10256.5 10657.8 10799.0 10881.2 10921.1 7036.7 7237.0 7411.1 7642.3 7824.8 8756.3 8851.4 8853.9 8869.4 8869.6 7307.6 7502.5 7560.2 7831.6 7854.1 7459.3 7602.6 7759.8 7770.7 7976.0 7218.8 7320.0 7324.5 7339.3 7344.5 5131.6 5213.1 5230.7 5230.8 5443.8 5106.6 5131.1 5198.8 5261.3 5284.9 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#4] prototype.html Cycles:180 Average:4739.21 Median:5343.79 stddev:1396.17 (26.1%) stddev-sans-first:1360.26 12:17:03 INFO - PROCESS | 29707 | Values: 327.6 338.4 338.5 339.4 343.2 5506.2 5522.7 5533.5 5546.3 5553.9 5398.4 5407.1 5436.4 5446.2 5447.1 4297.1 4312.7 4313.9 4317.6 4324.1 3859.6 4124.9 4292.6 4293.2 4297.9 4263.0 4265.4 4266.2 4268.4 4269.5 4055.8 4260.4 4269.7 4278.2 4309.7 3699.5 3727.2 3762.0 3773.4 3776.1 5325.5 5343.1 5347.6 5349.8 5352.2 4706.6 4856.2 4872.3 4873.3 4882.9 5643.7 5656.6 5663.7 5679.5 5685.6 4099.7 4118.3 4119.0 4121.6 4125.2 5385.6 5564.7 5950.4 5956.8 5966.8 4196.7 4205.6 4207.4 4218.3 4225.5 4236.6 4252.2 4267.9 4273.4 4277.5 4856.3 4857.0 4858.1 4867.2 4887.3 5556.3 5663.4 5678.3 5686.2 5703.2 1467.9 1478.0 1478.7 1482.9 1484.1 919.9 948.7 960.6 960.9 965.7 4935.9 4944.6 4950.7 4965.3 4984.6 5171.5 5173.6 5180.7 5194.8 5220.0 4577.5 4911.2 4935.9 4942.8 4965.9 2766.7 2767.7 2779.8 2788.7 2796.2 5330.3 5343.8 5343.8 5344.9 5356.7 5231.8 5267.9 5585.9 5683.4 5996.2 5968.6 6095.0 6095.6 6105.6 6134.3 5853.9 5934.7 5962.0 5965.6 5986.6 5610.7 5852.4 5893.5 5902.9 5905.6 6149.3 6158.6 6161.9 6175.0 6184.8 3964.4 4247.6 4276.0 4278.6 4289.5 5867.9 5902.5 5903.6 5905.1 5920.2 5723.5 5784.9 5798.5 5801.4 5809.7 5625.8 5730.1 5732.0 5757.9 5762.3 5710.8 5711.4 5721.2 5724.1 5734.6 5544.9 5784.5 5788.7 5795.9 5802.0 5606.3 5633.3 5645.6 5651.6 5655.1 12:17:03 INFO - PROCESS | 29707 | 12:17:03 INFO - PROCESS | 29707 | [#5] yui.html Cycles:180 Average:21515.20 Median:4495.92 stddev:30893.39 (687.1%) stddev-sans-first:30941.10 12:17:03 INFO - PROCESS | 29707 | Values: 851.7 902.7 907.8 924.7 951.2 16959.7 17829.9 17843.1 17870.7 17888.6 16213.4 16244.2 16249.1 16373.8 16400.1 722.9 864.1 888.0 895.1 902.0 869.7 871.9 872.3 877.8 878.2 916.9 918.8 919.1 920.2 925.5 759.2 905.5 928.2 929.4 934.5 685.1 690.4 691.2 691.3 692.0 16009.4 16046.4 16056.6 16071.9 16080.4 15461.2 15887.1 15921.2 15969.0 16059.4 12767.5 12900.8 13074.2 13104.7 13854.1 4787.0 4845.8 4869.6 4925.1 5027.3 78047.6 78197.6 78514.2 79239.6 79358.0 11597.4 11742.8 11749.1 11931.9 12006.8 20437.1 20526.3 20540.6 20558.7 20589.1 8721.4 8745.0 8818.1 8834.0 9050.1 4998.9 5055.6 5168.7 5169.1 5323.8 3422.1 3498.8 3529.8 3537.3 3540.6 616.4 617.4 628.0 634.8 656.5 546.6 758.2 794.0 828.8 847.1 9656.2 9926.4 10235.5 10435.9 10687.5 765.9 852.9 870.2 871.3 872.1 4400.7 4591.1 4626.9 4642.1 4649.6 128191.0 131560.0 132259.3 133506.0 135852.0 64313.4 74590.0 91067.5 106429.5 107146.5 57322.4 58856.7 82933.6 89972.0 91476.5 9917.5 10130.5 10602.2 10653.5 10720.3 41389.0 53361.2 53973.7 54535.7 56305.4 77701.6 80044.0 81289.0 82459.0 85130.0 13899.7 14378.0 14492.3 14649.3 14958.1 15593.5 15614.9 16139.3 16772.9 16910.6 12891.9 13817.7 13833.6 13957.4 14223.4 14644.9 14883.1 14940.6 14980.9 15025.9 13644.1 13769.7 13905.4 14058.2 15065.7 12194.9 13138.2 13381.1 13491.5 13510.7 12390.9 12581.3 12588.8 12981.6 12996.2 12:17:03 INFO - PROCESS | 29707 | -------- Summary: end -------- 12:17:03 INFO - PROCESS | 29707 | 12:17:04 INFO - TEST-INFO | 29707: exit 0 12:17:04 INFO - TEST-OK | dromaeo_css | took 1112977ms 12:17:04 INFO - TEST-START | kraken 12:17:04 INFO - operating with platform_type : linux_ 12:17:04 INFO - Initialising browser for kraken test... 12:17:04 INFO - TEST-INFO | started process 29914 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5o9L9/profile http://localhost:41969/getInfo.html) 12:17:08 INFO - PROCESS | 29914 | __metrics Screen width/height:1600/1200 12:17:08 INFO - PROCESS | 29914 | colorDepth:24 12:17:08 INFO - PROCESS | 29914 | Browser inner width/height: 1024/697 12:17:08 INFO - PROCESS | 29914 | __metrics 12:17:09 INFO - PROCESS | 29914 | 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" 12:17:09 INFO - PROCESS | 29914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:17:09 INFO - TEST-INFO | 29914: exit 0 12:17:09 INFO - Browser initialized. 12:17:09 INFO - Running cycle 1/1 for kraken test... 12:17:09 INFO - TEST-INFO | started process 30005 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5o9L9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:21 INFO - PROCESS | 30005 | RSS: Main: 146604032 12:17:21 INFO - PROCESS | 30005 | 12:17:24 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:24 INFO - PROCESS | 30005 | RSS: Main: 200433664 12:17:24 INFO - PROCESS | 30005 | 12:17:27 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-dft) 12:17:28 INFO - PROCESS | 30005 | RSS: Main: 185090048 12:17:28 INFO - PROCESS | 30005 | 12:17:30 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-fft) 12:17:31 INFO - PROCESS | 30005 | RSS: Main: 276930560 12:17:31 INFO - PROCESS | 30005 | 12:17:33 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator) 12:17:34 INFO - PROCESS | 30005 | RSS: Main: 182247424 12:17:34 INFO - PROCESS | 30005 | 12:17:36 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:36 INFO - PROCESS | 30005 | RSS: Main: 180793344 12:17:36 INFO - PROCESS | 30005 | 12:17:39 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:40 INFO - PROCESS | 30005 | RSS: Main: 216522752 12:17:40 INFO - PROCESS | 30005 | 12:17:43 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:44 INFO - PROCESS | 30005 | RSS: Main: 215818240 12:17:44 INFO - PROCESS | 30005 | 12:17:47 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial) 12:17:47 INFO - PROCESS | 30005 | RSS: Main: 215564288 12:17:47 INFO - PROCESS | 30005 | 12:17:49 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:49 INFO - PROCESS | 30005 | RSS: Main: 177700864 12:17:49 INFO - PROCESS | 30005 | 12:17:51 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:52 INFO - PROCESS | 30005 | RSS: Main: 185053184 12:17:52 INFO - PROCESS | 30005 | 12:17:54 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:54 INFO - PROCESS | 30005 | RSS: Main: 187240448 12:17:54 INFO - PROCESS | 30005 | 12:17:57 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:57 INFO - PROCESS | 30005 | RSS: Main: 186757120 12:17:57 INFO - PROCESS | 30005 | 12:18:00 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:01 INFO - PROCESS | 30005 | RSS: Main: 185495552 12:18:01 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | Cycle 1(1): loaded http://localhost:41969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41969/tests/kraken/driver.html?testName=ai-astar) 12:18:02 INFO - PROCESS | 30005 | RSS: Main: 236732416 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | __start_tp_report 12:18:02 INFO - PROCESS | 30005 | _x_x_mozilla_page_load 12:18:02 INFO - PROCESS | 30005 | _x_x_mozilla_page_load_details 12:18:02 INFO - PROCESS | 30005 | |i|pagename|runs| 12:18:02 INFO - PROCESS | 30005 | |0;ai-astar;111;110;118;110;112;110;111;111;110;112 12:18:02 INFO - PROCESS | 30005 | |1;audio-beat-detection;151;115;143;169;114;146;143;117;174;115 12:18:02 INFO - PROCESS | 30005 | |2;audio-dft;161;161;161;162;160;162;159;160;162;158 12:18:02 INFO - PROCESS | 30005 | |3;audio-fft;74;74;74;177;76;74;74;75;163;76 12:18:02 INFO - PROCESS | 30005 | |4;audio-oscillator;83;83;158;83;84;83;136;84;84;146 12:18:02 INFO - PROCESS | 30005 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;117;117;117 12:18:02 INFO - PROCESS | 30005 | |6;imaging-darkroom;146;146;145;146;146;146;146;145;145;145 12:18:02 INFO - PROCESS | 30005 | |7;imaging-desaturate;94;95;95;95;94;94;94;94;95;94 12:18:02 INFO - PROCESS | 30005 | |8;json-parse-financial;51;49;49;50;50;52;49;49;50;50 12:18:02 INFO - PROCESS | 30005 | |9;json-stringify-tinderbox;58;61;59;60;60;59;59;59;61;59 12:18:02 INFO - PROCESS | 30005 | |10;stanford-crypto-aes;72;82;71;74;75;78;72;72;71;71 12:18:02 INFO - PROCESS | 30005 | |11;stanford-crypto-ccm;119;119;126;126;115;114;125;116;150;128 12:18:02 INFO - PROCESS | 30005 | |12;stanford-crypto-pbkdf2;150;150;154;154;153;150;152;149;150;151 12:18:02 INFO - PROCESS | 30005 | |13;stanford-crypto-sha256-iterative;62;61;63;61;60;65;62;61;60;59 12:18:02 INFO - PROCESS | 30005 | __end_tp_report 12:18:02 INFO - PROCESS | 30005 | __start_cc_report 12:18:02 INFO - PROCESS | 30005 | _x_x_mozilla_cycle_collect,1722 12:18:02 INFO - PROCESS | 30005 | __end_cc_report 12:18:02 INFO - PROCESS | 30005 | __startTimestamp1473448682974__endTimestamp 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | ------- Summary: start ------- 12:18:02 INFO - PROCESS | 30005 | Number of tests: 14 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:2.42 (2.2%) stddev-sans-first:2.55 12:18:02 INFO - PROCESS | 30005 | Values: 111.0 110.0 118.0 110.0 112.0 110.0 111.0 111.0 110.0 112.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:144.50 stddev:22.63 (15.7%) stddev-sans-first:23.56 12:18:02 INFO - PROCESS | 30005 | Values: 151.0 115.0 143.0 169.0 114.0 146.0 143.0 117.0 174.0 115.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 12:18:02 INFO - PROCESS | 30005 | Values: 161.0 161.0 161.0 162.0 160.0 162.0 159.0 160.0 162.0 158.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#3] audio-fft Cycles:10 Average:93.70 Median:74.00 stddev:40.36 (54.5%) stddev-sans-first:42.17 12:18:02 INFO - PROCESS | 30005 | Values: 74.0 74.0 74.0 177.0 76.0 74.0 74.0 75.0 163.0 76.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:83.00 stddev:30.99 (37.3%) stddev-sans-first:32.06 12:18:02 INFO - PROCESS | 30005 | Values: 83.0 83.0 158.0 83.0 84.0 83.0 136.0 84.0 84.0 146.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:18:02 INFO - PROCESS | 30005 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 12:18:02 INFO - PROCESS | 30005 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:18:02 INFO - PROCESS | 30005 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:0.97 12:18:02 INFO - PROCESS | 30005 | Values: 51.0 49.0 49.0 50.0 50.0 52.0 49.0 49.0 50.0 50.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.97 (1.6%) stddev-sans-first:0.87 12:18:02 INFO - PROCESS | 30005 | Values: 58.0 61.0 59.0 60.0 60.0 59.0 59.0 59.0 61.0 59.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:3.65 (5.0%) stddev-sans-first:3.81 12:18:02 INFO - PROCESS | 30005 | Values: 72.0 82.0 71.0 74.0 75.0 78.0 72.0 72.0 71.0 71.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:125.50 stddev:10.52 (8.4%) stddev-sans-first:11.01 12:18:02 INFO - PROCESS | 30005 | Values: 119.0 119.0 126.0 126.0 115.0 114.0 125.0 116.0 150.0 128.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.50 stddev:1.83 (1.2%) stddev-sans-first:1.88 12:18:02 INFO - PROCESS | 30005 | Values: 150.0 150.0 154.0 154.0 153.0 150.0 152.0 149.0 150.0 151.0 12:18:02 INFO - PROCESS | 30005 | 12:18:02 INFO - PROCESS | 30005 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.71 (2.8%) stddev-sans-first:1.80 12:18:02 INFO - PROCESS | 30005 | Values: 62.0 61.0 63.0 61.0 60.0 65.0 62.0 61.0 60.0 59.0 12:18:02 INFO - PROCESS | 30005 | -------- Summary: end -------- 12:18:02 INFO - PROCESS | 30005 | 12:18:03 INFO - TEST-INFO | 30005: exit 0 12:18:03 INFO - TEST-OK | kraken | took 59208ms 12:18:03 INFO - SUITE-END | took 1172s 12:18:04 INFO - Completed test suite (00:19:32) 12:18:04 INFO - javascript benchmark 12:18:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.4069997050533, 3208.246062175389, 3216.131644455997, 3224.758143139265, 3247.6265229498613, 5496.367917689467, 5537.197106419567, 5558.221848941152, 5561.805336813212, 5568.415316391776, 5504.2018907258525, 5575.888482230353, 5577.52591029762, 5596.328440587125, 5638.520725738317, 4976.776045077981, 4998.6750861193905, 5066.873328166797, 5106.361704786902, 5139.768710408022, 5017.799288028475, 5112.36095488064, 5116.258142569325, 5117.104506711329, 5120.103981803189, 5066.417362003362, 5110.821121260757, 5120.231965205212, 5122.257272695457, 5154.716490593016, 5192.870178245536, 5201.817936372227, 5215.295935048795, 5217.165253559412, 5242.816501422432, 4945.357103576512, 4962.0, 4990.725510096943, 5109.105906466353, 5110.693358398471, 5397.298351214317, 5419.756110975016, 5435.076037073667, 5436.075867102602, 5445.074337362618, 5907.847969645921, 5914.704264786744, 5917.763289468416, 5923.229980102602, 5928.822135335947, 5863.384344643836, 5924.97037514814, 5928.377520360386, 5943.70281485924, 5986.790462333841, 5567.415421380737, 5577.44225577439, 5591.0, 5596.216529685807, 5600.103983362643, 5821.27234095738, 6101.054336577838, 6101.145839582373, 6105.633661980295, 6113.113598528189, 5687.687177205211, 5758.913616295759, 5763.510101641407, 5820.24336836217, 5863.970680146572, 5868.2664666916635, 5869.0, 5870.7945221917025, 5886.0288052471715, 5886.470217680344, 5779.133130030529, 5872.618279811884, 5883.852903677443, 5889.322727886348, 5891.587588868738, 5855.736491857791, 5914.940850591525, 5919.4080591940465, 5962.25471816023, 5998.040313549811, 4585.541445855388, 4588.564086411786, 4589.403377560896, 4591.127685739699, 4627.745473998963, 4034.3948407739076, 4095.2423801596206, 4098.0, 4099.8975025624, 4128.6284234419045, 5501.889962200734, 5548.833534993957, 5595.440455954372, 5612.214289999403, 5674.404187560329, 5917.201177841022, 5951.4941229995065, 5954.672493012839, 5958.285005799332, 5985.16207730918, 5656.60403771732, 5659.717014149276, 5666.15007748841, 5670.744816483269, 5673.574481913873, 4985.825496107619, 5078.669886457368, 5150.974245128826, 5155.716435596079, 5156.690598564098, 6166.414190651881, 6178.443940045508, 6180.072989051679, 6183.783567575113, 6184.196054512884, 5972.25346831646, 5979.13302571116, 5988.910166347421, 5991.610545314715, 6034.275886893601, 5982.162497250475, 6058.666773327509, 6093.964026115483, 6098.146259523759, 6119.173911522065, 5848.152017957457, 5871.412858714094, 5876.706164691663, 5888.558358123072, 5924.229850119457, 5977.790777322773, 5983.910241346296, 5984.700764961822, 5986.610870293417, 5989.940100599112, 5617.129344951539, 5806.912896306643, 5823.505002074871, 5846.269216347957, 5862.648241105548, 5851.21008663825, 5868.970655146697, 5870.148828419769, 5871.2073870027, 5890.7054647266605, 5777.3933736957015, 5818.0981947798155, 5827.59206855533, 5831.037878750129, 5835.037218858841, 5633.295838020247, 5808.332041815245, 5818.94181058201, 5863.882722345615, 5877.529797616266, 5718.971405142947, 5727.513161381329, 5733.2260144881575, 5748.597598168088, 5776.104703770922], "value": 5484.552631882763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.5181919458862, 626.1828314050164, 639.9744010239591, 648.306312245897, 650.4373716735025, 34495.82752086239, 38305.233895322075, 38322.23355532888, 38353.42469862954, 38357.232855342874, 48256.51743482564, 48654.0, 48743.75628121863, 53318.0, 53378.0, 9145.085491450869, 9269.397489163217, 9277.396969197014, 9285.582148803287, 9293.58178881952, 8493.235608795207, 8711.651533938635, 9245.72262832116, 9324.953375233148, 9337.906620933805, 8841.38110332277, 8893.77765555863, 11104.944475277613, 11422.885771142306, 11437.370944598044, 11226.15803814713, 11282.153838462065, 11288.887111128948, 11290.548378064868, 11301.208915375968, 11057.944710276477, 11126.666200014013, 11126.777464450746, 11134.888651113466, 11140.610078647209, 46807.29789053167, 46984.295235571495, 47196.2920556192, 47197.52802471966, 47209.763951180365, 3924.4701965234613, 3938.8030598469963, 3972.801359931992, 4134.152498737752, 4310.094880075188, 35787.82106089453, 35840.82079589585, 36030.8198459006, 36568.45147322792, 37155.25689486222, 4690.484046754854, 4754.0, 4814.06125805468, 4922.0, 4933.358663373739, 260178.0, 260887.0, 261429.0, 261642.0, 261897.0, 38544.6145538541, 39542.20915581672, 39553.0, 67357.32642673608, 67815.66092169507, 40678.593214068074, 40739.38890916639, 40902.0, 40940.38589421161, 40969.79515102405, 17326.39357622477, 17339.82660173382, 17414.564635884206, 17443.389481368093, 17474.65050698979, 13982.44070237179, 14002.649933751534, 14002.929985350009, 14006.229657368738, 14007.789883151761, 8580.785480363042, 9248.583813728399, 9266.860997085048, 9338.159565639126, 9367.718968430958, 3422.349753546818, 3529.0118766745354, 3550.1539199616054, 3553.875614353485, 3612.223371975038, 1083.7453198498347, 1144.1247445704043, 1157.670064031747, 1213.6177104212322, 1231.6551365617463, 34913.650863491544, 35448.29103417969, 35529.644703552636, 35735.64264357375, 35988.0, 1176.7940610393148, 1180.9489554296683, 1184.1829137894824, 1242.7328124453104, 1293.1723696834033, 18683.15925783343, 18822.81177188238, 18904.81095189058, 18924.0, 18937.90531047336, 570759.0, 719037.0, 727574.0, 734155.0, 735233.0, 59630.70184649049, 66127.33872661211, 66817.0, 67421.66289168621, 67544.32455675381, 1466.4134346261367, 1478.135290854862, 1485.7251408489249, 1499.4302165177162, 1501.6095815087938, 33054.0, 34675.4798678015, 34981.30037399289, 35734.827449425036, 36078.27843443169, 62778.0, 64401.0, 64582.0, 70226.64886675739, 70397.29602703908, 520924.0, 527242.0, 562919.0, 580830.0, 583078.0, 48801.0, 49146.0, 49353.50646493633, 49812.501874980786, 49859.25211121763, 55087.0, 55308.0, 55463.72268138634, 55932.0, 56045.0, 45221.0, 47429.0, 47442.52557474381, 47744.7612761934, 48597.757011216134, 45856.541434586565, 46665.53334466612, 47117.0, 47344.0, 48599.02801944012, 44988.77505612451, 45533.31700024568, 45961.77019114883, 46643.533564665275, 46693.299600506696, 36707.63292367042, 39771.204575907745, 41214.38178427266, 43328.78335608302, 43544.0, 30327.545086824157, 30386.356416772975, 30424.84787576048, 30650.846745766128, 30845.691543084282], "value": 21172.21560819777, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.264351697051, 402.7020005196156, 427.21179423962786, 433.65307753796975, 446.41965444921595, 15473.535793926172, 20536.486587835312, 20550.794492055094, 20716.0, 21768.238111666094, 20092.497687557818, 20187.39437816868, 20208.39374818754, 20277.69583456249, 20513.17947282107, 4007.5992400759983, 5637.492625663689, 5650.0959846424585, 5738.856528586777, 5886.058230683092, 5774.335951365605, 5816.33112192097, 5887.116932460144, 5894.911576326359, 5897.734601942923, 5886.970565147169, 5891.410858914096, 6202.875942481171, 6573.046908198308, 6580.14458120446, 6232.501399887998, 6675.766348177791, 6714.261431242535, 6717.059611654372, 6719.93280067198, 5731.0, 5773.076307790775, 5784.739686714108, 5785.247917770705, 5787.218725472049, 13457.125286856322, 13517.32413379327, 13540.593782186452, 13567.660808479768, 13695.520656777064, 2431.4043059450546, 2447.3392184110367, 2489.3403248139257, 2630.7632313091913, 2918.4454953558757, 10061.094501494901, 10290.977308559688, 10346.068853803194, 10486.265961382705, 10585.0, 1934.864559480837, 1978.0999645161417, 2004.4788355027652, 2016.0524553459998, 2059.7528296604355, 83458.0, 84236.57881710552, 84588.57705711432, 85657.57171214104, 85974.14025859724, 10714.41070741101, 10735.302205356627, 11091.611793587346, 11308.71728206785, 11526.366049867338, 16433.09617970999, 16442.177891105395, 16448.671026579403, 16462.506124816275, 16466.176691165394, 8529.0, 8667.783305417415, 8684.78288042804, 8719.171678690514, 8761.518116503526, 8398.622062007224, 8434.831303373898, 8455.281301089353, 8619.310455163572, 8661.566921653892, 4230.598093181143, 4957.182064959314, 4963.6525443218625, 5014.0, 5043.193089105725, 1774.7374506214571, 1923.5287354598213, 2093.235968871351, 2167.956640867174, 2170.6092903277563, 920.061537232033, 953.4041224234853, 1021.5760459409262, 1088.3905013192636, 1097.078454098545, 19722.0, 20652.58581144421, 20772.79227207739, 20838.0, 20921.476963076046, 1060.9946950265353, 1065.6217042949797, 1068.9732756681144, 1087.4671411008549, 1102.415719668577, 10802.729931751612, 11695.0, 11752.823707644393, 11799.0, 11834.230774999598, 525180.0, 554921.0, 556316.0, 580371.0, 580398.0, 91035.54482227546, 91607.0, 93238.53380733189, 95579.52210238905, 99269.50365248127, 1064.5050051725984, 1102.101787043573, 1108.894655007773, 1111.5887121764838, 1120.0983208517223, 18662.626747465245, 18990.525236869187, 19139.23443062262, 19344.806551934864, 19494.61010779805, 44823.327650084626, 45594.544054560356, 46015.07969840518, 46969.765151174026, 47180.056398871144, 64760.67619661872, 65748.34251657422, 65829.34170658424, 67298.0, 67598.0, 8285.710000149966, 8417.410781245255, 8467.49195048299, 8538.231559159705, 8604.440711353742, 11795.705107372385, 14314.284285785461, 14436.855631443552, 14484.203368814817, 14722.0, 12125.81811272814, 12239.082068844695, 12419.19275247106, 12504.249745015328, 12785.16896401731, 11749.823752643888, 11755.88244117548, 11873.584424545099, 11965.641030769091, 12314.876851231373, 11492.82760758606, 11959.820602690792, 12506.874931250572, 12650.430730617329, 13124.0, 8467.745967620982, 8593.742187734379, 8622.396432249683, 8708.78228044304, 8901.777455563662, 10582.735431614272, 11708.765824683629, 11725.648230553097, 12115.15193936416, 12254.264744115382], "value": 9560.935707717046, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.861592456679, 1571.2300972523462, 1718.1495159895853, 1832.8442082422994, 1868.355417381003, 8449.999999999993, 9135.04082071382, 9140.862887056683, 9150.313726470507, 9158.679446219392, 7894.565798881059, 8496.745097647081, 8676.696315628955, 8726.738197854058, 8954.149355811187, 2340.847844890077, 2357.846739961897, 2364.7398786133513, 2380.75002124777, 2382.3924899150693, 2317.9884100579475, 2322.6632138339933, 2388.7850093491584, 2390.916317928873, 2401.6157414813633, 2359.49270906755, 2364.8817559122062, 2369.360272726363, 2371.4664200554907, 2374.6675465434855, 2290.335802617239, 2300.1719381022817, 2304.228083592007, 2306.884655767213, 2330.4057465346395, 2269.0, 2277.4989502309463, 2282.8630282182958, 2283.7259528856644, 2284.942876428086, 8820.8676869847, 8934.06192349807, 8968.775780605538, 8990.640374385017, 8991.640334386617, 5126.974365128188, 5245.554127899133, 5318.414974352818, 5363.329583802025, 5366.0, 3169.3185965017633, 3238.4008958342783, 3328.434166191729, 3328.833558322074, 3328.8335583220983, 1080.3247970018738, 1082.577794660083, 1088.820344643133, 1092.1972350322508, 1117.435694974033, 12421.875781242163, 12458.501659933592, 12470.314132722697, 12483.0, 12498.0, 4475.373447717322, 4594.65540084495, 4681.976590117028, 4799.18413869643, 4835.44392394872, 10540.578376864993, 10545.261831671864, 10566.630167944086, 10574.365538067741, 10578.0, 5392.946070539323, 5410.107332290128, 5421.349438067457, 5422.7798745282, 5424.403315635277, 4291.442112525352, 4783.976080119577, 4895.31465594817, 4946.282788995647, 5005.1491246488185, 4091.460769451993, 4128.958710412918, 4190.308599081118, 4219.07180420307, 4223.640990515779, 1357.3484727330936, 1378.8827949624194, 1383.9169649821042, 1388.5348408283137, 1393.8048673185763, 865.8398196333699, 915.8671992561042, 964.1948972607951, 970.9271804614682, 971.5336638413601, 6054.66699331541, 6075.756969721162, 6125.0, 6165.475934545524, 6284.937150628527, 979.1139019187647, 1043.1706793099504, 1059.9364038157735, 1064.4784055812595, 1081.221520505235, 4113.91772164555, 4243.469566304212, 4252.617264446215, 4278.187144442546, 4379.1241751649795, 11223.214374993671, 11569.76860462786, 11570.363630000431, 11575.536978520933, 11586.594469193538, 11233.0, 11335.886641133648, 11407.828882566768, 11508.367039812723, 11613.0, 8472.19514146155, 8541.658333666708, 8614.827703445895, 8622.137786221454, 8628.35287353451, 6183.443490085914, 6184.165137706352, 6209.348018457997, 6215.0, 6268.153799237227, 10580.470976450993, 10596.993285637853, 10610.787784244427, 10660.307080039773, 10662.147028237878, 10256.53845576936, 10657.786844262917, 10799.0, 10881.238313317992, 10921.071708904832, 7036.683349249193, 7236.963815181101, 7411.0736157980255, 7642.312191902755, 7824.84350313002, 8756.343274254585, 8851.380403371702, 8853.867191992254, 8869.379143459895, 8869.556522173994, 7307.6349166873915, 7502.474826762074, 7560.168381478143, 7831.569263690551, 7854.096778870503, 7459.2540745927145, 7602.581857997863, 7759.844803104019, 7770.728024519115, 7976.0, 7218.819529511804, 7320.048393708997, 7324.487285889936, 7339.266073392618, 7344.522606030591, 5131.615128865275, 5213.061648903082, 5230.7384630769075, 5230.790768369223, 5443.755031023535, 5106.642535022661, 5131.050755610224, 5198.792048318025, 5261.263423120843, 5284.894302113859], "value": 4746.357264818727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.6034841594081, 338.3520558131177, 338.5209927951948, 339.39587534189144, 343.18151209365664, 5506.201600767887, 5522.66863988161, 5533.529649979757, 5546.251256080428, 5553.944460555394, 5398.379186393556, 5407.107827208516, 5436.429174936634, 5446.15584584389, 5447.093517268954, 4297.1190905864305, 4312.654987600984, 4313.892152696177, 4317.633001194902, 4324.135172965403, 3859.5947425520335, 4124.876253712393, 4292.561991732758, 4293.162833247518, 4297.914041719164, 4262.97868510657, 4265.381519679645, 4266.2320782259185, 4268.423762792015, 4269.509006464265, 4055.7566546007038, 4260.44614200152, 4269.6550586565445, 4278.165757677254, 4309.741415515064, 3699.5375578052744, 3727.2172843702583, 3761.9811900940595, 3773.3773927301963, 3776.093737503007, 5325.467453254643, 5343.145096784496, 5347.598930080259, 5349.786008559653, 5352.197170424429, 4706.623470122382, 4856.174450343415, 4872.269159626049, 4873.317735516995, 4882.902341953141, 5643.717814109278, 5656.575756818214, 5663.7451314690525, 5679.460451257124, 5685.583626958526, 4099.672026237894, 4118.320477121271, 4119.0, 4121.62905338521, 4125.195586860564, 5385.569154467594, 5564.749586268627, 5950.43470870271, 5956.761729530814, 5966.761329546813, 4196.748195108304, 4205.558416366299, 4207.368894665825, 4218.282891908383, 4225.535191128974, 4236.639885609696, 4252.1928180523155, 4267.914641707149, 4273.35899615054, 4277.529471758166, 4856.295837103671, 4856.975715121473, 4858.149823780825, 4867.172580661296, 4887.266909963463, 5556.277683901067, 5663.405342439067, 5678.261825962599, 5686.232358631614, 5703.17303990919, 1467.9045862018934, 1478.0245038274902, 1478.7190433817755, 1482.9332680029424, 1484.094702231638, 919.8764989716382, 948.6529128637352, 960.5725452173716, 960.9279304052224, 965.652365148546, 4935.889424879445, 4944.59079162437, 4950.6782059166035, 4965.280034394992, 4984.551390374883, 5171.50870667286, 5173.560247379015, 5180.7150606717, 5194.818181363634, 5219.973900130475, 4577.542245775396, 4911.238757992517, 4935.926910697515, 4942.777575009133, 4965.90068198634, 2766.695663477016, 2767.6955534891144, 2779.8332100074063, 2788.6653601567536, 2796.1611516544954, 5330.333708286465, 5343.786248550092, 5343.786248550092, 5344.946550534522, 5356.7323932401205, 5231.790728370898, 5267.920981185285, 5585.860353491195, 5683.380236632623, 5996.220491336098, 5968.582199246011, 6095.0, 6095.573309868267, 6105.572609917263, 6134.263888333428, 5853.9121913172185, 5934.703264836828, 5961.970190149022, 5965.582409231486, 5986.580939334205, 5610.691411972381, 5852.356240813422, 5893.528517718658, 5902.852428689145, 5905.5570832186895, 6149.323574406902, 6158.568900176944, 6161.876762464815, 6175.0, 6184.783532576338, 3964.3657014878636, 4247.5752424757275, 4275.957240427556, 4278.614924656796, 4289.506706728766, 5867.882642347044, 5902.5277977762535, 5903.616264942765, 5905.143754155709, 5920.158324004416, 5723.484886360247, 5784.855378615568, 5798.536117110537, 5801.3908539602735, 5809.709514524341, 5625.774969001194, 5730.111832665943, 5731.965152370848, 5757.91363129545, 5762.337331206965, 5710.829279997673, 5711.400302968129, 5721.170430287668, 5724.141378793048, 5734.627249228787, 5544.916826247691, 5784.50831679312, 5788.739506722123, 5795.855103622274, 5802.0, 5606.327240731139, 5633.324001119892, 5645.576581756302, 5651.632643878134, 5655.077273726953], "value": 4294.759080488801, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.6508231625035, 902.7201567514071, 907.8138981508791, 924.7364501117183, 951.158224970901, 16959.660806783857, 17829.91085044576, 17843.107844607766, 17870.731939020927, 17888.64222715548, 16213.432529861428, 16244.187790610482, 16249.106299153542, 16373.83626163738, 16400.097994610293, 722.9313215244556, 864.1056506515727, 888.0, 895.1496078725205, 901.9549022548879, 869.652139144341, 871.8517851965149, 872.2672954718035, 877.7586163804941, 878.2403221213636, 916.8533034714447, 918.811643613061, 919.0992827029515, 920.1534588178891, 925.4909799610222, 759.2483441393036, 905.529124855075, 928.187835643815, 929.3819609959369, 934.5093825741473, 685.1333063674456, 690.4303949241895, 691.2085661917076, 691.3190507350256, 691.9966049228639, 16009.359625614961, 16046.35814567416, 16056.598585035468, 16071.91964040184, 16080.43718469848, 15461.226938653022, 15887.126208058553, 15921.203939802963, 15969.0, 16059.357625694958, 12767.489300427971, 12900.80648790269, 13074.215547067206, 13104.737905241842, 13854.099483533639, 4786.976065119652, 4845.757712114416, 4869.586085182764, 4925.138100832376, 5027.296178535008, 78047.60976195139, 78197.60901195515, 78514.21485785184, 79239.60380198061, 79358.0, 11597.362145081932, 11742.823857642143, 11749.118816088827, 11931.940340298243, 12006.759864802654, 20437.080331384826, 20526.281580144623, 20540.58918821645, 20558.69161962572, 20589.07349169291, 8721.389502734748, 8744.956275218583, 8818.11818818113, 8834.0, 9050.140236677506, 4998.900021999612, 5055.620828437882, 5168.664036837594, 5169.069567477815, 5323.813666521731, 3422.127357523865, 3498.772579782357, 3529.8235088245483, 3537.3102245061964, 3540.6459354064386, 616.442119881508, 617.4319625944142, 628.0485065126337, 634.8222497700651, 656.5174596671443, 546.5709418106767, 758.1773775453585, 793.9722109726248, 828.7871383406199, 847.1401527449643, 9656.22750179984, 9926.404415734934, 10235.488225588691, 10435.895641043644, 10687.519061642244, 765.9468231182125, 852.9147085291421, 870.2341939093557, 871.285545852395, 872.0843114729508, 4400.713953593006, 4591.127685739699, 4626.930596041062, 4642.0715856828765, 4649.604783593365, 128191.0, 131560.0, 132259.33870330587, 133506.0, 135852.0, 64313.35686643074, 74590.0, 91067.54466227627, 106429.46785266024, 107146.46426767972, 57322.42677573171, 58856.705716471144, 82933.58533207295, 89972.0, 91476.54261728648, 9917.5109613812, 10130.53842746734, 10602.151827853908, 10653.520591573553, 10720.303180293256, 41389.0, 53361.19958200552, 53973.73013134909, 54535.72732136314, 56305.43694563002, 77701.61149194218, 80044.0, 81289.0, 82459.0, 85130.0, 13899.722005560034, 14378.0, 14492.276374086583, 14649.340779665154, 14958.102513849204, 15593.454229101926, 15614.921925390301, 16139.273732682292, 16772.91613541973, 16910.57723556921, 12891.935540322238, 13817.654558636115, 13833.584992450242, 13957.371918263905, 14223.359948802121, 14644.853551464348, 14883.10701357922, 14940.626484337978, 14980.925095374454, 15025.94818362704, 13644.113132646346, 13769.655758606115, 13905.37425815861, 14058.226797526235, 15065.698686025999, 12194.878051219373, 13138.211707297593, 13381.130226535244, 13491.460341586228, 13510.729785404434, 12390.876091239334, 12581.308028058545, 12588.811167832308, 12981.610551683465, 12996.155249908727], "value": 8078.949669860138, "unit": "score"}], "name": "dromaeo_css", "value": 7533.866909535716, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 118.0, 110.0, 112.0, 110.0, 111.0, 111.0, 110.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 143.0, 169.0, 114.0, 146.0, 143.0, 117.0, 174.0, 115.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 160.0, 162.0, 159.0, 160.0, 162.0, 158.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 177.0, 76.0, 74.0, 74.0, 75.0, 163.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 158.0, 83.0, 84.0, 83.0, 136.0, 84.0, 84.0, 146.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 50.0, 52.0, 49.0, 49.0, 50.0, 50.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 61.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 82.0, 71.0, 74.0, 75.0, 78.0, 72.0, 72.0, 71.0, 71.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 126.0, 126.0, 115.0, 114.0, 125.0, 116.0, 150.0, 128.0], "value": 123.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 154.0, 154.0, 153.0, 150.0, 152.0, 149.0, 150.0, 151.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 61.0, 60.0, 65.0, 62.0, 61.0, 60.0, 59.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1483.3, "alertThreshold": 2.0}]} 12:18:04 INFO - Return code: 0 12:18:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:04 INFO - # TBPL SUCCESS # 12:18:04 INFO - Running post-action listener: _resource_record_post_action 12:18:04 INFO - [mozharness: 2016-09-09 19:18:04.282265Z] Finished run-tests step (success) 12:18:04 INFO - Running post-run listener: _resource_record_post_run 12:18:04 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 324702208; Read time: 196; Write time: 236160 12:18:04 INFO - TinderboxPrint: CPU usage
12.9% 12:18:04 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 196 12:18:04 INFO - TinderboxPrint: I/O write bytes / time
324,702,208 / 236,160 12:18:04 INFO - TinderboxPrint: CPU idle
8,261.0 (87.0%) 12:18:04 INFO - TinderboxPrint: CPU user
1,184.7 (12.5%) 12:18:04 INFO - TinderboxPrint: Swap in / out
0 / 0 12:18:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79253504; Read time: 0; Write time: 42708 12:18:04 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 245448704; Read time: 196; Write time: 193452 12:18:04 INFO - Running post-run listener: _upload_blobber_files 12:18:04 INFO - Blob upload gear active. 12:18:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:04 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 12:18:04 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'] 12:18:04 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 12:18:05 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:18:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:18:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:06 INFO - (blobuploader) - INFO - Done attempting. 12:18:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:18:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:18:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:07 INFO - (blobuploader) - INFO - Done attempting. 12:18:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:18:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:10 INFO - (blobuploader) - INFO - Done attempting. 12:18:10 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:10 INFO - Return code: 0 12:18:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2753aa4a8e9c572446151dd9a03bd82afe60ab471143486eefcefde4860c353ea683718381fc44f01da40088dc31ce941a3d096aa5d823711f0a33ba437ddb0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3319a2001f16d1de098d6c844790f1b3d3064ba6d8ee196d544f96f575339f2708522e0d2be33e569c56cf54e770e9943d78e1ebc422161720959f444c21cc98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5503c11cad56a5201af7c77222299e1f031968c55c4f330f0be27b437430c267b0a04a6157e387159109beb29b390ed02b71517335e3813549ad4151460f4224"} 12:18:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:10 INFO - Contents: 12:18:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2753aa4a8e9c572446151dd9a03bd82afe60ab471143486eefcefde4860c353ea683718381fc44f01da40088dc31ce941a3d096aa5d823711f0a33ba437ddb0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3319a2001f16d1de098d6c844790f1b3d3064ba6d8ee196d544f96f575339f2708522e0d2be33e569c56cf54e770e9943d78e1ebc422161720959f444c21cc98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5503c11cad56a5201af7c77222299e1f031968c55c4f330f0be27b437430c267b0a04a6157e387159109beb29b390ed02b71517335e3813549ad4151460f4224"} 12:18:10 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:10 INFO - Copying logs to upload dir... 12:18:10 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.876891 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-09 12:18:10.782027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:10.789172) ========= 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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 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/2753aa4a8e9c572446151dd9a03bd82afe60ab471143486eefcefde4860c353ea683718381fc44f01da40088dc31ce941a3d096aa5d823711f0a33ba437ddb0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3319a2001f16d1de098d6c844790f1b3d3064ba6d8ee196d544f96f575339f2708522e0d2be33e569c56cf54e770e9943d78e1ebc422161720959f444c21cc98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5503c11cad56a5201af7c77222299e1f031968c55c4f330f0be27b437430c267b0a04a6157e387159109beb29b390ed02b71517335e3813549ad4151460f4224"} build_url:https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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/2753aa4a8e9c572446151dd9a03bd82afe60ab471143486eefcefde4860c353ea683718381fc44f01da40088dc31ce941a3d096aa5d823711f0a33ba437ddb0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3319a2001f16d1de098d6c844790f1b3d3064ba6d8ee196d544f96f575339f2708522e0d2be33e569c56cf54e770e9943d78e1ebc422161720959f444c21cc98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5503c11cad56a5201af7c77222299e1f031968c55c4f330f0be27b437430c267b0a04a6157e387159109beb29b390ed02b71517335e3813549ad4151460f4224"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:10.817648) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:10.818035) ========= 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=0333a256a5e4f052382dd180000001b1-1473446699.893663-1406920339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:10.867756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 12:18:10.868117) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 12:18:10.868440) ========= ========= Total master_lag: 0.33 =========