builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-030 starttime: 1471053996.93 results: success (0) buildid: 20160812154615 builduid: c21ebdbf17bf4120ae93ccef2bf2292d revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:36.930958) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:36.931469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:36.931813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:36.948050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:36.948406) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-12 19:06:37-- 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% 21.5M=0.001s 2016-08-12 19:06:37 (21.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.072312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.072685) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019924 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.123420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.123787) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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 a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-12 19:06:37,445 truncating revision to first 12 chars 2016-08-12 19:06:37,445 Setting DEBUG logging. 2016-08-12 19:06:37,445 attempt 1/10 2016-08-12 19:06:37,445 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a6fcbbeaddff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 19:06:37,780 unpacking tar archive at: mozilla-aurora-a6fcbbeaddff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.727153 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.865083) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.871478) ========= script_repo_revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.871947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.872276) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:06:37.885905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-12 19:06:37.886310) ========= /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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:06:38 INFO - MultiFileLogger online at 20160812 19:06:38 in /builds/slave/test 19:06:38 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 19:06:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:06:38 INFO - {'append_to_log': False, 19:06:38 INFO - 'base_work_dir': '/builds/slave/test', 19:06:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:06:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:06:38 INFO - 'branch': 'Mozilla-Aurora', 19:06:38 INFO - 'buildbot_json_path': 'buildprops.json', 19:06:38 INFO - 'config_files': ('talos/linux_config.py',), 19:06:38 INFO - 'default_actions': ('clobber', 19:06:38 INFO - 'read-buildbot-config', 19:06:38 INFO - 'download-and-extract', 19:06:38 INFO - 'populate-webroot', 19:06:38 INFO - 'create-virtualenv', 19:06:38 INFO - 'install', 19:06:38 INFO - 'run-tests'), 19:06:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:06:38 INFO - 'download_minidump_stackwalk': True, 19:06:38 INFO - 'download_symbols': 'ondemand', 19:06:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:06:38 INFO - 'tooltool.py': '/tools/tooltool.py', 19:06:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:06:38 INFO - '/tools/misc-python/virtualenv.py')}, 19:06:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:06:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:06:38 INFO - 'installer_path': 'installer.exe', 19:06:38 INFO - 'log_level': 'info', 19:06:38 INFO - 'log_name': 'talos', 19:06:38 INFO - 'log_to_console': True, 19:06:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:06:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:06:38 INFO - 'opt_config_files': (), 19:06:38 INFO - 'pip_index': False, 19:06:38 INFO - 'sps_profile': False, 19:06:38 INFO - 'sps_profile_interval': 0, 19:06:38 INFO - 'suite': 'dromaeojs', 19:06:38 INFO - 'system_bits': '32', 19:06:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:06:38 INFO - 'title': 'talos-linux64-ix-030', 19:06:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:06:38 INFO - 'use_talos_json': True, 19:06:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:06:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:06:38 INFO - 'work_dir': 'build'} 19:06:38 INFO - [mozharness: 2016-08-13 02:06:38.301285Z] Running clobber step. 19:06:38 INFO - Running pre-action listener: _resource_record_pre_action 19:06:38 INFO - Running main action method: clobber 19:06:38 INFO - rmtree: /builds/slave/test/build 19:06:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:06:38 INFO - Running post-action listener: _resource_record_post_action 19:06:38 INFO - [mozharness: 2016-08-13 02:06:38.757501Z] Finished clobber step (success) 19:06:38 INFO - [mozharness: 2016-08-13 02:06:38.757603Z] Running read-buildbot-config step. 19:06:38 INFO - Running pre-action listener: _resource_record_pre_action 19:06:38 INFO - Running main action method: read_buildbot_config 19:06:38 INFO - Using buildbot properties: 19:06:38 INFO - { 19:06:38 INFO - "project": "", 19:06:38 INFO - "product": "firefox", 19:06:38 INFO - "script_repo_revision": "production", 19:06:38 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:06:38 INFO - "repository": "", 19:06:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:06:38 INFO - "buildid": "20160812154615", 19:06:38 INFO - "pgo_build": "True", 19:06:38 INFO - "basedir": "/builds/slave/test", 19:06:38 INFO - "buildnumber": 416, 19:06:38 INFO - "slavename": "talos-linux64-ix-030", 19:06:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:06:38 INFO - "platform": "ubuntu64_hw", 19:06:38 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:06:38 INFO - "branch": "mozilla-aurora", 19:06:38 INFO - "repo_path": "releases/mozilla-aurora", 19:06:38 INFO - "revision": "a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5", 19:06:38 INFO - "stage_platform": "linux64-pgo", 19:06:38 INFO - "builduid": "c21ebdbf17bf4120ae93ccef2bf2292d", 19:06:38 INFO - "slavebuilddir": "test" 19:06:38 INFO - } 19:06:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 19:06:38 INFO - Running post-action listener: _resource_record_post_action 19:06:38 INFO - [mozharness: 2016-08-13 02:06:38.760038Z] Finished read-buildbot-config step (success) 19:06:38 INFO - [mozharness: 2016-08-13 02:06:38.760127Z] Running download-and-extract step. 19:06:38 INFO - Running pre-action listener: _resource_record_pre_action 19:06:38 INFO - Running main action method: download_and_extract 19:06:38 INFO - mkdir: /builds/slave/test/build/tests 19:06:38 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')]} 19:06:38 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:06:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:06:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:06:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:06:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:06:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:06:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:06:38 INFO - retry: Failed, sleeping 30 seconds before retrying 19:07:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:07:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:07:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:07:08 INFO - retry: Failed, sleeping 60 seconds before retrying 19:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:08:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:08:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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! 19:08:08 INFO - Caught exception: HTTP Error 404: Not Found 19:08:08 INFO - Caught exception: HTTP Error 404: Not Found 19:08:08 INFO - Caught exception: HTTP Error 404: Not Found 19:08:08 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:08:08 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:09 INFO - Downloaded 1361 bytes. 19:08:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:08:09 INFO - Using the following test package requirements: 19:08:09 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:08:09 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:08:09 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:08:09 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'jsshell-linux-x86_64.zip'], 19:08:09 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:08:09 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:08:09 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:08:09 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:08:09 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:08:09 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:08:09 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:08:09 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:09 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:08:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:08:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:08:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:08:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:08:09 INFO - retry: Failed, sleeping 30 seconds before retrying 19:08:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:08:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:08:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:08:39 INFO - retry: Failed, sleeping 60 seconds before retrying 19:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:09:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:09:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:09:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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! 19:09:39 INFO - Caught exception: HTTP Error 404: Not Found 19:09:39 INFO - Caught exception: HTTP Error 404: Not Found 19:09:39 INFO - Caught exception: HTTP Error 404: Not Found 19:09:39 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:09:39 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:09:40 INFO - Downloaded 21765597 bytes. 19:09:40 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'] 19:09:40 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 19:09:41 INFO - Return code: 0 19:09:41 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 19:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:41 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:09:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:09:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:09:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:09:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:09:41 INFO - retry: Failed, sleeping 30 seconds before retrying 19:10:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:10:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:10:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:10:11 INFO - retry: Failed, sleeping 60 seconds before retrying 19:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:11:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:11:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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! 19:11:11 INFO - Caught exception: HTTP Error 404: Not Found 19:11:11 INFO - Caught exception: HTTP Error 404: Not Found 19:11:11 INFO - Caught exception: HTTP Error 404: Not Found 19:11:11 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:11:11 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:12 INFO - Downloaded 21765597 bytes. 19:11:12 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'] 19:11:12 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 19:11:13 INFO - Return code: 0 19:11:13 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')]} 19:11:13 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:11:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:11:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:11:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:11:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:11:13 INFO - retry: Failed, sleeping 30 seconds before retrying 19:11:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:11:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:11:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:11:43 INFO - retry: Failed, sleeping 60 seconds before retrying 19:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:12:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:12:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:12:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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! 19:12:43 INFO - Caught exception: HTTP Error 404: Not Found 19:12:43 INFO - Caught exception: HTTP Error 404: Not Found 19:12:43 INFO - Caught exception: HTTP Error 404: Not Found 19:12:44 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:12:44 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:12:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:12:44 INFO - Downloaded 14413723 bytes. 19:12:44 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'] 19:12:44 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 19:12:44 INFO - Return code: 0 19:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:44 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:12:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:12:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:12:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:12:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:12:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:12:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:12:44 INFO - retry: Failed, sleeping 30 seconds before retrying 19:13:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:13:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:13:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:13:14 INFO - retry: Failed, sleeping 60 seconds before retrying 19:14:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:14:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:14:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:14:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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! 19:14:15 INFO - Caught exception: HTTP Error 404: Not Found 19:14:15 INFO - Caught exception: HTTP Error 404: Not Found 19:14:15 INFO - Caught exception: HTTP Error 404: Not Found 19:14:15 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:14:15 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:14:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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 19:14:16 INFO - Downloaded 63764023 bytes. 19:14:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:14:16 INFO - mkdir: /builds/slave/test/properties 19:14:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:14:16 INFO - Writing to file /builds/slave/test/properties/build_url 19:14:16 INFO - Contents: 19:14:16 INFO - build_url:https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:14:17 INFO - Running post-action listener: _resource_record_post_action 19:14:17 INFO - Running post-action listener: set_extra_try_arguments 19:14:17 INFO - [mozharness: 2016-08-13 02:14:17.512241Z] Finished download-and-extract step (success) 19:14:17 INFO - [mozharness: 2016-08-13 02:14:17.512474Z] Running populate-webroot step. 19:14:17 INFO - Running pre-action listener: _resource_record_pre_action 19:14:17 INFO - Running main action method: populate_webroot 19:14:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:14:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:14:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:14:17 INFO - '--tppagecycles', 19:14:17 INFO - '7'], 19:14:17 INFO - 'tests': ['tsvgm']}}, 19:14:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:14:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:14:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:14:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:14:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:14:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['damp', 'tps']}, 19:14:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'tests': ['damp', 'tps']}, 19:14:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['dromaeo_dom']}, 19:14:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:14:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['basic_compositor_video']}, 19:14:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:14:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'other-e10s': {'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['a11yr', 19:14:17 INFO - 'ts_paint', 19:14:17 INFO - 'tpaint', 19:14:17 INFO - 'sessionrestore', 19:14:17 INFO - 'sessionrestore_no_auto_restore', 19:14:17 INFO - 'tabpaint']}, 19:14:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['tsvgx', 19:14:17 INFO - 'tsvgr_opacity', 19:14:17 INFO - 'tart', 19:14:17 INFO - 'tscrollx', 19:14:17 INFO - 'cart']}, 19:14:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:14:17 INFO - 'tsvgr_opacity', 19:14:17 INFO - 'tart', 19:14:17 INFO - 'tscrollx', 19:14:17 INFO - 'cart']}, 19:14:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'talos_options': ['--disable-e10s'], 19:14:17 INFO - 'tests': ['tp5o']}, 19:14:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'tests': ['tp5o']}, 19:14:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'talos_options': ['--disable-e10s', 19:14:17 INFO - '--xperf_path', 19:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:17 INFO - 'tests': ['tp5n']}, 19:14:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:14:17 INFO - 'talos_options': ['--xperf_path', 19:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:14:17 INFO - 'tests': ['tp5n']}}, 19:14:17 INFO - 'talos.zip': {'path': '', 19:14:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:14:17 INFO - Running post-action listener: _resource_record_post_action 19:14:17 INFO - [mozharness: 2016-08-13 02:14:17.533953Z] Finished populate-webroot step (success) 19:14:17 INFO - [mozharness: 2016-08-13 02:14:17.534043Z] Running create-virtualenv step. 19:14:17 INFO - Running pre-action listener: _resource_record_pre_action 19:14:17 INFO - Running main action method: create_virtualenv 19:14:17 INFO - Creating virtualenv /builds/slave/test/build/venv 19:14:17 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 19:14:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:14:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:14:17 INFO - Using real prefix '/usr' 19:14:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:14:18 INFO - Installing distribute.............................................................................................................................................................................................done. 19:14:20 INFO - Installing pip.................done. 19:14:20 INFO - Return code: 0 19:14:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:14:20 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')]} 19:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:20 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')]} 19:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:20 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:20 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 19:14:20 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 19:14:20 INFO - Using env: {'DISPLAY': ':0', 19:14:20 INFO - 'HOME': '/home/cltbld', 19:14:20 INFO - 'LANG': 'en_US.UTF-8', 19:14:20 INFO - 'LANGUAGE': 'en_US:en', 19:14:20 INFO - 'LOGNAME': 'cltbld', 19:14:20 INFO - 'MAIL': '/var/mail/cltbld', 19:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:20 INFO - 'MOZ_NO_REMOTE': '1', 19:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:20 INFO - 'NO_EM_RESTART': '1', 19:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:20 INFO - 'PWD': '/builds/slave/test', 19:14:20 INFO - 'SHELL': '/bin/bash', 19:14:20 INFO - 'SHLVL': '1', 19:14:20 INFO - 'TERM': 'linux', 19:14:20 INFO - 'TMOUT': '86400', 19:14:20 INFO - 'USER': 'cltbld', 19:14:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:20 INFO - '_': '/tools/buildbot/bin/python'} 19:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:20 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 19:14:20 INFO - Cleaning up... 19:14:20 INFO - Return code: 0 19:14:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:14:20 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')]} 19:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:20 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')]} 19:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:20 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:20 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 19:14:20 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 19:14:20 INFO - Using env: {'DISPLAY': ':0', 19:14:20 INFO - 'HOME': '/home/cltbld', 19:14:20 INFO - 'LANG': 'en_US.UTF-8', 19:14:20 INFO - 'LANGUAGE': 'en_US:en', 19:14:20 INFO - 'LOGNAME': 'cltbld', 19:14:20 INFO - 'MAIL': '/var/mail/cltbld', 19:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:20 INFO - 'MOZ_NO_REMOTE': '1', 19:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:20 INFO - 'NO_EM_RESTART': '1', 19:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:20 INFO - 'PWD': '/builds/slave/test', 19:14:20 INFO - 'SHELL': '/bin/bash', 19:14:20 INFO - 'SHLVL': '1', 19:14:20 INFO - 'TERM': 'linux', 19:14:20 INFO - 'TMOUT': '86400', 19:14:20 INFO - 'USER': 'cltbld', 19:14:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:20 INFO - '_': '/tools/buildbot/bin/python'} 19:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:20 INFO - Downloading/unpacking psutil>=3.1.1 19:14:20 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 19:14:20 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 19:14:20 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 19:14:20 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 19:14:20 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 19:14:20 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 19:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:14:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:22 INFO - Installing collected packages: psutil 19:14:22 INFO - Running setup.py install for psutil 19:14:22 INFO - building 'psutil._psutil_linux' extension 19:14:22 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 19:14:22 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 19:14:22 INFO - building 'psutil._psutil_posix' extension 19:14:22 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 19:14:22 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 19:14:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:23 INFO - Successfully installed psutil 19:14:23 INFO - Cleaning up... 19:14:23 INFO - Return code: 0 19:14:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:23 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:23 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 19:14:23 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 19:14:23 INFO - Using env: {'DISPLAY': ':0', 19:14:23 INFO - 'HOME': '/home/cltbld', 19:14:23 INFO - 'LANG': 'en_US.UTF-8', 19:14:23 INFO - 'LANGUAGE': 'en_US:en', 19:14:23 INFO - 'LOGNAME': 'cltbld', 19:14:23 INFO - 'MAIL': '/var/mail/cltbld', 19:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:23 INFO - 'MOZ_NO_REMOTE': '1', 19:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:23 INFO - 'NO_EM_RESTART': '1', 19:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:23 INFO - 'PWD': '/builds/slave/test', 19:14:23 INFO - 'SHELL': '/bin/bash', 19:14:23 INFO - 'SHLVL': '1', 19:14:23 INFO - 'TERM': 'linux', 19:14:23 INFO - 'TMOUT': '86400', 19:14:23 INFO - 'USER': 'cltbld', 19:14:23 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:23 INFO - '_': '/tools/buildbot/bin/python'} 19:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:14:23 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 19:14:23 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 19:14:23 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 19:14:23 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 19:14:23 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 19:14:23 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 19:14:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:14:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:14:25 INFO - Installing collected packages: mozsystemmonitor 19:14:25 INFO - Running setup.py install for mozsystemmonitor 19:14:25 INFO - Successfully installed mozsystemmonitor 19:14:25 INFO - Cleaning up... 19:14:25 INFO - Return code: 0 19:14:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:14:25 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')]} 19:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:25 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')]} 19:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:25 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:25 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 19:14:25 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 19:14:25 INFO - Using env: {'DISPLAY': ':0', 19:14:25 INFO - 'HOME': '/home/cltbld', 19:14:25 INFO - 'LANG': 'en_US.UTF-8', 19:14:25 INFO - 'LANGUAGE': 'en_US:en', 19:14:25 INFO - 'LOGNAME': 'cltbld', 19:14:25 INFO - 'MAIL': '/var/mail/cltbld', 19:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:25 INFO - 'MOZ_NO_REMOTE': '1', 19:14:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:25 INFO - 'NO_EM_RESTART': '1', 19:14:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:25 INFO - 'PWD': '/builds/slave/test', 19:14:25 INFO - 'SHELL': '/bin/bash', 19:14:25 INFO - 'SHLVL': '1', 19:14:25 INFO - 'TERM': 'linux', 19:14:25 INFO - 'TMOUT': '86400', 19:14:25 INFO - 'USER': 'cltbld', 19:14:25 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:25 INFO - '_': '/tools/buildbot/bin/python'} 19:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:25 INFO - Downloading/unpacking jsonschema==2.5.1 19:14:25 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 19:14:25 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 19:14:25 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 19:14:25 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 19:14:25 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 19:14:25 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 19:14:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:14:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:14:27 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 19:14:27 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 19:14:27 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 19:14:27 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 19:14:27 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 19:14:27 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 19:14:27 INFO - Downloading functools32-3.2.3-2.tar.gz 19:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:14:27 INFO - warning: no files found matching '*.txt' 19:14:27 INFO - no previously-included directories found matching 'build' 19:14:27 INFO - no previously-included directories found matching 'dist' 19:14:27 INFO - no previously-included directories found matching '.git*' 19:14:27 INFO - Installing collected packages: jsonschema, functools32 19:14:27 INFO - Running setup.py install for functools32 19:14:27 INFO - warning: no files found matching '*.txt' 19:14:27 INFO - no previously-included directories found matching 'build' 19:14:27 INFO - no previously-included directories found matching 'dist' 19:14:27 INFO - no previously-included directories found matching '.git*' 19:14:27 INFO - Successfully installed jsonschema functools32 19:14:27 INFO - Cleaning up... 19:14:27 INFO - Return code: 0 19:14:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:27 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:27 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 19:14:27 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 19:14:27 INFO - Using env: {'DISPLAY': ':0', 19:14:27 INFO - 'HOME': '/home/cltbld', 19:14:27 INFO - 'LANG': 'en_US.UTF-8', 19:14:27 INFO - 'LANGUAGE': 'en_US:en', 19:14:27 INFO - 'LOGNAME': 'cltbld', 19:14:27 INFO - 'MAIL': '/var/mail/cltbld', 19:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:27 INFO - 'MOZ_NO_REMOTE': '1', 19:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:27 INFO - 'NO_EM_RESTART': '1', 19:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:27 INFO - 'PWD': '/builds/slave/test', 19:14:27 INFO - 'SHELL': '/bin/bash', 19:14:27 INFO - 'SHLVL': '1', 19:14:27 INFO - 'TERM': 'linux', 19:14:27 INFO - 'TMOUT': '86400', 19:14:27 INFO - 'USER': 'cltbld', 19:14:27 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:27 INFO - '_': '/tools/buildbot/bin/python'} 19:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:14:28 INFO - Cleaning up... 19:14:28 INFO - Return code: 0 19:14:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:28 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:28 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 19:14:28 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 19:14:28 INFO - Using env: {'DISPLAY': ':0', 19:14:28 INFO - 'HOME': '/home/cltbld', 19:14:28 INFO - 'LANG': 'en_US.UTF-8', 19:14:28 INFO - 'LANGUAGE': 'en_US:en', 19:14:28 INFO - 'LOGNAME': 'cltbld', 19:14:28 INFO - 'MAIL': '/var/mail/cltbld', 19:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:28 INFO - 'MOZ_NO_REMOTE': '1', 19:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:28 INFO - 'NO_EM_RESTART': '1', 19:14:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:28 INFO - 'PWD': '/builds/slave/test', 19:14:28 INFO - 'SHELL': '/bin/bash', 19:14:28 INFO - 'SHLVL': '1', 19:14:28 INFO - 'TERM': 'linux', 19:14:28 INFO - 'TMOUT': '86400', 19:14:28 INFO - 'USER': 'cltbld', 19:14:28 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:28 INFO - '_': '/tools/buildbot/bin/python'} 19:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:28 INFO - Downloading/unpacking blobuploader==1.2.4 19:14:28 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 19:14:28 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 19:14:28 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 19:14:28 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 19:14:28 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 19:14:28 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 19:14:30 INFO - Downloading blobuploader-1.2.4.tar.gz 19:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:14:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:14:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 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 19:14:30 INFO - Downloading docopt-0.6.1.tar.gz 19:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:14:30 INFO - Installing collected packages: blobuploader, requests, docopt 19:14:30 INFO - Running setup.py install for blobuploader 19:14:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:14:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:14:31 INFO - Running setup.py install for requests 19:14:31 INFO - Running setup.py install for docopt 19:14:31 INFO - Successfully installed blobuploader requests docopt 19:14:31 INFO - Cleaning up... 19:14:31 INFO - Return code: 0 19:14:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:31 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:31 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 19:14:31 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 19:14:31 INFO - Using env: {'DISPLAY': ':0', 19:14:31 INFO - 'HOME': '/home/cltbld', 19:14:31 INFO - 'LANG': 'en_US.UTF-8', 19:14:31 INFO - 'LANGUAGE': 'en_US:en', 19:14:31 INFO - 'LOGNAME': 'cltbld', 19:14:31 INFO - 'MAIL': '/var/mail/cltbld', 19:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:31 INFO - 'MOZ_NO_REMOTE': '1', 19:14:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:31 INFO - 'NO_EM_RESTART': '1', 19:14:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:31 INFO - 'PWD': '/builds/slave/test', 19:14:31 INFO - 'SHELL': '/bin/bash', 19:14:31 INFO - 'SHLVL': '1', 19:14:31 INFO - 'TERM': 'linux', 19:14:31 INFO - 'TMOUT': '86400', 19:14:31 INFO - 'USER': 'cltbld', 19:14:31 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:31 INFO - '_': '/tools/buildbot/bin/python'} 19:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:31 INFO - Running setup.py (path:/tmp/pip-MUR8Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:31 INFO - Running setup.py (path:/tmp/pip-E9TNMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:31 INFO - Running setup.py (path:/tmp/pip-D14Vc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:32 INFO - Running setup.py (path:/tmp/pip-7b9FIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:32 INFO - Running setup.py (path:/tmp/pip-cYzztk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:32 INFO - Running setup.py (path:/tmp/pip-BVsSmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:32 INFO - Running setup.py (path:/tmp/pip-qwPV3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:32 INFO - Running setup.py (path:/tmp/pip-d6Uw_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:32 INFO - Running setup.py (path:/tmp/pip-1aWHPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:32 INFO - Running setup.py (path:/tmp/pip-jnH1FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:32 INFO - Running setup.py (path:/tmp/pip-bpV_EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:32 INFO - Running setup.py (path:/tmp/pip-atrlFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:32 INFO - Running setup.py (path:/tmp/pip-3W2J1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:32 INFO - Running setup.py (path:/tmp/pip-2oZQZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:32 INFO - Running setup.py (path:/tmp/pip-OWwTXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:32 INFO - Running setup.py (path:/tmp/pip-2o9ype-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:32 INFO - Running setup.py (path:/tmp/pip-9k1Whp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:14:33 INFO - Running setup.py install for manifestparser 19:14:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozcrash 19:14:33 INFO - Running setup.py install for mozdebug 19:14:33 INFO - Running setup.py install for mozdevice 19:14:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozfile 19:14:33 INFO - Running setup.py install for mozhttpd 19:14:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozinfo 19:14:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozInstall 19:14:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:14:33 INFO - Running setup.py install for mozleak 19:14:33 INFO - Running setup.py install for mozlog 19:14:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Running setup.py install for moznetwork 19:14:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Running setup.py install for mozprocess 19:14:34 INFO - Running setup.py install for mozprofile 19:14:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Running setup.py install for mozrunner 19:14:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Running setup.py install for mozscreenshot 19:14:34 INFO - Running setup.py install for moztest 19:14:34 INFO - Running setup.py install for mozversion 19:14:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:14:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:14:34 INFO - Cleaning up... 19:14:34 INFO - Return code: 0 19:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:34 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:34 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 19:14:34 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 19:14:34 INFO - Using env: {'DISPLAY': ':0', 19:14:34 INFO - 'HOME': '/home/cltbld', 19:14:34 INFO - 'LANG': 'en_US.UTF-8', 19:14:34 INFO - 'LANGUAGE': 'en_US:en', 19:14:34 INFO - 'LOGNAME': 'cltbld', 19:14:34 INFO - 'MAIL': '/var/mail/cltbld', 19:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:34 INFO - 'MOZ_NO_REMOTE': '1', 19:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:34 INFO - 'NO_EM_RESTART': '1', 19:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:34 INFO - 'PWD': '/builds/slave/test', 19:14:34 INFO - 'SHELL': '/bin/bash', 19:14:34 INFO - 'SHLVL': '1', 19:14:34 INFO - 'TERM': 'linux', 19:14:34 INFO - 'TMOUT': '86400', 19:14:34 INFO - 'USER': 'cltbld', 19:14:34 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:34 INFO - '_': '/tools/buildbot/bin/python'} 19:14:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:35 INFO - Running setup.py (path:/tmp/pip-qvSL8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:35 INFO - Running setup.py (path:/tmp/pip-RkTwam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:35 INFO - Running setup.py (path:/tmp/pip-ajN7xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:35 INFO - Running setup.py (path:/tmp/pip-29Wioa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:35 INFO - Running setup.py (path:/tmp/pip-ufubBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:35 INFO - Running setup.py (path:/tmp/pip-vNTYUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:35 INFO - Running setup.py (path:/tmp/pip-QJhfj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:35 INFO - Running setup.py (path:/tmp/pip-LvanGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:35 INFO - Running setup.py (path:/tmp/pip-X7zFK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:35 INFO - Running setup.py (path:/tmp/pip-YZywsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:35 INFO - Running setup.py (path:/tmp/pip-WQCMv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:35 INFO - Running setup.py (path:/tmp/pip-NAK6v8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:35 INFO - Running setup.py (path:/tmp/pip-f3iLtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:35 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)) 19:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:35 INFO - Running setup.py (path:/tmp/pip-hXYKJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:36 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)) 19:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:36 INFO - Running setup.py (path:/tmp/pip-YlUvmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:36 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)) 19:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:36 INFO - Running setup.py (path:/tmp/pip-7HW2tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:36 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)) 19:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:36 INFO - Running setup.py (path:/tmp/pip-x3xOFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:36 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)) 19:14:36 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)) 19:14:36 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)) 19:14:36 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)) 19:14:36 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)) 19:14:36 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)) 19:14:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:14:36 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 19:14:36 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 19:14:36 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 19:14:36 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 19:14:36 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 19:14:36 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 19:14:38 INFO - Downloading blessings-1.6.tar.gz 19:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:14:38 INFO - Installing collected packages: blessings 19:14:38 INFO - Running setup.py install for blessings 19:14:38 INFO - Successfully installed blessings 19:14:38 INFO - Cleaning up... 19:14:38 INFO - Return code: 0 19:14:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:38 INFO - Reading from file tmpfile_stdout 19:14:38 INFO - Current package versions: 19:14:38 INFO - argparse == 1.2.1 19:14:38 INFO - blessings == 1.6 19:14:38 INFO - blobuploader == 1.2.4 19:14:38 INFO - docopt == 0.6.1 19:14:38 INFO - functools32 == 3.2.3-2 19:14:38 INFO - jsonschema == 2.5.1 19:14:38 INFO - manifestparser == 1.1 19:14:38 INFO - mozInstall == 1.12 19:14:38 INFO - mozcrash == 0.17 19:14:38 INFO - mozdebug == 0.1 19:14:38 INFO - mozdevice == 0.48 19:14:38 INFO - mozfile == 1.2 19:14:38 INFO - mozhttpd == 0.7 19:14:38 INFO - mozinfo == 0.9 19:14:38 INFO - mozleak == 0.1 19:14:38 INFO - mozlog == 3.3 19:14:38 INFO - moznetwork == 0.27 19:14:38 INFO - mozprocess == 0.23 19:14:38 INFO - mozprofile == 0.28 19:14:38 INFO - mozrunner == 6.12 19:14:38 INFO - mozscreenshot == 0.1 19:14:38 INFO - mozsystemmonitor == 0.3 19:14:38 INFO - moztest == 0.7 19:14:38 INFO - mozversion == 1.4 19:14:38 INFO - psutil == 3.1.1 19:14:38 INFO - requests == 1.2.3 19:14:38 INFO - wsgiref == 0.1.2 19:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:38 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')]} 19:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:38 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')]} 19:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:38 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:38 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 19:14:38 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 19:14:38 INFO - Using env: {'DISPLAY': ':0', 19:14:38 INFO - 'HOME': '/home/cltbld', 19:14:38 INFO - 'LANG': 'en_US.UTF-8', 19:14:38 INFO - 'LANGUAGE': 'en_US:en', 19:14:38 INFO - 'LOGNAME': 'cltbld', 19:14:38 INFO - 'MAIL': '/var/mail/cltbld', 19:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:38 INFO - 'MOZ_NO_REMOTE': '1', 19:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:38 INFO - 'NO_EM_RESTART': '1', 19:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:38 INFO - 'PWD': '/builds/slave/test', 19:14:38 INFO - 'SHELL': '/bin/bash', 19:14:38 INFO - 'SHLVL': '1', 19:14:38 INFO - 'TERM': 'linux', 19:14:38 INFO - 'TMOUT': '86400', 19:14:38 INFO - 'USER': 'cltbld', 19:14:38 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:38 INFO - '_': '/tools/buildbot/bin/python'} 19:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 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)) 19:14:38 INFO - Cleaning up... 19:14:38 INFO - Return code: 0 19:14:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:14:38 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')]} 19:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:38 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')]} 19:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:38 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 0x1aec030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c4f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, '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': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', '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 19:14:38 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 19:14:38 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 19:14:38 INFO - Using env: {'DISPLAY': ':0', 19:14:38 INFO - 'HOME': '/home/cltbld', 19:14:38 INFO - 'LANG': 'en_US.UTF-8', 19:14:38 INFO - 'LANGUAGE': 'en_US:en', 19:14:38 INFO - 'LOGNAME': 'cltbld', 19:14:38 INFO - 'MAIL': '/var/mail/cltbld', 19:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:38 INFO - 'MOZ_NO_REMOTE': '1', 19:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:38 INFO - 'NO_EM_RESTART': '1', 19:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:38 INFO - 'PWD': '/builds/slave/test', 19:14:38 INFO - 'SHELL': '/bin/bash', 19:14:38 INFO - 'SHLVL': '1', 19:14:38 INFO - 'TERM': 'linux', 19:14:38 INFO - 'TMOUT': '86400', 19:14:38 INFO - 'USER': 'cltbld', 19:14:38 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:38 INFO - '_': '/tools/buildbot/bin/python'} 19:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:14:39 INFO - Cleaning up... 19:14:39 INFO - Return code: 0 19:14:39 INFO - Running post-action listener: _resource_record_post_action 19:14:39 INFO - Running post-action listener: _start_resource_monitoring 19:14:39 INFO - Starting resource monitoring. 19:14:39 INFO - [mozharness: 2016-08-13 02:14:39.069750Z] Finished create-virtualenv step (success) 19:14:39 INFO - [mozharness: 2016-08-13 02:14:39.070233Z] Running install step. 19:14:39 INFO - Running pre-action listener: _resource_record_pre_action 19:14:39 INFO - Running main action method: install 19:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:39 INFO - Reading from file tmpfile_stdout 19:14:39 INFO - Detecting whether we're running mozinstall >=1.0... 19:14:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:14:39 INFO - Reading from file tmpfile_stdout 19:14:39 INFO - Output received: 19:14:39 INFO - Usage: mozinstall [options] installer 19:14:39 INFO - Options: 19:14:39 INFO - -h, --help show this help message and exit 19:14:39 INFO - -d DEST, --destination=DEST 19:14:39 INFO - Directory to install application into. [default: 19:14:39 INFO - "/builds/slave/test"] 19:14:39 INFO - --app=APP Application being installed. [default: firefox] 19:14:39 INFO - mkdir: /builds/slave/test/build/application 19:14:39 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'] 19:14:39 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 19:14:55 INFO - Reading from file tmpfile_stdout 19:14:55 INFO - Output received: 19:14:55 INFO - /builds/slave/test/build/application/firefox/firefox 19:14:55 INFO - Running post-action listener: _resource_record_post_action 19:14:55 INFO - [mozharness: 2016-08-13 02:14:55.975868Z] Finished install step (success) 19:14:55 INFO - [mozharness: 2016-08-13 02:14:55.976145Z] Running run-tests step. 19:14:55 INFO - Running pre-action listener: _resource_record_pre_action 19:14:55 INFO - Running main action method: run_tests 19:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:14:55 INFO - Python 2.7.3 19:14:55 INFO - Return code: 0 19:14:55 INFO - grabbing minidump binary from tooltool 19:14: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')]} 19:14:55 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 0x1dab3b0>, '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 0x1dac2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dac740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:14:55 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 19:14:55 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 19:14:55 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 19:14:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:14:56 INFO - Return code: 0 19:14:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:14:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:14:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:14:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:14:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:14:56 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/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 19:14:56 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/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 19:14:56 INFO - Using env: {'DISPLAY': ':0', 19:14:56 INFO - 'HOME': '/home/cltbld', 19:14:56 INFO - 'LANG': 'en_US.UTF-8', 19:14:56 INFO - 'LANGUAGE': 'en_US:en', 19:14:56 INFO - 'LOGNAME': 'cltbld', 19:14:56 INFO - 'MAIL': '/var/mail/cltbld', 19:14:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:14:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:56 INFO - 'MOZ_NO_REMOTE': '1', 19:14:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:14:56 INFO - 'NO_EM_RESTART': '1', 19:14:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:56 INFO - 'PWD': '/builds/slave/test', 19:14:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:14:56 INFO - 'SHELL': '/bin/bash', 19:14:56 INFO - 'SHLVL': '1', 19:14:56 INFO - 'TERM': 'linux', 19:14:56 INFO - 'TMOUT': '86400', 19:14:56 INFO - 'USER': 'cltbld', 19:14:56 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171', 19:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:56 INFO - '_': '/tools/buildbot/bin/python'} 19:14:56 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/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 19:14:56 INFO - mozversion application_buildid: 20160812154615 19:14:56 INFO - mozversion application_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 19:14:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:14:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:14:56 INFO - mozversion application_name: Firefox 19:14:56 INFO - mozversion application_remotingname: firefox-dev 19:14:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:14:56 INFO - mozversion application_vendor: Mozilla 19:14:56 INFO - mozversion application_version: 50.0a2 19:14:56 INFO - mozversion platform_buildid: 20160812154615 19:14:56 INFO - mozversion platform_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 19:14:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:14:56 INFO - mozversion platform_version: 50.0a2 19:14:56 INFO - using testdate: 1471054496 19:14:56 INFO - actual date: 1471054496 19:14:56 INFO - starting webserver on 'localhost:42476' 19:14:56 INFO - SUITE-START | Running 2 tests 19:14:56 INFO - TEST-START | dromaeo_css 19:14:56 INFO - operating with platform_type : linux_ 19:14:56 INFO - Initialising browser for dromaeo_css test... 19:14:56 INFO - TEST-INFO | started process 11114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphtkhzE/profile http://localhost:42476/getInfo.html) 19:14:59 INFO - PROCESS | 11114 | __metrics Screen width/height:1600/1200 19:14:59 INFO - PROCESS | 11114 | colorDepth:24 19:14:59 INFO - PROCESS | 11114 | Browser inner width/height: 1024/697 19:14:59 INFO - PROCESS | 11114 | __metrics 19:15:00 INFO - PROCESS | 11114 | 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" 19:15:00 INFO - PROCESS | 11114 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:01 INFO - TEST-INFO | 11114: exit 0 19:15:01 INFO - Browser initialized. 19:15:01 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:01 INFO - TEST-INFO | started process 11203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphtkhzE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:13 INFO - PROCESS | 11203 | RSS: Main: 145330176 19:15:13 INFO - PROCESS | 11203 | 19:16:05 INFO - PROCESS | 11203 | 1471054564994 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] 19:18:12 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42476/tests/dromaeo/cssquery-ext.html) 19:18:13 INFO - PROCESS | 11203 | RSS: Main: 275296256 19:18:13 INFO - PROCESS | 11203 | 19:21:16 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-ext.html (next: http://localhost:42476/tests/dromaeo/cssquery-jquery.html) 19:21:16 INFO - PROCESS | 11203 | RSS: Main: 234651648 19:21:16 INFO - PROCESS | 11203 | 19:24:18 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42476/tests/dromaeo/cssquery-mootools.html) 19:24:19 INFO - PROCESS | 11203 | RSS: Main: 173359104 19:24:19 INFO - PROCESS | 11203 | 19:27:21 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42476/tests/dromaeo/cssquery-prototype.html) 19:27:22 INFO - PROCESS | 11203 | RSS: Main: 175001600 19:27:22 INFO - PROCESS | 11203 | 19:30:24 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42476/tests/dromaeo/cssquery-yui.html) 19:30:25 INFO - PROCESS | 11203 | RSS: Main: 206082048 19:30:25 INFO - PROCESS | 11203 | 19:33:27 INFO - PROCESS | 11203 | Cycle 1(1): loaded http://localhost:42476/tests/dromaeo/cssquery-yui.html (next: http://localhost:42476/tests/dromaeo/cssquery-dojo.html) 19:33:27 INFO - PROCESS | 11203 | RSS: Main: 265396224 19:33:27 INFO - PROCESS | 11203 | 19:33:27 INFO - PROCESS | 11203 | __start_tp_report 19:33:27 INFO - PROCESS | 11203 | _x_x_mozilla_page_load 19:33:27 INFO - PROCESS | 11203 | _x_x_mozilla_page_load_details 19:33:27 INFO - PROCESS | 11203 | |i|pagename|runs| 19:33:27 INFO - PROCESS | 11203 | |0;dojo.html;3196.6643502432244;3255.9999999999986;3268.395346860831;3275.91810204745;3304.041827869918;5589.74846131924;5670.4896559309655;5673.177389278552;5700.030994730895;5711.114777209534;5610.523105536025;5650.29371328584;5652.406497317784;5653.6607803531815;5679.886402271952;5041.092603331399;5069.417017043042;5100.438951715309;5106.846794596168;5158.277841102249;5098.745062746866;5120.795168193268;5133;5147.974260128694;5173.3016042834115;5076.111680455924;5118.488151184889;5120;5136.306598609177;5138.434772175057;5168.3281173447585;5181.792728290901;5184.662996905208;5184.896302073975;5218.608604354688;4884;5051.4695956924725;5069.340985671876;5080.1617733074;5084.186530155157;5593.412691667357;5639.887202255973;5647.689377084259;5677.858053548694;5683.5737319700775;6071.51427885768;6075.453209211192;6082.8746681863995;6084.847878803065;6117.868194384008;5924.259467566554;6023.668698221596;6031.185789918349;6084.239470066242;6101.572889897709;5751.654900705971;5752.108423194411;5760.36635970043;5761;5764.394738552475;6226.5641405101205;6263.279722831932;6278.591891527036;6281.340459251713;6283.277423096311;5737.655740655574;5866.413358664185;5882.000059989812;5896.351401345849;6041.093835924647;5967.8209653710455;6041.365656605993;6050.485708714808;6053.061775424728;6057.697115144313;5989.101634754822;5995.2206213192;5995.910061348996;6000;6000.459958603747;5918.496927761188;5976.581639285208;5982.850428739316;6057.42454466825;6092.329843717188;4597.471290801601;4599.494055653875;4600.723956562617;4631.536846315342;4638.373819534387;4117.794110294533;4119.526254480713;4119.6910231732145;4138.668906487534;4151.647109995623;5559.861003474864;5574.888502229933;5626.1842032905;5699.202111704365;5771.451712087345;5978.581499295007;5984.970075149597;5985.25184351956;6014.218151640259;6021.2774467063355;5626.915596266059;5660.858478537987;5667.773289068474;5674.971625141848;5680.857978550486;5081.2378143278065;5149.6910185389;5166.276721259027;5187.507186817246;5234.633575649744;6232.532560057924;6254.437100660963;6270.968645156745;6274.247090349188;6275.780347687901;6077.453029227391;6081.726322315418;6087.239095113111;6089.665068421102;6092.939070609237;6116.541259405473;6118.619179173164;6133.53998450127;6284.4972402206795;6307.873842523032;5907.497862681548;5912.793052243151;5914.970425147847;5946.226990491208;5957.702114894151;5958.582899197015;5998.370171131968;6018.307894592002;6018.849528761816;6022.45797878193;5735.168400581809;5879.7060146993335;5884.558658100574;5890.263717035371;5904.704764761659;5949.464548190684;5953.642781433128;5960;5981.7607295707685;5995.280566331893;5911.55663325261;5925.703714814328;5928.140419639214;5941.287045554388;5945.64326140433;5848.795292164754;5945.524358051432;5945.554083443845;5951.910721339097;6052.243469566304;5822.563307752021;5866.090755932837;5873.177755114373;5888.234529511136;5908.615939963888 19:33:27 INFO - PROCESS | 11203 | |1;ext.html;598.9041753319469;625.4902254662447;626.2391194698442;643.1125047434541;654.744649586661;34612.134696632595;45389.319160212624;46431.5356846431;46507.53492465075;46529.53470465294;46759.53240467603;47189.29216061762;47201;47244;47247;8411.537365444898;9152.771180720485;9277.211437027861;9306.976232614406;9314.394564353295;8328.33373330132;8618.224359807647;8621.698240561582;10804.513796879159;11114.333140011584;11269.830952535718;11340.319580825177;11341.603043893465;11342.716432089223;11355.432228388589;11187.32876027437;11251.887481125166;11253.774924501464;11254.212205145643;11261.380624065672;10993.780124397466;11071.944640276828;11079.833802492969;11089.168312376523;11096.944515277371;44507.77746111281;44895.10209795819;44944;45012.3248151278;45082;3756.1924186299802;3766.856234633836;3804.7907365094907;3855.1133239354826;3878.941815872764;34591.30817383663;34830.82584587086;34919.30161396783;35176;36862.262754744756;4440.511543730187;4513.593776560126;4514.345419914094;4591.517890621503;4669.517263427114;371139;373980;374367;374457;375274;37809.62190378116;60911;61239.38760612337;61293;61333.08000379998;38950.220995579926;40447.79776101159;40470.79764601158;40545.797271014046;40563.39154912678;17225.56936076608;17443.389481368093;17459.563510912074;17462.65074698499;17463.38878139259;14462.85537144615;14484.130952142905;14583.270836458134;14590;14598;8641.481511109228;8881.33389995753;9073.682421115229;9152.81694366109;9296.070392960784;3534.540509733718;3545.2023294758474;3552.3605750964557;3564.6435356464663;3591.76084250933;1079.8002369561657;1090.1387903556101;1110.3837370259423;1128.6049882541045;1178.6228406909706;34928.65071349254;35028.299434011176;35029.29941401158;35034;35140.64859351374;1120.288616728367;1157.7105723569107;1197.868234494205;1223.5350566784564;1225.597828256627;16409.343626255053;17197.484075477754;17284.395046173577;17313;17337.133143342784;532107;535414;536332;536367;536888;60951;61414.0787888182;61434.69282653648;61461.69269153626;61477.38522614806;1370.1915869636962;1465.992670036643;1468.7576549869366;1499.4077339451073;1525.8016457860629;33231;34950.82524587463;35273.47089793604;35449.291014179056;35467.645323546436;52811.471885280655;53402.73298633482;53419.19871201857;53561.464385357205;53598.46401535935;454079;457002;457581;458890;459281;26932.326691832077;27576.448471030868;27860.721392785814;27900;32736.363181840337;50990.74504627453;51422.742886286826;51711.48288517217;51815.2227716577;53044.46955530396;44140.33789493224;44915;45147.83474365356;45726.31410528778;46923.530764693285;43625.78187109044;44778.55221447744;45161.77419112883;45599.54400455953;45815.31277030914;40668;41413.792931035154;41570;43293.78353108215;44911;34644.653553464144;38155.80922095372;38310;38496.80751596224;39161;28915.7108428913;28957.710422895503;29182.27044323909;29207.415851683272;29264.853675731487 19:33:28 INFO - PROCESS | 11203 | |2;jquery.html;399.6003996003996;400.4573802497614;427.07751257284264;433.8243011580309;435.4295872407147;15286.541403757878;20012.799872001277;20165.29421470247;20861.582768344622;21122.577548449022;20463.18147274107;20497.692534611993;20848.791512084874;20862.68705969408;20996.8950155249;5678.4605462480995;5833.300003999505;5923.614965027281;5981.371955944628;5982.670953097578;4448.332750087497;4683.8360657377;5890.852728681774;5940.940590594104;5946.464818166364;4320.308750599905;6408.93591064088;6670.833229169261;6675.365840245169;6678.232003319632;6667.3999340059145;6674.098996635489;6722.361375669304;6726.596404215738;6736.966315168417;5967.522598192134;5977.970110149421;5979.581429299951;5983.820485385445;5986.401359864022;23836.523269534686;24285.635715464283;24509.01963921441;24623;24692.012319507197;2572.909948151824;2577.05937332874;2826.618406515135;2851.2159156231996;3015.1406849048135;14987.250637468193;15032.398704051824;15556.766648500281;15758.054516728918;15823.920880395639;2050.4976280811297;2071.3682326890294;2071.5753270579494;2127.4149608857533;2292.667563203343;75378;77378.61310693467;79796;87522;89521.99999999935;10809.35143891369;10864;11117.11063114965;11180.273282236629;11197.216194866443;15193.012454190663;15207.239638018054;15219.543413697607;15230.390784368723;15253.313600887986;8262.628181731836;8464.661413543512;8806.987196472359;8918.955405222932;9142.588583513758;8902.86645700315;8924.8661270081;8942.930966410398;8958.955205223932;9179.127982841619;4659.998100408361;4705;4724.952750472451;4881.389826271716;5082.110630639697;2098.5802839432176;2131.8294536437047;2131.946701332448;2142.389419015573;2144.324537770597;934.6354921580589;985.5959056786683;1017.2980643356061;1064.3241541621126;1065.8134826405349;19896.204151834056;20550.794492054887;20577.897110514652;20854.791452085286;21096.472588185417;1012.8835183953793;1020.0717347214148;1036.1917704190744;1054.0513537815873;1093.2183488805542;9515.143637072697;11374.715632109264;11418.885811141949;11512.654620361234;11560.6531804046;183538;185266;431944;514980;521645;67809.66095169492;94328;95275.52362238283;95987;96589.51705241429;1021.1065317847033;1028.1466382902174;1045.320541647935;1048.556862510349;1052.8683914510686;17640.647187056442;17937.192826322585;18090.366837160636;18176.727349090037;18489.72265416047;33104.66895331016;42430;42526.574734253496;43513.564864352214;44324.556754432044;61601;61713.38286617256;64006;64323;64636;8445.493270403795;8605.35459840502;8615.396922215385;8673.653053877773;8851.734447966572;8857.955710221408;9098.044705305847;9189.375440068185;9651.662191823252;10055.29612927088;8696.956515217384;8766.167214114648;8801.647934082564;9018.053104423941;9273.86089208649;8641.783955400913;8789.472631642122;8879.733607991771;8984.505852177932;8992.640294388151;8823.867641985504;8963.641454341754;9150.725478235663;9233.322336522602;9241.584128714358;12525.248485090558;12737.426815793497;12764.872351276368;12765.72342765716;13223.933880330536;12654.557090501788;12937.353132343156;12964.935175324063;12972.870271297545;12998.350082496027 19:33:28 INFO - PROCESS | 11203 | |3;mootools.html;1278.1500302298969;1437.5543581489737;1477.7340078785815;1880.4452686457494;1909.6180763847235;9180.449173049614;9931;9932.503374831256;9936.056074672904;9958.402495850254;8550.74347769568;9175.357724959238;9422.481763503021;9437.716868493939;9674.758131046727;2391.246757271458;2418.3349578865864;2418.600930846408;2427.7208121066037;2430.4895971845935;2437.19572541061;2462.150558057467;2495.0144692846316;2498.487809998955;2505.5991041433376;2104.442322784463;2173.8260939124834;2431.9513609727796;2508.285138735461;2517.3580736912063;2487.7139129000125;2507.611320245365;2508.09708504939;2510.836795608289;2513.195777351248;2466.136852101768;2520.823542352036;2522.1676846640567;2522.7477252274625;2523.2430270918653;9736.318457707963;9737;9772.169365603932;9798.412095274307;9808.607655693764;5525.696086715229;5609.270794796691;5664.008798460294;5694.259746233005;5694.743736531825;3514.279572687593;3548.0952357148763;3657.1405719656127;3669.9266014679556;3681.3189559931498;1263.6082814327588;1270.8284381608457;1272.1485726750739;1277.4379273119798;1287.4978758284276;13124.86875131246;13158.078934474686;13159.144655597356;13163.60509184726;13227.801582976263;4749.881252968635;4880.194767863334;4896.706197628154;4979.875503112378;5035.7985680572365;11109.222354435273;11110.61112861062;11117.888821111846;11144.164187685956;11169.609063682732;5698.401667824902;5714.342850572154;5716.685582293014;5732.627379220421;5733.254676891977;5084.949150508448;5214.869628259248;5223.059849227099;5225.895482090337;5262.3158989331905;4293.592108749664;4376.212281789309;4415.050764085737;4521.796519156645;4534.727916325031;1321.1148530484597;1341.6779972806455;1344.9731005379838;1356.5998030581002;1402.0255922134222;1075.5160177919968;1086.9510872010778;1096.3750662122513;1098.8132017557034;1103.5199688135526;6461.515386346043;6469.870602587922;6593.637349945799;6721.7311307548125;6771.4582833373215;488.6372066569373;492.09945799187403;495.3016247091584;503.5166240409248;504.2285303485673;4498.572635599613;4551.2717965126085;4632.119897219518;4704.388429504211;4717.339572459859;11826.34955077479;12203.328816915155;12295.69260768488;12309.26144431337;12315.568955086708;11328.207025508136;11741.823872641917;11814.704632384259;12041.217320874115;12179.147459677915;8696.608652610648;8804.8679269811;8874.245689116497;8886.37795354319;8891.377603567687;6723.798286051427;7033.859322813412;7041.3662770348865;7078.716851325889;7096.7516136934955;11547.8267825981;11846.585369512026;11847.585334513251;11855.407229638657;11864.288142711464;11553.942230288794;11570.652880413601;11780.352080635304;11895.940520297343;11978.46096925623;8624.873207501998;8719.346049046473;8758.12418758119;8760.824783504167;8825.02924678272;9145.63417463294;9216.262698983932;9275.953620231856;9304;9306.767330816783;8052.597370131587;8155.79610509742;8313.750587482386;8366.832663346577;8403.915960840557;8179.754607361789;8327;8343.95828020856;8349.373796965374;8436.789080273042;7745.186755390603;7865.4494185406475;7959.044914610284;8147.75556733299;8150.673973041012;5642.266505354278;5835.44563266489;5879.059350503899;5928.525717942468;5929.199558059607;5897.5576831736935;5934.584579079423;5940.198073260054;6029.75880964774;6066.636001839904 19:33:28 INFO - PROCESS | 11203 | |4;prototype.html;350.2959052304868;352.68963312285183;356.3656691089858;359.123738079087;360.4143267190815;5585.385607583163;5641.943580564198;5660.565956638644;5675.687837168949;5682.545396368291;5592.6085174037735;5620.46605572471;5633.380328163909;5666.235058267132;5666.376698563155;3981.606437746794;4054.351303791394;4322.913541729164;4355.303151495761;4387.341898715186;4338.240807858628;4348.695591308594;4359.324304732771;4360.956390436087;4376.343548467724;4336.566343365654;4340.348947657861;4354.3250796126495;4356.324769660708;4357.433533640622;4118.217538667644;4254.149170165948;4362.978185109086;4370.907273181705;4383.824647014148;3885.2812229737588;3889.961100388988;3897.2984862724697;3900.2589507993675;3903.512060992376;5466.289382380305;5474.534664553517;5487.6981766002855;5493.818828951795;5508.394076651565;4862.902741945177;4956.521739130435;4960.925586116211;4984.800607975713;5027.597792176617;5778.797742079007;5787.594868359217;5791.623544469596;5802.912956305659;5814.97092514539;4182.937255941163;4223.282042052859;4253.766042867641;4265.360195970629;4276.422682937795;5828.562857785683;6046.2744470663365;6055.273367195965;6066;6085.7828434313315;4323.092150648399;4326.524082350938;4332.393464914914;4332.545082766327;4349.608535231844;4417.955820441819;4426.402435671143;4427.358033085249;4439.600435960779;4450.310201918708;4794.496577859344;4946.5053494650965;5003.199488081889;5013.543363751808;5028.622853286018;5850.3272123705465;5869.706514674334;5887.381824908409;5900.734466948998;5930.169776231331;1425.4583258361756;1476.6898951220364;1490.180400779567;1496.6707324388617;1503.3310176971142;935.7320830275015;964.1756298364797;967.8983706710835;980.764616492051;999.5651891427252;5583.743657676991;5647.921882574007;5682.204491371212;5721.854304635762;5730.484256416859;6045.6699526104185;6052.425019623129;6055.000924847439;6068.4538391544975;6071.483923866433;5510.559155267569;5708.828735137952;5710.943475457054;5711.771529138871;5717.885642287131;3177.237463008861;3201.6798320167795;3210.293735378213;3215.5980502437196;3218.9839050804594;6141.170941922784;6192.535559832941;6281.461042044728;6297.614524804536;6364.809055728336;6113.441072526566;6135.944458606327;6136.6011209271255;6150.200473938449;6189.690515474208;6240.095186197976;6249.031400132987;6260.686965651699;6269.65516896566;6281.780137695158;5777.653340799566;5996.220491336273;6024.246969128859;6059.363766804422;6067.696615169136;5962.9105563415715;5988.760449581968;5995;6006.729697163551;6009.626197651815;6022.45797878193;6167.784127555514;6178.320384757763;6287.2140982377205;6310.400511951358;3819.6371344722206;3821.808909554567;3852.460655508175;3887.1253967857006;3906.706996975295;5360.973195133999;5394.406615272238;5445.482679145475;5457.918131227955;5485.286912701324;5259.974305010486;5269.868253293698;5532.86167845791;5937.732802024006;5955.374685657944;5882.764689412375;5895.793647222326;5922.703864806656;5925.703714814156;5930.094253815686;5492.35229431161;5860.824175274749;5944.821655350346;5945.127284905241;5957.702114894151;5044.167712327425;5924.822255332347;5963.224780778471;5966.403359663999;5968.791092311922;5815.336338106966;5832.679202643895;5879.000569903042;5947.434993675594;5960.612560183574 19:33:28 INFO - PROCESS | 11203 | |5;yui.html;834.64110432514;872.7120050383375;893.3746377535723;904.0642934562729;919.3151102428692;13984;14333.354999025043;14364.784528232085;14374.856251437483;14736.189509576969;14995.47515836946;15115.319810608522;15125.470608528703;15133.470328538504;15144.318505667245;714.7248309400892;837.6816809612354;857.7684025313162;863.201538576817;864.3906046237419;829.8298848735994;859.2052351574769;861.3066481482405;864.8313578852126;865.0614083719172;908.7682640926588;911.2163539356148;911.3711539038076;911.5715613661569;911.7492689510404;730.7917243585553;903.5346796399859;903.8101998580306;904.6064961741663;904.900460949295;645.5803727577113;660.0594153331483;665.4809248785955;665.7436886798617;666.8366250268667;13421.66445838852;13495.797563036562;13529.120607160503;13556.389962451713;13625;14818.777718334233;15414.383424663;15984.360625574964;15991.040537567784;16080.437184698596;14315.055144394857;14471.42114315426;14545.27273636314;14552.781708274384;14633.634159146;4640.396748422717;4784.043191361741;4790.760461976887;4852.878678033077;4914.459409464956;89166.55416722875;89166.55416722875;89206.5539672304;89580.55209723975;89744.5512772432;12353.752924941451;12463.937680311541;12844.229346239255;13074.477020919247;13230.07389482727;23180.76819231786;23181.188658396874;23445.88277058604;23492.647610285858;23533.411664708176;8909.821803563893;9086.86369704455;9368.203702685349;9398.906010939803;9486.33595648298;4914.680545764585;5129.769160387791;5220.817271395483;5270.683758974474;5332.0402327581405;3728.925421491555;3861.092643228839;3867;3867.439221312894;3872.5933776953016;649.4187702006677;651.8598501322239;658.9439897608758;674.2684187656448;676.2730065179853;602.295314482057;662.0797092042066;668.6489592963733;722.9132504099437;749.775067479754;9791.118799308097;10070.748231294276;10146.188304935587;10164.136048435817;10818.188635852342;582.9562782791223;667.276005534001;724.7789424225662;744.4528271720282;790.4940837863771;4640.65195110368;4813.927791083137;4918.680285781485;4922.906453894728;4960.330355402047;98510.50744746231;102586;135531.64468355387;135766.32116839552;138439;62562;73001.6349918247;100085;101016.49491752642;102269.48865255626;54901.72549137309;76761;89983.55008225048;91088;93127.06872931319;9531.666391676536;9868.654597089067;9900.108990190918;10935.398553079656;11443.599474018369;54046.729766350916;54065.18902216391;54952.450475494734;55544.44455555393;57244.13779311101;80155;81309.59345203236;81351.59324203341;81360;81479;12384.504619815107;12581.496740130293;12601.873981260436;12805;12805.487780488676;13960.930195348958;14047.297635118408;14257.928710356382;14453.710925781215;14513.05665131763;12789.744205116032;13194;13520.12119212248;13542.864571354161;13558.661033474242;12301.876981230072;12883.291418971672;12909.289989050692;13049.934750326187;13238.53665121716;13127.540536081191;13146;13243.536476223286;13318.733625327246;13417.530386436429;10078.496075196357;10191.592336306465;10230.437325947145;10297.279190456597;10731.463426828472;11114.77440754524;11392.25950313227;11401.144914131308;11457.942710286728;11474.483648236017 19:33:28 INFO - PROCESS | 11203 | __end_tp_report 19:33:28 INFO - PROCESS | 11203 | __start_cc_report 19:33:28 INFO - PROCESS | 11203 | _x_x_mozilla_cycle_collect,1174 19:33:28 INFO - PROCESS | 11203 | __end_cc_report 19:33:28 INFO - PROCESS | 11203 | __startTimestamp1471055607993__endTimestamp 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | ------- Summary: start ------- 19:33:28 INFO - PROCESS | 11203 | Number of tests: 6 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#0] dojo.html Cycles:165 Average:5609.75 Median:5879.71 stddev:641.81 (10.9%) stddev-sans-first:615.22 19:33:28 INFO - PROCESS | 11203 | Values: 3196.7 3256.0 3268.4 3275.9 3304.0 5589.7 5670.5 5673.2 5700.0 5711.1 5610.5 5650.3 5652.4 5653.7 5679.9 5041.1 5069.4 5100.4 5106.8 5158.3 5098.7 5120.8 5133.0 5148.0 5173.3 5076.1 5118.5 5120.0 5136.3 5138.4 5168.3 5181.8 5184.7 5184.9 5218.6 4884.0 5051.5 5069.3 5080.2 5084.2 5593.4 5639.9 5647.7 5677.9 5683.6 6071.5 6075.5 6082.9 6084.8 6117.9 5924.3 6023.7 6031.2 6084.2 6101.6 5751.7 5752.1 5760.4 5761.0 5764.4 6226.6 6263.3 6278.6 6281.3 6283.3 5737.7 5866.4 5882.0 5896.4 6041.1 5967.8 6041.4 6050.5 6053.1 6057.7 5989.1 5995.2 5995.9 6000.0 6000.5 5918.5 5976.6 5982.9 6057.4 6092.3 4597.5 4599.5 4600.7 4631.5 4638.4 4117.8 4119.5 4119.7 4138.7 4151.6 5559.9 5574.9 5626.2 5699.2 5771.5 5978.6 5985.0 5985.3 6014.2 6021.3 5626.9 5660.9 5667.8 5675.0 5680.9 5081.2 5149.7 5166.3 5187.5 5234.6 6232.5 6254.4 6271.0 6274.2 6275.8 6077.5 6081.7 6087.2 6089.7 6092.9 6116.5 6118.6 6133.5 6284.5 6307.9 5907.5 5912.8 5915.0 5946.2 5957.7 5958.6 5998.4 6018.3 6018.8 6022.5 5735.2 5879.7 5884.6 5890.3 5904.7 5949.5 5953.6 5960.0 5981.8 5995.3 5911.6 5925.7 5928.1 5941.3 5945.6 5848.8 5945.5 5945.6 5951.9 6052.2 5822.6 5866.1 5873.2 5888.2 5908.6 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#1] ext.html Cycles:180 Average:61826.48 Median:21175.96 stddev:121966.68 (576.0%) stddev-sans-first:122222.19 19:33:28 INFO - PROCESS | 11203 | Values: 598.9 625.5 626.2 643.1 654.7 34612.1 45389.3 46431.5 46507.5 46529.5 46759.5 47189.3 47201.0 47244.0 47247.0 8411.5 9152.8 9277.2 9307.0 9314.4 8328.3 8618.2 8621.7 10804.5 11114.3 11269.8 11340.3 11341.6 11342.7 11355.4 11187.3 11251.9 11253.8 11254.2 11261.4 10993.8 11071.9 11079.8 11089.2 11096.9 44507.8 44895.1 44944.0 45012.3 45082.0 3756.2 3766.9 3804.8 3855.1 3878.9 34591.3 34830.8 34919.3 35176.0 36862.3 4440.5 4513.6 4514.3 4591.5 4669.5 371139.0 373980.0 374367.0 374457.0 375274.0 37809.6 60911.0 61239.4 61293.0 61333.1 38950.2 40447.8 40470.8 40545.8 40563.4 17225.6 17443.4 17459.6 17462.7 17463.4 14462.9 14484.1 14583.3 14590.0 14598.0 8641.5 8881.3 9073.7 9152.8 9296.1 3534.5 3545.2 3552.4 3564.6 3591.8 1079.8 1090.1 1110.4 1128.6 1178.6 34928.7 35028.3 35029.3 35034.0 35140.6 1120.3 1157.7 1197.9 1223.5 1225.6 16409.3 17197.5 17284.4 17313.0 17337.1 532107.0 535414.0 536332.0 536367.0 536888.0 60951.0 61414.1 61434.7 61461.7 61477.4 1370.2 1466.0 1468.8 1499.4 1525.8 33231.0 34950.8 35273.5 35449.3 35467.6 52811.5 53402.7 53419.2 53561.5 53598.5 454079.0 457002.0 457581.0 458890.0 459281.0 26932.3 27576.4 27860.7 27900.0 32736.4 50990.7 51422.7 51711.5 51815.2 53044.5 44140.3 44915.0 45147.8 45726.3 46923.5 43625.8 44778.6 45161.8 45599.5 45815.3 40668.0 41413.8 41570.0 43293.8 44911.0 34644.7 38155.8 38310.0 38496.8 39161.0 28915.7 28957.7 29182.3 29207.4 29264.9 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#2] jquery.html Cycles:180 Average:25932.77 Median:216185.54 stddev:66610.62 (30.8%) stddev-sans-first:66769.89 19:33:28 INFO - PROCESS | 11203 | Values: 399.6 400.5 427.1 433.8 435.4 15286.5 20012.8 20165.3 20861.6 21122.6 20463.2 20497.7 20848.8 20862.7 20996.9 5678.5 5833.3 5923.6 5981.4 5982.7 4448.3 4683.8 5890.9 5940.9 5946.5 4320.3 6408.9 6670.8 6675.4 6678.2 6667.4 6674.1 6722.4 6726.6 6737.0 5967.5 5978.0 5979.6 5983.8 5986.4 23836.5 24285.6 24509.0 24623.0 24692.0 2572.9 2577.1 2826.6 2851.2 3015.1 14987.3 15032.4 15556.8 15758.1 15823.9 2050.5 2071.4 2071.6 2127.4 2292.7 75378.0 77378.6 79796.0 87522.0 89522.0 10809.4 10864.0 11117.1 11180.3 11197.2 15193.0 15207.2 15219.5 15230.4 15253.3 8262.6 8464.7 8807.0 8919.0 9142.6 8902.9 8924.9 8942.9 8959.0 9179.1 4660.0 4705.0 4725.0 4881.4 5082.1 2098.6 2131.8 2131.9 2142.4 2144.3 934.6 985.6 1017.3 1064.3 1065.8 19896.2 20550.8 20577.9 20854.8 21096.5 1012.9 1020.1 1036.2 1054.1 1093.2 9515.1 11374.7 11418.9 11512.7 11560.7 183538.0 185266.0 431944.0 514980.0 521645.0 67809.7 94328.0 95275.5 95987.0 96589.5 1021.1 1028.1 1045.3 1048.6 1052.9 17640.6 17937.2 18090.4 18176.7 18489.7 33104.7 42430.0 42526.6 43513.6 44324.6 61601.0 61713.4 64006.0 64323.0 64636.0 8445.5 8605.4 8615.4 8673.7 8851.7 8858.0 9098.0 9189.4 9651.7 10055.3 8697.0 8766.2 8801.6 9018.1 9273.9 8641.8 8789.5 8879.7 8984.5 8992.6 8823.9 8963.6 9150.7 9233.3 9241.6 12525.2 12737.4 12764.9 12765.7 13223.9 12654.6 12937.4 12964.9 12972.9 12998.4 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#3] mootools.html Cycles:180 Average:6379.55 Median:2619.26 stddev:3683.64 (140.6%) stddev-sans-first:3674.02 19:33:28 INFO - PROCESS | 11203 | Values: 1278.2 1437.6 1477.7 1880.4 1909.6 9180.4 9931.0 9932.5 9936.1 9958.4 8550.7 9175.4 9422.5 9437.7 9674.8 2391.2 2418.3 2418.6 2427.7 2430.5 2437.2 2462.2 2495.0 2498.5 2505.6 2104.4 2173.8 2432.0 2508.3 2517.4 2487.7 2507.6 2508.1 2510.8 2513.2 2466.1 2520.8 2522.2 2522.7 2523.2 9736.3 9737.0 9772.2 9798.4 9808.6 5525.7 5609.3 5664.0 5694.3 5694.7 3514.3 3548.1 3657.1 3669.9 3681.3 1263.6 1270.8 1272.1 1277.4 1287.5 13124.9 13158.1 13159.1 13163.6 13227.8 4749.9 4880.2 4896.7 4979.9 5035.8 11109.2 11110.6 11117.9 11144.2 11169.6 5698.4 5714.3 5716.7 5732.6 5733.3 5084.9 5214.9 5223.1 5225.9 5262.3 4293.6 4376.2 4415.1 4521.8 4534.7 1321.1 1341.7 1345.0 1356.6 1402.0 1075.5 1087.0 1096.4 1098.8 1103.5 6461.5 6469.9 6593.6 6721.7 6771.5 488.6 492.1 495.3 503.5 504.2 4498.6 4551.3 4632.1 4704.4 4717.3 11826.3 12203.3 12295.7 12309.3 12315.6 11328.2 11741.8 11814.7 12041.2 12179.1 8696.6 8804.9 8874.2 8886.4 8891.4 6723.8 7033.9 7041.4 7078.7 7096.8 11547.8 11846.6 11847.6 11855.4 11864.3 11553.9 11570.7 11780.4 11895.9 11978.5 8624.9 8719.3 8758.1 8760.8 8825.0 9145.6 9216.3 9276.0 9304.0 9306.8 8052.6 8155.8 8313.8 8366.8 8403.9 8179.8 8327.0 8344.0 8349.4 8436.8 7745.2 7865.4 7959.0 8147.8 8150.7 5642.3 5835.4 5879.1 5928.5 5929.2 5897.6 5934.6 5940.2 6029.8 6066.6 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#4] prototype.html Cycles:180 Average:4917.91 Median:5637.66 stddev:1459.18 (25.9%) stddev-sans-first:1422.43 19:33:28 INFO - PROCESS | 11203 | Values: 350.3 352.7 356.4 359.1 360.4 5585.4 5641.9 5660.6 5675.7 5682.5 5592.6 5620.5 5633.4 5666.2 5666.4 3981.6 4054.4 4322.9 4355.3 4387.3 4338.2 4348.7 4359.3 4361.0 4376.3 4336.6 4340.3 4354.3 4356.3 4357.4 4118.2 4254.1 4363.0 4370.9 4383.8 3885.3 3890.0 3897.3 3900.3 3903.5 5466.3 5474.5 5487.7 5493.8 5508.4 4862.9 4956.5 4960.9 4984.8 5027.6 5778.8 5787.6 5791.6 5802.9 5815.0 4182.9 4223.3 4253.8 4265.4 4276.4 5828.6 6046.3 6055.3 6066.0 6085.8 4323.1 4326.5 4332.4 4332.5 4349.6 4418.0 4426.4 4427.4 4439.6 4450.3 4794.5 4946.5 5003.2 5013.5 5028.6 5850.3 5869.7 5887.4 5900.7 5930.2 1425.5 1476.7 1490.2 1496.7 1503.3 935.7 964.2 967.9 980.8 999.6 5583.7 5647.9 5682.2 5721.9 5730.5 6045.7 6052.4 6055.0 6068.5 6071.5 5510.6 5708.8 5710.9 5711.8 5717.9 3177.2 3201.7 3210.3 3215.6 3219.0 6141.2 6192.5 6281.5 6297.6 6364.8 6113.4 6135.9 6136.6 6150.2 6189.7 6240.1 6249.0 6260.7 6269.7 6281.8 5777.7 5996.2 6024.2 6059.4 6067.7 5962.9 5988.8 5995.0 6006.7 6009.6 6022.5 6167.8 6178.3 6287.2 6310.4 3819.6 3821.8 3852.5 3887.1 3906.7 5361.0 5394.4 5445.5 5457.9 5485.3 5260.0 5269.9 5532.9 5937.7 5955.4 5882.8 5895.8 5922.7 5925.7 5930.1 5492.4 5860.8 5944.8 5945.1 5957.7 5044.2 5924.8 5963.2 5966.4 5968.8 5815.3 5832.7 5879.0 5947.4 5960.6 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - PROCESS | 11203 | [#5] yui.html Cycles:180 Average:21378.15 Median:4787.40 stddev:31030.53 (648.2%) stddev-sans-first:31079.24 19:33:28 INFO - PROCESS | 11203 | Values: 834.6 872.7 893.4 904.1 919.3 13984.0 14333.4 14364.8 14374.9 14736.2 14995.5 15115.3 15125.5 15133.5 15144.3 714.7 837.7 857.8 863.2 864.4 829.8 859.2 861.3 864.8 865.1 908.8 911.2 911.4 911.6 911.7 730.8 903.5 903.8 904.6 904.9 645.6 660.1 665.5 665.7 666.8 13421.7 13495.8 13529.1 13556.4 13625.0 14818.8 15414.4 15984.4 15991.0 16080.4 14315.1 14471.4 14545.3 14552.8 14633.6 4640.4 4784.0 4790.8 4852.9 4914.5 89166.6 89166.6 89206.6 89580.6 89744.6 12353.8 12463.9 12844.2 13074.5 13230.1 23180.8 23181.2 23445.9 23492.6 23533.4 8909.8 9086.9 9368.2 9398.9 9486.3 4914.7 5129.8 5220.8 5270.7 5332.0 3728.9 3861.1 3867.0 3867.4 3872.6 649.4 651.9 658.9 674.3 676.3 602.3 662.1 668.6 722.9 749.8 9791.1 10070.7 10146.2 10164.1 10818.2 583.0 667.3 724.8 744.5 790.5 4640.7 4813.9 4918.7 4922.9 4960.3 98510.5 102586.0 135531.6 135766.3 138439.0 62562.0 73001.6 100085.0 101016.5 102269.5 54901.7 76761.0 89983.6 91088.0 93127.1 9531.7 9868.7 9900.1 10935.4 11443.6 54046.7 54065.2 54952.5 55544.4 57244.1 80155.0 81309.6 81351.6 81360.0 81479.0 12384.5 12581.5 12601.9 12805.0 12805.5 13960.9 14047.3 14257.9 14453.7 14513.1 12789.7 13194.0 13520.1 13542.9 13558.7 12301.9 12883.3 12909.3 13049.9 13238.5 13127.5 13146.0 13243.5 13318.7 13417.5 10078.5 10191.6 10230.4 10297.3 10731.5 11114.8 11392.3 11401.1 11457.9 11474.5 19:33:28 INFO - PROCESS | 11203 | -------- Summary: end -------- 19:33:28 INFO - PROCESS | 11203 | 19:33:28 INFO - TEST-INFO | 11203: exit 0 19:33:28 INFO - TEST-OK | dromaeo_css | took 1112494ms 19:33:28 INFO - TEST-START | kraken 19:33:28 INFO - operating with platform_type : linux_ 19:33:28 INFO - Initialising browser for kraken test... 19:33:28 INFO - TEST-INFO | started process 11411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJb5dYZ/profile http://localhost:42476/getInfo.html) 19:33:32 INFO - PROCESS | 11411 | __metrics Screen width/height:1600/1200 19:33:32 INFO - PROCESS | 11411 | colorDepth:24 19:33:32 INFO - PROCESS | 11411 | Browser inner width/height: 1024/697 19:33:32 INFO - PROCESS | 11411 | __metrics 19:33:33 INFO - PROCESS | 11411 | 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" 19:33:33 INFO - PROCESS | 11411 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:33:34 INFO - TEST-INFO | 11411: exit 0 19:33:34 INFO - Browser initialized. 19:33:34 INFO - Running cycle 1/1 for kraken test... 19:33:34 INFO - TEST-INFO | started process 11498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJb5dYZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:45 INFO - PROCESS | 11498 | RSS: Main: 145702912 19:33:45 INFO - PROCESS | 11498 | 19:33:48 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42476/tests/kraken/driver.html?testName=audio-beat-detection) 19:33:49 INFO - PROCESS | 11498 | RSS: Main: 191324160 19:33:49 INFO - PROCESS | 11498 | 19:33:51 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42476/tests/kraken/driver.html?testName=audio-dft) 19:33:52 INFO - PROCESS | 11498 | RSS: Main: 181014528 19:33:52 INFO - PROCESS | 11498 | 19:33:55 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42476/tests/kraken/driver.html?testName=audio-fft) 19:33:55 INFO - PROCESS | 11498 | RSS: Main: 269266944 19:33:55 INFO - PROCESS | 11498 | 19:33:57 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42476/tests/kraken/driver.html?testName=audio-oscillator) 19:33:58 INFO - PROCESS | 11498 | RSS: Main: 175030272 19:33:58 INFO - PROCESS | 11498 | 19:34:00 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42476/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:01 INFO - PROCESS | 11498 | RSS: Main: 176877568 19:34:01 INFO - PROCESS | 11498 | 19:34:04 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42476/tests/kraken/driver.html?testName=imaging-darkroom) 19:34:04 INFO - PROCESS | 11498 | RSS: Main: 212447232 19:34:04 INFO - PROCESS | 11498 | 19:34:08 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42476/tests/kraken/driver.html?testName=imaging-desaturate) 19:34:08 INFO - PROCESS | 11498 | RSS: Main: 211783680 19:34:08 INFO - PROCESS | 11498 | 19:34:11 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42476/tests/kraken/driver.html?testName=json-parse-financial) 19:34:12 INFO - PROCESS | 11498 | RSS: Main: 211550208 19:34:12 INFO - PROCESS | 11498 | 19:34:13 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42476/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:34:14 INFO - PROCESS | 11498 | RSS: Main: 175828992 19:34:14 INFO - PROCESS | 11498 | 19:34:15 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:34:16 INFO - PROCESS | 11498 | RSS: Main: 182378496 19:34:16 INFO - PROCESS | 11498 | 19:34:18 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:34:19 INFO - PROCESS | 11498 | RSS: Main: 185856000 19:34:19 INFO - PROCESS | 11498 | 19:34:21 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:34:22 INFO - PROCESS | 11498 | RSS: Main: 186036224 19:34:22 INFO - PROCESS | 11498 | 19:34:24 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:34:25 INFO - PROCESS | 11498 | RSS: Main: 184639488 19:34:25 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | Cycle 1(1): loaded http://localhost:42476/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42476/tests/kraken/driver.html?testName=ai-astar) 19:34:27 INFO - PROCESS | 11498 | RSS: Main: 235630592 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | __start_tp_report 19:34:27 INFO - PROCESS | 11498 | _x_x_mozilla_page_load 19:34:27 INFO - PROCESS | 11498 | _x_x_mozilla_page_load_details 19:34:27 INFO - PROCESS | 11498 | |i|pagename|runs| 19:34:27 INFO - PROCESS | 11498 | |0;ai-astar;101;111;108;110;111;109;109;110;109;109 19:34:27 INFO - PROCESS | 11498 | |1;audio-beat-detection;152;115;117;163;118;119;142;116;155;115 19:34:27 INFO - PROCESS | 11498 | |2;audio-dft;163;159;159;159;160;161;160;160;160;159 19:34:27 INFO - PROCESS | 11498 | |3;audio-fft;77;75;74;118;74;76;73;75;126;74 19:34:27 INFO - PROCESS | 11498 | |4;audio-oscillator;81;82;121;81;83;82;115;82;83;122 19:34:27 INFO - PROCESS | 11498 | |5;imaging-gaussian-blur;119;117;117;117;116;116;117;117;116;117 19:34:27 INFO - PROCESS | 11498 | |6;imaging-darkroom;146;146;145;146;146;146;146;146;145;146 19:34:27 INFO - PROCESS | 11498 | |7;imaging-desaturate;95;94;94;95;95;94;94;96;94;94 19:34:27 INFO - PROCESS | 11498 | |8;json-parse-financial;51;52;52;51;51;51;52;51;53;53 19:34:27 INFO - PROCESS | 11498 | |9;json-stringify-tinderbox;59;60;60;60;60;60;59;60;60;60 19:34:27 INFO - PROCESS | 11498 | |10;stanford-crypto-aes;73;72;73;73;82;74;72;72;81;72 19:34:27 INFO - PROCESS | 11498 | |11;stanford-crypto-ccm;118;122;117;116;118;119;124;119;157;120 19:34:27 INFO - PROCESS | 11498 | |12;stanford-crypto-pbkdf2;152;155;153;150;153;155;153;155;156;151 19:34:27 INFO - PROCESS | 11498 | |13;stanford-crypto-sha256-iterative;63;61;61;61;62;61;60;60;59;59 19:34:27 INFO - PROCESS | 11498 | __end_tp_report 19:34:27 INFO - PROCESS | 11498 | __start_cc_report 19:34:27 INFO - PROCESS | 11498 | _x_x_mozilla_cycle_collect,1618 19:34:27 INFO - PROCESS | 11498 | __end_cc_report 19:34:27 INFO - PROCESS | 11498 | __startTimestamp1471055667311__endTimestamp 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | ------- Summary: start ------- 19:34:27 INFO - PROCESS | 11498 | Number of tests: 14 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.50 stddev:2.87 (2.6%) stddev-sans-first:1.01 19:34:27 INFO - PROCESS | 11498 | Values: 101.0 111.0 108.0 110.0 111.0 109.0 109.0 110.0 109.0 109.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:130.50 stddev:19.46 (14.9%) stddev-sans-first:19.13 19:34:27 INFO - PROCESS | 11498 | Values: 152.0 115.0 117.0 163.0 118.0 119.0 142.0 116.0 155.0 115.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.25 (0.8%) stddev-sans-first:0.71 19:34:27 INFO - PROCESS | 11498 | Values: 163.0 159.0 159.0 159.0 160.0 161.0 160.0 160.0 160.0 159.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#3] audio-fft Cycles:10 Average:84.20 Median:74.50 stddev:20.04 (26.9%) stddev-sans-first:21.09 19:34:27 INFO - PROCESS | 11498 | Values: 77.0 75.0 74.0 118.0 74.0 76.0 73.0 75.0 126.0 74.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:82.00 stddev:18.13 (22.1%) stddev-sans-first:18.69 19:34:27 INFO - PROCESS | 11498 | Values: 81.0 82.0 121.0 81.0 83.0 82.0 115.0 82.0 83.0 122.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.50 19:34:27 INFO - PROCESS | 11498 | Values: 119.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 19:34:27 INFO - PROCESS | 11498 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 19:34:27 INFO - PROCESS | 11498 | Values: 95.0 94.0 94.0 95.0 95.0 94.0 94.0 96.0 94.0 94.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 19:34:27 INFO - PROCESS | 11498 | Values: 51.0 52.0 52.0 51.0 51.0 51.0 52.0 51.0 53.0 53.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 19:34:27 INFO - PROCESS | 11498 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:73.00 stddev:3.81 (5.2%) stddev-sans-first:4.00 19:34:27 INFO - PROCESS | 11498 | Values: 73.0 72.0 73.0 73.0 82.0 74.0 72.0 72.0 81.0 72.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:119.50 stddev:12.17 (10.2%) stddev-sans-first:12.78 19:34:27 INFO - PROCESS | 11498 | Values: 118.0 122.0 117.0 116.0 118.0 119.0 124.0 119.0 157.0 120.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:154.00 stddev:1.95 (1.3%) stddev-sans-first:2.01 19:34:27 INFO - PROCESS | 11498 | Values: 152.0 155.0 153.0 150.0 153.0 155.0 153.0 155.0 156.0 151.0 19:34:27 INFO - PROCESS | 11498 | 19:34:27 INFO - PROCESS | 11498 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:1.25 (2.1%) stddev-sans-first:1.01 19:34:27 INFO - PROCESS | 11498 | Values: 63.0 61.0 61.0 61.0 62.0 61.0 60.0 60.0 59.0 59.0 19:34:27 INFO - PROCESS | 11498 | -------- Summary: end -------- 19:34:27 INFO - PROCESS | 11498 | 19:34:28 INFO - TEST-INFO | 11498: exit 0 19:34:28 INFO - TEST-OK | kraken | took 59603ms 19:34:28 INFO - SUITE-END | took 1172s 19:34:28 INFO - Completed test suite (00:19:32) 19:34:28 INFO - javascript benchmark 19:34:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3196.6643502432244, 3255.9999999999986, 3268.395346860831, 3275.91810204745, 3304.041827869918, 5589.74846131924, 5670.4896559309655, 5673.177389278552, 5700.030994730895, 5711.114777209534, 5610.523105536025, 5650.29371328584, 5652.406497317784, 5653.6607803531815, 5679.886402271952, 5041.092603331399, 5069.417017043042, 5100.438951715309, 5106.846794596168, 5158.277841102249, 5098.745062746866, 5120.795168193268, 5133.0, 5147.974260128694, 5173.3016042834115, 5076.111680455924, 5118.488151184889, 5120.0, 5136.306598609177, 5138.434772175057, 5168.3281173447585, 5181.792728290901, 5184.662996905208, 5184.896302073975, 5218.608604354688, 4884.0, 5051.4695956924725, 5069.340985671876, 5080.1617733074, 5084.186530155157, 5593.412691667357, 5639.887202255973, 5647.689377084259, 5677.858053548694, 5683.5737319700775, 6071.51427885768, 6075.453209211192, 6082.8746681863995, 6084.847878803065, 6117.868194384008, 5924.259467566554, 6023.668698221596, 6031.185789918349, 6084.239470066242, 6101.572889897709, 5751.654900705971, 5752.108423194411, 5760.36635970043, 5761.0, 5764.394738552475, 6226.5641405101205, 6263.279722831932, 6278.591891527036, 6281.340459251713, 6283.277423096311, 5737.655740655574, 5866.413358664185, 5882.000059989812, 5896.351401345849, 6041.093835924647, 5967.8209653710455, 6041.365656605993, 6050.485708714808, 6053.061775424728, 6057.697115144313, 5989.101634754822, 5995.2206213192, 5995.910061348996, 6000.0, 6000.459958603747, 5918.496927761188, 5976.581639285208, 5982.850428739316, 6057.42454466825, 6092.329843717188, 4597.471290801601, 4599.494055653875, 4600.723956562617, 4631.536846315342, 4638.373819534387, 4117.794110294533, 4119.526254480713, 4119.6910231732145, 4138.668906487534, 4151.647109995623, 5559.861003474864, 5574.888502229933, 5626.1842032905, 5699.202111704365, 5771.451712087345, 5978.581499295007, 5984.970075149597, 5985.25184351956, 6014.218151640259, 6021.2774467063355, 5626.915596266059, 5660.858478537987, 5667.773289068474, 5674.971625141848, 5680.857978550486, 5081.2378143278065, 5149.6910185389, 5166.276721259027, 5187.507186817246, 5234.633575649744, 6232.532560057924, 6254.437100660963, 6270.968645156745, 6274.247090349188, 6275.780347687901, 6077.453029227391, 6081.726322315418, 6087.239095113111, 6089.665068421102, 6092.939070609237, 6116.541259405473, 6118.619179173164, 6133.53998450127, 6284.4972402206795, 6307.873842523032, 5907.497862681548, 5912.793052243151, 5914.970425147847, 5946.226990491208, 5957.702114894151, 5958.582899197015, 5998.370171131968, 6018.307894592002, 6018.849528761816, 6022.45797878193, 5735.168400581809, 5879.7060146993335, 5884.558658100574, 5890.263717035371, 5904.704764761659, 5949.464548190684, 5953.642781433128, 5960.0, 5981.7607295707685, 5995.280566331893, 5911.55663325261, 5925.703714814328, 5928.140419639214, 5941.287045554388, 5945.64326140433, 5848.795292164754, 5945.524358051432, 5945.554083443845, 5951.910721339097, 6052.243469566304, 5822.563307752021, 5866.090755932837, 5873.177755114373, 5888.234529511136, 5908.615939963888], "value": 5566.008784240312, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.9041753319469, 625.4902254662447, 626.2391194698442, 643.1125047434541, 654.744649586661, 34612.134696632595, 45389.319160212624, 46431.5356846431, 46507.53492465075, 46529.53470465294, 46759.53240467603, 47189.29216061762, 47201.0, 47244.0, 47247.0, 8411.537365444898, 9152.771180720485, 9277.211437027861, 9306.976232614406, 9314.394564353295, 8328.33373330132, 8618.224359807647, 8621.698240561582, 10804.513796879159, 11114.333140011584, 11269.830952535718, 11340.319580825177, 11341.603043893465, 11342.716432089223, 11355.432228388589, 11187.32876027437, 11251.887481125166, 11253.774924501464, 11254.212205145643, 11261.380624065672, 10993.780124397466, 11071.944640276828, 11079.833802492969, 11089.168312376523, 11096.944515277371, 44507.77746111281, 44895.10209795819, 44944.0, 45012.3248151278, 45082.0, 3756.1924186299802, 3766.856234633836, 3804.7907365094907, 3855.1133239354826, 3878.941815872764, 34591.30817383663, 34830.82584587086, 34919.30161396783, 35176.0, 36862.262754744756, 4440.511543730187, 4513.593776560126, 4514.345419914094, 4591.517890621503, 4669.517263427114, 371139.0, 373980.0, 374367.0, 374457.0, 375274.0, 37809.62190378116, 60911.0, 61239.38760612337, 61293.0, 61333.08000379998, 38950.220995579926, 40447.79776101159, 40470.79764601158, 40545.797271014046, 40563.39154912678, 17225.56936076608, 17443.389481368093, 17459.563510912074, 17462.65074698499, 17463.38878139259, 14462.85537144615, 14484.130952142905, 14583.270836458134, 14590.0, 14598.0, 8641.481511109228, 8881.33389995753, 9073.682421115229, 9152.81694366109, 9296.070392960784, 3534.540509733718, 3545.2023294758474, 3552.3605750964557, 3564.6435356464663, 3591.76084250933, 1079.8002369561657, 1090.1387903556101, 1110.3837370259423, 1128.6049882541045, 1178.6228406909706, 34928.65071349254, 35028.299434011176, 35029.29941401158, 35034.0, 35140.64859351374, 1120.288616728367, 1157.7105723569107, 1197.868234494205, 1223.5350566784564, 1225.597828256627, 16409.343626255053, 17197.484075477754, 17284.395046173577, 17313.0, 17337.133143342784, 532107.0, 535414.0, 536332.0, 536367.0, 536888.0, 60951.0, 61414.0787888182, 61434.69282653648, 61461.69269153626, 61477.38522614806, 1370.1915869636962, 1465.992670036643, 1468.7576549869366, 1499.4077339451073, 1525.8016457860629, 33231.0, 34950.82524587463, 35273.47089793604, 35449.291014179056, 35467.645323546436, 52811.471885280655, 53402.73298633482, 53419.19871201857, 53561.464385357205, 53598.46401535935, 454079.0, 457002.0, 457581.0, 458890.0, 459281.0, 26932.326691832077, 27576.448471030868, 27860.721392785814, 27900.0, 32736.363181840337, 50990.74504627453, 51422.742886286826, 51711.48288517217, 51815.2227716577, 53044.46955530396, 44140.33789493224, 44915.0, 45147.83474365356, 45726.31410528778, 46923.530764693285, 43625.78187109044, 44778.55221447744, 45161.77419112883, 45599.54400455953, 45815.31277030914, 40668.0, 41413.792931035154, 41570.0, 43293.78353108215, 44911.0, 34644.653553464144, 38155.80922095372, 38310.0, 38496.80751596224, 39161.0, 28915.7108428913, 28957.710422895503, 29182.27044323909, 29207.415851683272, 29264.853675731487], "value": 20390.919499847238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.6003996003996, 400.4573802497614, 427.07751257284264, 433.8243011580309, 435.4295872407147, 15286.541403757878, 20012.799872001277, 20165.29421470247, 20861.582768344622, 21122.577548449022, 20463.18147274107, 20497.692534611993, 20848.791512084874, 20862.68705969408, 20996.8950155249, 5678.4605462480995, 5833.300003999505, 5923.614965027281, 5981.371955944628, 5982.670953097578, 4448.332750087497, 4683.8360657377, 5890.852728681774, 5940.940590594104, 5946.464818166364, 4320.308750599905, 6408.93591064088, 6670.833229169261, 6675.365840245169, 6678.232003319632, 6667.3999340059145, 6674.098996635489, 6722.361375669304, 6726.596404215738, 6736.966315168417, 5967.522598192134, 5977.970110149421, 5979.581429299951, 5983.820485385445, 5986.401359864022, 23836.523269534686, 24285.635715464283, 24509.01963921441, 24623.0, 24692.012319507197, 2572.909948151824, 2577.05937332874, 2826.618406515135, 2851.2159156231996, 3015.1406849048135, 14987.250637468193, 15032.398704051824, 15556.766648500281, 15758.054516728918, 15823.920880395639, 2050.4976280811297, 2071.3682326890294, 2071.5753270579494, 2127.4149608857533, 2292.667563203343, 75378.0, 77378.61310693467, 79796.0, 87522.0, 89521.99999999935, 10809.35143891369, 10864.0, 11117.11063114965, 11180.273282236629, 11197.216194866443, 15193.012454190663, 15207.239638018054, 15219.543413697607, 15230.390784368723, 15253.313600887986, 8262.628181731836, 8464.661413543512, 8806.987196472359, 8918.955405222932, 9142.588583513758, 8902.86645700315, 8924.8661270081, 8942.930966410398, 8958.955205223932, 9179.127982841619, 4659.998100408361, 4705.0, 4724.952750472451, 4881.389826271716, 5082.110630639697, 2098.5802839432176, 2131.8294536437047, 2131.946701332448, 2142.389419015573, 2144.324537770597, 934.6354921580589, 985.5959056786683, 1017.2980643356061, 1064.3241541621126, 1065.8134826405349, 19896.204151834056, 20550.794492054887, 20577.897110514652, 20854.791452085286, 21096.472588185417, 1012.8835183953793, 1020.0717347214148, 1036.1917704190744, 1054.0513537815873, 1093.2183488805542, 9515.143637072697, 11374.715632109264, 11418.885811141949, 11512.654620361234, 11560.6531804046, 183538.0, 185266.0, 431944.0, 514980.0, 521645.0, 67809.66095169492, 94328.0, 95275.52362238283, 95987.0, 96589.51705241429, 1021.1065317847033, 1028.1466382902174, 1045.320541647935, 1048.556862510349, 1052.8683914510686, 17640.647187056442, 17937.192826322585, 18090.366837160636, 18176.727349090037, 18489.72265416047, 33104.66895331016, 42430.0, 42526.574734253496, 43513.564864352214, 44324.556754432044, 61601.0, 61713.38286617256, 64006.0, 64323.0, 64636.0, 8445.493270403795, 8605.35459840502, 8615.396922215385, 8673.653053877773, 8851.734447966572, 8857.955710221408, 9098.044705305847, 9189.375440068185, 9651.662191823252, 10055.29612927088, 8696.956515217384, 8766.167214114648, 8801.647934082564, 9018.053104423941, 9273.86089208649, 8641.783955400913, 8789.472631642122, 8879.733607991771, 8984.505852177932, 8992.640294388151, 8823.867641985504, 8963.641454341754, 9150.725478235663, 9233.322336522602, 9241.584128714358, 12525.248485090558, 12737.426815793497, 12764.872351276368, 12765.72342765716, 13223.933880330536, 12654.557090501788, 12937.353132343156, 12964.935175324063, 12972.870271297545, 12998.350082496027], "value": 9431.144414199362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1278.1500302298969, 1437.5543581489737, 1477.7340078785815, 1880.4452686457494, 1909.6180763847235, 9180.449173049614, 9931.0, 9932.503374831256, 9936.056074672904, 9958.402495850254, 8550.74347769568, 9175.357724959238, 9422.481763503021, 9437.716868493939, 9674.758131046727, 2391.246757271458, 2418.3349578865864, 2418.600930846408, 2427.7208121066037, 2430.4895971845935, 2437.19572541061, 2462.150558057467, 2495.0144692846316, 2498.487809998955, 2505.5991041433376, 2104.442322784463, 2173.8260939124834, 2431.9513609727796, 2508.285138735461, 2517.3580736912063, 2487.7139129000125, 2507.611320245365, 2508.09708504939, 2510.836795608289, 2513.195777351248, 2466.136852101768, 2520.823542352036, 2522.1676846640567, 2522.7477252274625, 2523.2430270918653, 9736.318457707963, 9737.0, 9772.169365603932, 9798.412095274307, 9808.607655693764, 5525.696086715229, 5609.270794796691, 5664.008798460294, 5694.259746233005, 5694.743736531825, 3514.279572687593, 3548.0952357148763, 3657.1405719656127, 3669.9266014679556, 3681.3189559931498, 1263.6082814327588, 1270.8284381608457, 1272.1485726750739, 1277.4379273119798, 1287.4978758284276, 13124.86875131246, 13158.078934474686, 13159.144655597356, 13163.60509184726, 13227.801582976263, 4749.881252968635, 4880.194767863334, 4896.706197628154, 4979.875503112378, 5035.7985680572365, 11109.222354435273, 11110.61112861062, 11117.888821111846, 11144.164187685956, 11169.609063682732, 5698.401667824902, 5714.342850572154, 5716.685582293014, 5732.627379220421, 5733.254676891977, 5084.949150508448, 5214.869628259248, 5223.059849227099, 5225.895482090337, 5262.3158989331905, 4293.592108749664, 4376.212281789309, 4415.050764085737, 4521.796519156645, 4534.727916325031, 1321.1148530484597, 1341.6779972806455, 1344.9731005379838, 1356.5998030581002, 1402.0255922134222, 1075.5160177919968, 1086.9510872010778, 1096.3750662122513, 1098.8132017557034, 1103.5199688135526, 6461.515386346043, 6469.870602587922, 6593.637349945799, 6721.7311307548125, 6771.4582833373215, 488.6372066569373, 492.09945799187403, 495.3016247091584, 503.5166240409248, 504.2285303485673, 4498.572635599613, 4551.2717965126085, 4632.119897219518, 4704.388429504211, 4717.339572459859, 11826.34955077479, 12203.328816915155, 12295.69260768488, 12309.26144431337, 12315.568955086708, 11328.207025508136, 11741.823872641917, 11814.704632384259, 12041.217320874115, 12179.147459677915, 8696.608652610648, 8804.8679269811, 8874.245689116497, 8886.37795354319, 8891.377603567687, 6723.798286051427, 7033.859322813412, 7041.3662770348865, 7078.716851325889, 7096.7516136934955, 11547.8267825981, 11846.585369512026, 11847.585334513251, 11855.407229638657, 11864.288142711464, 11553.942230288794, 11570.652880413601, 11780.352080635304, 11895.940520297343, 11978.46096925623, 8624.873207501998, 8719.346049046473, 8758.12418758119, 8760.824783504167, 8825.02924678272, 9145.63417463294, 9216.262698983932, 9275.953620231856, 9304.0, 9306.767330816783, 8052.597370131587, 8155.79610509742, 8313.750587482386, 8366.832663346577, 8403.915960840557, 8179.754607361789, 8327.0, 8343.95828020856, 8349.373796965374, 8436.789080273042, 7745.186755390603, 7865.4494185406475, 7959.044914610284, 8147.75556733299, 8150.673973041012, 5642.266505354278, 5835.44563266489, 5879.059350503899, 5928.525717942468, 5929.199558059607, 5897.5576831736935, 5934.584579079423, 5940.198073260054, 6029.75880964774, 6066.636001839904], "value": 4986.534535915741, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2959052304868, 352.68963312285183, 356.3656691089858, 359.123738079087, 360.4143267190815, 5585.385607583163, 5641.943580564198, 5660.565956638644, 5675.687837168949, 5682.545396368291, 5592.6085174037735, 5620.46605572471, 5633.380328163909, 5666.235058267132, 5666.376698563155, 3981.606437746794, 4054.351303791394, 4322.913541729164, 4355.303151495761, 4387.341898715186, 4338.240807858628, 4348.695591308594, 4359.324304732771, 4360.956390436087, 4376.343548467724, 4336.566343365654, 4340.348947657861, 4354.3250796126495, 4356.324769660708, 4357.433533640622, 4118.217538667644, 4254.149170165948, 4362.978185109086, 4370.907273181705, 4383.824647014148, 3885.2812229737588, 3889.961100388988, 3897.2984862724697, 3900.2589507993675, 3903.512060992376, 5466.289382380305, 5474.534664553517, 5487.6981766002855, 5493.818828951795, 5508.394076651565, 4862.902741945177, 4956.521739130435, 4960.925586116211, 4984.800607975713, 5027.597792176617, 5778.797742079007, 5787.594868359217, 5791.623544469596, 5802.912956305659, 5814.97092514539, 4182.937255941163, 4223.282042052859, 4253.766042867641, 4265.360195970629, 4276.422682937795, 5828.562857785683, 6046.2744470663365, 6055.273367195965, 6066.0, 6085.7828434313315, 4323.092150648399, 4326.524082350938, 4332.393464914914, 4332.545082766327, 4349.608535231844, 4417.955820441819, 4426.402435671143, 4427.358033085249, 4439.600435960779, 4450.310201918708, 4794.496577859344, 4946.5053494650965, 5003.199488081889, 5013.543363751808, 5028.622853286018, 5850.3272123705465, 5869.706514674334, 5887.381824908409, 5900.734466948998, 5930.169776231331, 1425.4583258361756, 1476.6898951220364, 1490.180400779567, 1496.6707324388617, 1503.3310176971142, 935.7320830275015, 964.1756298364797, 967.8983706710835, 980.764616492051, 999.5651891427252, 5583.743657676991, 5647.921882574007, 5682.204491371212, 5721.854304635762, 5730.484256416859, 6045.6699526104185, 6052.425019623129, 6055.000924847439, 6068.4538391544975, 6071.483923866433, 5510.559155267569, 5708.828735137952, 5710.943475457054, 5711.771529138871, 5717.885642287131, 3177.237463008861, 3201.6798320167795, 3210.293735378213, 3215.5980502437196, 3218.9839050804594, 6141.170941922784, 6192.535559832941, 6281.461042044728, 6297.614524804536, 6364.809055728336, 6113.441072526566, 6135.944458606327, 6136.6011209271255, 6150.200473938449, 6189.690515474208, 6240.095186197976, 6249.031400132987, 6260.686965651699, 6269.65516896566, 6281.780137695158, 5777.653340799566, 5996.220491336273, 6024.246969128859, 6059.363766804422, 6067.696615169136, 5962.9105563415715, 5988.760449581968, 5995.0, 6006.729697163551, 6009.626197651815, 6022.45797878193, 6167.784127555514, 6178.320384757763, 6287.2140982377205, 6310.400511951358, 3819.6371344722206, 3821.808909554567, 3852.460655508175, 3887.1253967857006, 3906.706996975295, 5360.973195133999, 5394.406615272238, 5445.482679145475, 5457.918131227955, 5485.286912701324, 5259.974305010486, 5269.868253293698, 5532.86167845791, 5937.732802024006, 5955.374685657944, 5882.764689412375, 5895.793647222326, 5922.703864806656, 5925.703714814156, 5930.094253815686, 5492.35229431161, 5860.824175274749, 5944.821655350346, 5945.127284905241, 5957.702114894151, 5044.167712327425, 5924.822255332347, 5963.224780778471, 5966.403359663999, 5968.791092311922, 5815.336338106966, 5832.679202643895, 5879.000569903042, 5947.434993675594, 5960.612560183574], "value": 4453.482704051225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.64110432514, 872.7120050383375, 893.3746377535723, 904.0642934562729, 919.3151102428692, 13984.0, 14333.354999025043, 14364.784528232085, 14374.856251437483, 14736.189509576969, 14995.47515836946, 15115.319810608522, 15125.470608528703, 15133.470328538504, 15144.318505667245, 714.7248309400892, 837.6816809612354, 857.7684025313162, 863.201538576817, 864.3906046237419, 829.8298848735994, 859.2052351574769, 861.3066481482405, 864.8313578852126, 865.0614083719172, 908.7682640926588, 911.2163539356148, 911.3711539038076, 911.5715613661569, 911.7492689510404, 730.7917243585553, 903.5346796399859, 903.8101998580306, 904.6064961741663, 904.900460949295, 645.5803727577113, 660.0594153331483, 665.4809248785955, 665.7436886798617, 666.8366250268667, 13421.66445838852, 13495.797563036562, 13529.120607160503, 13556.389962451713, 13625.0, 14818.777718334233, 15414.383424663, 15984.360625574964, 15991.040537567784, 16080.437184698596, 14315.055144394857, 14471.42114315426, 14545.27273636314, 14552.781708274384, 14633.634159146, 4640.396748422717, 4784.043191361741, 4790.760461976887, 4852.878678033077, 4914.459409464956, 89166.55416722875, 89166.55416722875, 89206.5539672304, 89580.55209723975, 89744.5512772432, 12353.752924941451, 12463.937680311541, 12844.229346239255, 13074.477020919247, 13230.07389482727, 23180.76819231786, 23181.188658396874, 23445.88277058604, 23492.647610285858, 23533.411664708176, 8909.821803563893, 9086.86369704455, 9368.203702685349, 9398.906010939803, 9486.33595648298, 4914.680545764585, 5129.769160387791, 5220.817271395483, 5270.683758974474, 5332.0402327581405, 3728.925421491555, 3861.092643228839, 3867.0, 3867.439221312894, 3872.5933776953016, 649.4187702006677, 651.8598501322239, 658.9439897608758, 674.2684187656448, 676.2730065179853, 602.295314482057, 662.0797092042066, 668.6489592963733, 722.9132504099437, 749.775067479754, 9791.118799308097, 10070.748231294276, 10146.188304935587, 10164.136048435817, 10818.188635852342, 582.9562782791223, 667.276005534001, 724.7789424225662, 744.4528271720282, 790.4940837863771, 4640.65195110368, 4813.927791083137, 4918.680285781485, 4922.906453894728, 4960.330355402047, 98510.50744746231, 102586.0, 135531.64468355387, 135766.32116839552, 138439.0, 62562.0, 73001.6349918247, 100085.0, 101016.49491752642, 102269.48865255626, 54901.72549137309, 76761.0, 89983.55008225048, 91088.0, 93127.06872931319, 9531.666391676536, 9868.654597089067, 9900.108990190918, 10935.398553079656, 11443.599474018369, 54046.729766350916, 54065.18902216391, 54952.450475494734, 55544.44455555393, 57244.13779311101, 80155.0, 81309.59345203236, 81351.59324203341, 81360.0, 81479.0, 12384.504619815107, 12581.496740130293, 12601.873981260436, 12805.0, 12805.487780488676, 13960.930195348958, 14047.297635118408, 14257.928710356382, 14453.710925781215, 14513.05665131763, 12789.744205116032, 13194.0, 13520.12119212248, 13542.864571354161, 13558.661033474242, 12301.876981230072, 12883.291418971672, 12909.289989050692, 13049.934750326187, 13238.53665121716, 13127.540536081191, 13146.0, 13243.536476223286, 13318.733625327246, 13417.530386436429, 10078.496075196357, 10191.592336306465, 10230.437325947145, 10297.279190456597, 10731.463426828472, 11114.77440754524, 11392.25950313227, 11401.144914131308, 11457.942710286728, 11474.483648236017], "value": 7840.338482783604, "unit": "score"}], "name": "dromaeo_css", "value": 7557.935537855416, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 108.0, 110.0, 111.0, 109.0, 109.0, 110.0, 109.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 117.0, 163.0, 118.0, 119.0, 142.0, 116.0, 155.0, 115.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 159.0, 159.0, 160.0, 161.0, 160.0, 160.0, 160.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 118.0, 74.0, 76.0, 73.0, 75.0, 126.0, 74.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 121.0, 81.0, 83.0, 82.0, 115.0, 82.0, 83.0, 122.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "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, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 51.0, 51.0, 52.0, 51.0, 53.0, 53.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 73.0, 82.0, 74.0, 72.0, 72.0, 81.0, 72.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 117.0, 116.0, 118.0, 119.0, 124.0, 119.0, 157.0, 120.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 153.0, 150.0, 153.0, 155.0, 153.0, 155.0, 156.0, 151.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 61.0, 62.0, 61.0, 60.0, 60.0, 59.0, 59.0], "value": 60.7, "unit": "score"}], "name": "kraken", "value": 1457.4, "alertThreshold": 2.0}]} 19:34:28 INFO - Return code: 0 19:34:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:34:28 INFO - # TBPL SUCCESS # 19:34:28 INFO - Running post-action listener: _resource_record_post_action 19:34:28 INFO - [mozharness: 2016-08-13 02:34:28.602283Z] Finished run-tests step (success) 19:34:28 INFO - Running post-run listener: _resource_record_post_run 19:34:28 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 329355264; Read time: 260; Write time: 235468 19:34:28 INFO - TinderboxPrint: CPU usage
12.9% 19:34:28 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 260 19:34:28 INFO - TinderboxPrint: I/O write bytes / time
329,355,264 / 235,468 19:34:28 INFO - TinderboxPrint: CPU idle
8,262.3 (87.1%) 19:34:28 INFO - TinderboxPrint: CPU user
1,184.9 (12.5%) 19:34:28 INFO - TinderboxPrint: Swap in / out
0 / 0 19:34:28 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78348288; Read time: 0; Write time: 45816 19:34:28 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250109952; Read time: 260; Write time: 189628 19:34:29 INFO - Running post-run listener: _upload_blobber_files 19:34:29 INFO - Blob upload gear active. 19:34:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:34:29 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 19:34:29 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'] 19:34:29 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 19:34:29 INFO - (blobuploader) - INFO - Open directory for files ... 19:34:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:30 INFO - (blobuploader) - INFO - Done attempting. 19:34:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:31 INFO - (blobuploader) - INFO - Done attempting. 19:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:34:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:35 INFO - (blobuploader) - INFO - Done attempting. 19:34:35 INFO - (blobuploader) - INFO - Iteration through files over. 19:34:35 INFO - Return code: 0 19:34:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:34:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:34:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802140c935e297ec0ee6cefa901ebf5d8a5df53d071b94362abb952ae0d61c37051edb83da452866c2cbd53ea4fe45610561fa57dc8cdff791efab2bbe801280", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcce1c5042896e48e0beb53962292669b0b7c93d0165b981f390789712baa09827e13e79bdc9537e1204e7e9de70f2d4c8c5f8a5e9c1c194076948430c877650", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ec0f3b2242e7d5c81744e593e1a44610c7f6ba99efa290a34e6caefcd1e0a1b1be3c111bf511f04bd33ce3c4fbd31aec858fab083f4d665c040064f58186344"} 19:34:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:34:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:34:35 INFO - Contents: 19:34:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802140c935e297ec0ee6cefa901ebf5d8a5df53d071b94362abb952ae0d61c37051edb83da452866c2cbd53ea4fe45610561fa57dc8cdff791efab2bbe801280", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcce1c5042896e48e0beb53962292669b0b7c93d0165b981f390789712baa09827e13e79bdc9537e1204e7e9de70f2d4c8c5f8a5e9c1c194076948430c877650", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ec0f3b2242e7d5c81744e593e1a44610c7f6ba99efa290a34e6caefcd1e0a1b1be3c111bf511f04bd33ce3c4fbd31aec858fab083f4d665c040064f58186344"} 19:34:35 INFO - Running post-run listener: copy_logs_to_upload_dir 19:34:35 INFO - Copying logs to upload dir... 19:34:35 INFO - mkdir: /builds/slave/test/build/upload/logs 19:34:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.914623 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-12 19:34:34.831347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:34:34.834656) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 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/802140c935e297ec0ee6cefa901ebf5d8a5df53d071b94362abb952ae0d61c37051edb83da452866c2cbd53ea4fe45610561fa57dc8cdff791efab2bbe801280", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcce1c5042896e48e0beb53962292669b0b7c93d0165b981f390789712baa09827e13e79bdc9537e1204e7e9de70f2d4c8c5f8a5e9c1c194076948430c877650", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ec0f3b2242e7d5c81744e593e1a44610c7f6ba99efa290a34e6caefcd1e0a1b1be3c111bf511f04bd33ce3c4fbd31aec858fab083f4d665c040064f58186344"} build_url:https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/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/802140c935e297ec0ee6cefa901ebf5d8a5df53d071b94362abb952ae0d61c37051edb83da452866c2cbd53ea4fe45610561fa57dc8cdff791efab2bbe801280", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcce1c5042896e48e0beb53962292669b0b7c93d0165b981f390789712baa09827e13e79bdc9537e1204e7e9de70f2d4c8c5f8a5e9c1c194076948430c877650", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ec0f3b2242e7d5c81744e593e1a44610c7f6ba99efa290a34e6caefcd1e0a1b1be3c111bf511f04bd33ce3c4fbd31aec858fab083f4d665c040064f58186344"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:34:34.863122) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:34:34.863505) ========= 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=4ac8d263c7293b36a1be1aed000001be-1471053995.992679-1112686171 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:34:34.948623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:34:34.949550) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:34:34.950375) ========= ========= Total master_lag: 0.19 =========