builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1471518328.69 results: success (0) buildid: 20160817221914 builduid: 8a42d84b4c664f7f898715475fc754b5 revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.686292) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.686639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.686906) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.832372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.832615) ========= 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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 04:05:29-- 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% 223M=0s 2016-08-18 04:05:29 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130477 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.002832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.003097) ========= 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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017961 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.066810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.067103) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d23f68f5cb77934e673700bfaaaf607893a2df9b --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 d23f68f5cb77934e673700bfaaaf607893a2df9b --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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 04:05:29,755 truncating revision to first 12 chars 2016-08-18 04:05:29,755 Setting DEBUG logging. 2016-08-18 04:05:29,756 attempt 1/10 2016-08-18 04:05:29,756 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d23f68f5cb77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 04:05:30,077 unpacking tar archive at: mozilla-aurora-d23f68f5cb77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.701366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.791295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.791686) ========= script_repo_revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.792053) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.792325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.804773) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 13 secs) (at 2016-08-18 04:05:29.805028) ========= /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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:30 INFO - MultiFileLogger online at 20160818 04:05:30 in /builds/slave/test 04:05:30 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 04:05:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:30 INFO - {'append_to_log': False, 04:05:30 INFO - 'base_work_dir': '/builds/slave/test', 04:05:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:05:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:30 INFO - 'branch': 'Mozilla-Aurora', 04:05:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:30 INFO - 'config_files': ('talos/linux_config.py',), 04:05:30 INFO - 'default_actions': ('clobber', 04:05:30 INFO - 'read-buildbot-config', 04:05:30 INFO - 'download-and-extract', 04:05:30 INFO - 'populate-webroot', 04:05:30 INFO - 'create-virtualenv', 04:05:30 INFO - 'install', 04:05:30 INFO - 'run-tests'), 04:05:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:30 INFO - 'download_minidump_stackwalk': True, 04:05:30 INFO - 'download_symbols': 'ondemand', 04:05:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:05:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:05:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:05:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:05:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:30 INFO - 'installer_path': 'installer.exe', 04:05:30 INFO - 'log_level': 'info', 04:05:30 INFO - 'log_name': 'talos', 04:05:30 INFO - 'log_to_console': True, 04:05:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:30 INFO - 'opt_config_files': (), 04:05:30 INFO - 'pip_index': False, 04:05:30 INFO - 'sps_profile': False, 04:05:30 INFO - 'sps_profile_interval': 0, 04:05:30 INFO - 'suite': 'dromaeojs', 04:05:30 INFO - 'system_bits': '32', 04:05:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:30 INFO - 'title': 'talos-linux64-ix-043', 04:05:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:30 INFO - 'use_talos_json': True, 04:05:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:30 INFO - 'work_dir': 'build'} 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.584531Z] Running clobber step. 04:05:30 INFO - Running pre-action listener: _resource_record_pre_action 04:05:30 INFO - Running main action method: clobber 04:05:30 INFO - rmtree: /builds/slave/test/build 04:05:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.045623Z] Finished clobber step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.045724Z] Running read-buildbot-config step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: read_buildbot_config 04:05:31 INFO - Using buildbot properties: 04:05:31 INFO - { 04:05:31 INFO - "project": "", 04:05:31 INFO - "product": "firefox", 04:05:31 INFO - "script_repo_revision": "production", 04:05:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:05:31 INFO - "repository": "", 04:05:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:05:31 INFO - "buildid": "20160817221914", 04:05:31 INFO - "pgo_build": "True", 04:05:31 INFO - "basedir": "/builds/slave/test", 04:05:31 INFO - "buildnumber": 689, 04:05:31 INFO - "slavename": "talos-linux64-ix-043", 04:05:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:05:31 INFO - "platform": "ubuntu64_hw", 04:05:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:05:31 INFO - "branch": "mozilla-aurora", 04:05:31 INFO - "repo_path": "releases/mozilla-aurora", 04:05:31 INFO - "revision": "d23f68f5cb77934e673700bfaaaf607893a2df9b", 04:05:31 INFO - "stage_platform": "linux64-pgo", 04:05:31 INFO - "builduid": "8a42d84b4c664f7f898715475fc754b5", 04:05:31 INFO - "slavebuilddir": "test" 04:05:31 INFO - } 04:05:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:05:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.048179Z] Finished read-buildbot-config step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.048268Z] Running download-and-extract step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: download_and_extract 04:05:31 INFO - mkdir: /builds/slave/test/build/tests 04:05: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')]} 04:05:31 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:05:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:05:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:05:31 INFO - retry: Failed, sleeping 30 seconds before retrying 04:06:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:06:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:06:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:06:01 INFO - retry: Failed, sleeping 60 seconds before retrying 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:01 INFO - Downloaded 1361 bytes. 04:07:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - Using the following test package requirements: 04:07:01 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:07:01 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:07:01 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'jsshell-linux-x86_64.zip'], 04:07:01 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:07:01 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:07:01 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:07:01 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:07:01 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:07:01 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:07:01 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')]} 04:07:01 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:07:01 INFO - retry: Failed, sleeping 30 seconds before retrying 04:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:07:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:07:31 INFO - retry: Failed, sleeping 60 seconds before retrying 04:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:08:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:08:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 04:08:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:32 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:08:35 INFO - Downloaded 21766304 bytes. 04:08:35 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'] 04:08:35 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 04:08:36 INFO - Return code: 0 04:08:36 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 04:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:36 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:08:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:36 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:09:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:09:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:06 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - Caught exception: HTTP Error 404: Not Found 04:10:06 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:06 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:07 INFO - Downloaded 21766304 bytes. 04:10:07 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'] 04:10:07 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 04:10:08 INFO - Return code: 0 04:10:08 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')]} 04:10:08 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:10:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:08 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:10:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:38 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:11:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - Caught exception: HTTP Error 404: Not Found 04:11:38 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:38 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:11:39 INFO - Downloaded 14413723 bytes. 04:11:39 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'] 04:11:39 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 04:11:40 INFO - Return code: 0 04:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:40 INFO - https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:11:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:11:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:11:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:40 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:12:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:12:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:10 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:13:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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! 04:13:10 INFO - Caught exception: HTTP Error 404: Not Found 04:13:10 INFO - Caught exception: HTTP Error 404: Not Found 04:13:10 INFO - Caught exception: HTTP Error 404: Not Found 04:13:10 INFO - trying https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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 04:13:16 INFO - Downloaded 63746489 bytes. 04:13:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:16 INFO - mkdir: /builds/slave/test/properties 04:13:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:16 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:16 INFO - Contents: 04:13:16 INFO - build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:16 INFO - Running post-action listener: _resource_record_post_action 04:13:16 INFO - Running post-action listener: set_extra_try_arguments 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.791701Z] Finished download-and-extract step (success) 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.791936Z] Running populate-webroot step. 04:13:16 INFO - Running pre-action listener: _resource_record_pre_action 04:13:16 INFO - Running main action method: populate_webroot 04:13:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:16 INFO - '--tppagecycles', 04:13:16 INFO - '7'], 04:13:16 INFO - 'tests': ['tsvgm']}}, 04:13:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['damp', 'tps']}, 04:13:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['damp', 'tps']}, 04:13:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['dromaeo_dom']}, 04:13:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['basic_compositor_video']}, 04:13:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['a11yr', 04:13:16 INFO - 'ts_paint', 04:13:16 INFO - 'tpaint', 04:13:16 INFO - 'sessionrestore', 04:13:16 INFO - 'sessionrestore_no_auto_restore', 04:13:16 INFO - 'tabpaint']}, 04:13:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tsvgx', 04:13:16 INFO - 'tsvgr_opacity', 04:13:16 INFO - 'tart', 04:13:16 INFO - 'tscrollx', 04:13:16 INFO - 'cart']}, 04:13:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:16 INFO - 'tsvgr_opacity', 04:13:16 INFO - 'tart', 04:13:16 INFO - 'tscrollx', 04:13:16 INFO - 'cart']}, 04:13:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s'], 04:13:16 INFO - 'tests': ['tp5o']}, 04:13:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'tests': ['tp5o']}, 04:13:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--disable-e10s', 04:13:16 INFO - '--xperf_path', 04:13:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:16 INFO - 'tests': ['tp5n']}, 04:13:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:16 INFO - 'talos_options': ['--xperf_path', 04:13:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:16 INFO - 'tests': ['tp5n']}}, 04:13:16 INFO - 'talos.zip': {'path': '', 04:13:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:16 INFO - Running post-action listener: _resource_record_post_action 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.814556Z] Finished populate-webroot step (success) 04:13:16 INFO - [mozharness: 2016-08-18 11:13:16.814646Z] Running create-virtualenv step. 04:13:16 INFO - Running pre-action listener: _resource_record_pre_action 04:13:16 INFO - Running main action method: create_virtualenv 04:13:16 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:16 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 04:13:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:16 INFO - Using real prefix '/usr' 04:13:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:17 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:19 INFO - Installing pip.................done. 04:13:19 INFO - Return code: 0 04:13:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:19 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 04:13:19 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 04:13:19 INFO - Using env: {'DISPLAY': ':0', 04:13:19 INFO - 'HOME': '/home/cltbld', 04:13:19 INFO - 'LANG': 'en_US.UTF-8', 04:13:19 INFO - 'LANGUAGE': 'en_US:en', 04:13:19 INFO - 'LOGNAME': 'cltbld', 04:13:19 INFO - 'MAIL': '/var/mail/cltbld', 04:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:19 INFO - 'MOZ_NO_REMOTE': '1', 04:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:19 INFO - 'NO_EM_RESTART': '1', 04:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:19 INFO - 'PWD': '/builds/slave/test', 04:13:19 INFO - 'SHELL': '/bin/bash', 04:13:19 INFO - 'SHLVL': '1', 04:13:19 INFO - 'TERM': 'linux', 04:13:19 INFO - 'TMOUT': '86400', 04:13:19 INFO - 'USER': 'cltbld', 04:13:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:19 INFO - '_': '/tools/buildbot/bin/python'} 04:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:19 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 04:13:19 INFO - Cleaning up... 04:13:19 INFO - Return code: 0 04:13:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:19 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:19 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 04:13:19 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 04:13:19 INFO - Using env: {'DISPLAY': ':0', 04:13:19 INFO - 'HOME': '/home/cltbld', 04:13:19 INFO - 'LANG': 'en_US.UTF-8', 04:13:19 INFO - 'LANGUAGE': 'en_US:en', 04:13:19 INFO - 'LOGNAME': 'cltbld', 04:13:19 INFO - 'MAIL': '/var/mail/cltbld', 04:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:19 INFO - 'MOZ_NO_REMOTE': '1', 04:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:19 INFO - 'NO_EM_RESTART': '1', 04:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:19 INFO - 'PWD': '/builds/slave/test', 04:13:19 INFO - 'SHELL': '/bin/bash', 04:13:19 INFO - 'SHLVL': '1', 04:13:19 INFO - 'TERM': 'linux', 04:13:19 INFO - 'TMOUT': '86400', 04:13:19 INFO - 'USER': 'cltbld', 04:13:19 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:19 INFO - '_': '/tools/buildbot/bin/python'} 04:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:19 INFO - Downloading/unpacking psutil>=3.1.1 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:19 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 04:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:22 INFO - Installing collected packages: psutil 04:13:22 INFO - Running setup.py install for psutil 04:13:22 INFO - building 'psutil._psutil_linux' extension 04:13: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 04:13: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 04:13:22 INFO - building 'psutil._psutil_posix' extension 04:13: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 04:13:23 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 04:13:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:23 INFO - Successfully installed psutil 04:13:23 INFO - Cleaning up... 04:13:23 INFO - Return code: 0 04:13:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13: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 04:13: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 04:13:23 INFO - Using env: {'DISPLAY': ':0', 04:13:23 INFO - 'HOME': '/home/cltbld', 04:13:23 INFO - 'LANG': 'en_US.UTF-8', 04:13:23 INFO - 'LANGUAGE': 'en_US:en', 04:13:23 INFO - 'LOGNAME': 'cltbld', 04:13:23 INFO - 'MAIL': '/var/mail/cltbld', 04:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:23 INFO - 'MOZ_NO_REMOTE': '1', 04:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:23 INFO - 'NO_EM_RESTART': '1', 04:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:23 INFO - 'PWD': '/builds/slave/test', 04:13:23 INFO - 'SHELL': '/bin/bash', 04:13:23 INFO - 'SHLVL': '1', 04:13:23 INFO - 'TERM': 'linux', 04:13:23 INFO - 'TMOUT': '86400', 04:13:23 INFO - 'USER': 'cltbld', 04:13:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:23 INFO - '_': '/tools/buildbot/bin/python'} 04:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:25 INFO - Installing collected packages: mozsystemmonitor 04:13:25 INFO - Running setup.py install for mozsystemmonitor 04:13:25 INFO - Successfully installed mozsystemmonitor 04:13:25 INFO - Cleaning up... 04:13:25 INFO - Return code: 0 04:13:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13: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 04:13: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 04:13:25 INFO - Using env: {'DISPLAY': ':0', 04:13:25 INFO - 'HOME': '/home/cltbld', 04:13:25 INFO - 'LANG': 'en_US.UTF-8', 04:13:25 INFO - 'LANGUAGE': 'en_US:en', 04:13:25 INFO - 'LOGNAME': 'cltbld', 04:13:25 INFO - 'MAIL': '/var/mail/cltbld', 04:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:25 INFO - 'MOZ_NO_REMOTE': '1', 04:13:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:25 INFO - 'NO_EM_RESTART': '1', 04:13:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:25 INFO - 'PWD': '/builds/slave/test', 04:13:25 INFO - 'SHELL': '/bin/bash', 04:13:25 INFO - 'SHLVL': '1', 04:13:25 INFO - 'TERM': 'linux', 04:13:25 INFO - 'TMOUT': '86400', 04:13:25 INFO - 'USER': 'cltbld', 04:13:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:25 INFO - '_': '/tools/buildbot/bin/python'} 04:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:25 INFO - Downloading/unpacking jsonschema==2.5.1 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:28 INFO - warning: no files found matching '*.txt' 04:13:28 INFO - no previously-included directories found matching 'build' 04:13:28 INFO - no previously-included directories found matching 'dist' 04:13:28 INFO - no previously-included directories found matching '.git*' 04:13:28 INFO - Installing collected packages: jsonschema, functools32 04:13:28 INFO - Running setup.py install for functools32 04:13:28 INFO - warning: no files found matching '*.txt' 04:13:28 INFO - no previously-included directories found matching 'build' 04:13:28 INFO - no previously-included directories found matching 'dist' 04:13:28 INFO - no previously-included directories found matching '.git*' 04:13:28 INFO - Successfully installed jsonschema functools32 04:13:28 INFO - Cleaning up... 04:13:28 INFO - Return code: 0 04:13:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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', '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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:13: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 functools32==3.2.3-2 04:13:28 INFO - Using env: {'DISPLAY': ':0', 04:13:28 INFO - 'HOME': '/home/cltbld', 04:13:28 INFO - 'LANG': 'en_US.UTF-8', 04:13:28 INFO - 'LANGUAGE': 'en_US:en', 04:13:28 INFO - 'LOGNAME': 'cltbld', 04:13:28 INFO - 'MAIL': '/var/mail/cltbld', 04:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:28 INFO - 'MOZ_NO_REMOTE': '1', 04:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:28 INFO - 'NO_EM_RESTART': '1', 04:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:28 INFO - 'PWD': '/builds/slave/test', 04:13:28 INFO - 'SHELL': '/bin/bash', 04:13:28 INFO - 'SHLVL': '1', 04:13:28 INFO - 'TERM': 'linux', 04:13:28 INFO - 'TMOUT': '86400', 04:13:28 INFO - 'USER': 'cltbld', 04:13:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:28 INFO - '_': '/tools/buildbot/bin/python'} 04:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:28 INFO - Cleaning up... 04:13:28 INFO - Return code: 0 04:13:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13: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 04:13: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 04:13:28 INFO - Using env: {'DISPLAY': ':0', 04:13:28 INFO - 'HOME': '/home/cltbld', 04:13:28 INFO - 'LANG': 'en_US.UTF-8', 04:13:28 INFO - 'LANGUAGE': 'en_US:en', 04:13:28 INFO - 'LOGNAME': 'cltbld', 04:13:28 INFO - 'MAIL': '/var/mail/cltbld', 04:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:28 INFO - 'MOZ_NO_REMOTE': '1', 04:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:28 INFO - 'NO_EM_RESTART': '1', 04:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:28 INFO - 'PWD': '/builds/slave/test', 04:13:28 INFO - 'SHELL': '/bin/bash', 04:13:28 INFO - 'SHLVL': '1', 04:13:28 INFO - 'TERM': 'linux', 04:13:28 INFO - 'TMOUT': '86400', 04:13:28 INFO - 'USER': 'cltbld', 04:13:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:28 INFO - '_': '/tools/buildbot/bin/python'} 04:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:28 INFO - Downloading/unpacking blobuploader==1.2.4 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:30 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 INFO - Downloading docopt-0.6.1.tar.gz 04:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:31 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:31 INFO - Running setup.py install for blobuploader 04:13:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:31 INFO - Running setup.py install for requests 04:13:32 INFO - Running setup.py install for docopt 04:13:32 INFO - Successfully installed blobuploader requests docopt 04:13:32 INFO - Cleaning up... 04:13:32 INFO - Return code: 0 04:13:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:32 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:32 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 04:13:32 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 04:13:32 INFO - Using env: {'DISPLAY': ':0', 04:13:32 INFO - 'HOME': '/home/cltbld', 04:13:32 INFO - 'LANG': 'en_US.UTF-8', 04:13:32 INFO - 'LANGUAGE': 'en_US:en', 04:13:32 INFO - 'LOGNAME': 'cltbld', 04:13:32 INFO - 'MAIL': '/var/mail/cltbld', 04:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:32 INFO - 'MOZ_NO_REMOTE': '1', 04:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:32 INFO - 'NO_EM_RESTART': '1', 04:13:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:32 INFO - 'PWD': '/builds/slave/test', 04:13:32 INFO - 'SHELL': '/bin/bash', 04:13:32 INFO - 'SHLVL': '1', 04:13:32 INFO - 'TERM': 'linux', 04:13:32 INFO - 'TMOUT': '86400', 04:13:32 INFO - 'USER': 'cltbld', 04:13:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:32 INFO - '_': '/tools/buildbot/bin/python'} 04:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:32 INFO - Running setup.py (path:/tmp/pip-BmeVo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:32 INFO - Running setup.py (path:/tmp/pip-XXYSpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:32 INFO - Running setup.py (path:/tmp/pip-nWhgyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:32 INFO - Running setup.py (path:/tmp/pip-253PU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:32 INFO - Running setup.py (path:/tmp/pip-PR38Mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:32 INFO - Running setup.py (path:/tmp/pip-oItPe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:32 INFO - Running setup.py (path:/tmp/pip-f1_98F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:32 INFO - Running setup.py (path:/tmp/pip-ca8sWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:33 INFO - Running setup.py (path:/tmp/pip-E4XY7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:33 INFO - Running setup.py (path:/tmp/pip-y8gnDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:33 INFO - Running setup.py (path:/tmp/pip-6PHaiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:33 INFO - Running setup.py (path:/tmp/pip-ziPeE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:33 INFO - Running setup.py (path:/tmp/pip-YhmP6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:33 INFO - Running setup.py (path:/tmp/pip-kPQRLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:33 INFO - Running setup.py (path:/tmp/pip-wwGFmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:33 INFO - Running setup.py (path:/tmp/pip-eELNHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:33 INFO - Running setup.py (path:/tmp/pip-6dLagw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:33 INFO - Running setup.py install for manifestparser 04:13:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozcrash 04:13:33 INFO - Running setup.py install for mozdebug 04:13:33 INFO - Running setup.py install for mozdevice 04:13:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozfile 04:13:34 INFO - Running setup.py install for mozhttpd 04:13:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozinfo 04:13:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozInstall 04:13:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozleak 04:13:34 INFO - Running setup.py install for mozlog 04:13:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for moznetwork 04:13:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:34 INFO - Running setup.py install for mozprocess 04:13:34 INFO - Running setup.py install for mozprofile 04:13:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:35 INFO - Running setup.py install for mozrunner 04:13:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:35 INFO - Running setup.py install for mozscreenshot 04:13:35 INFO - Running setup.py install for moztest 04:13:35 INFO - Running setup.py install for mozversion 04:13:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:35 INFO - Cleaning up... 04:13:35 INFO - Return code: 0 04:13:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:35 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:35 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 04:13:35 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 04:13:35 INFO - Using env: {'DISPLAY': ':0', 04:13:35 INFO - 'HOME': '/home/cltbld', 04:13:35 INFO - 'LANG': 'en_US.UTF-8', 04:13:35 INFO - 'LANGUAGE': 'en_US:en', 04:13:35 INFO - 'LOGNAME': 'cltbld', 04:13:35 INFO - 'MAIL': '/var/mail/cltbld', 04:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:35 INFO - 'MOZ_NO_REMOTE': '1', 04:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:35 INFO - 'NO_EM_RESTART': '1', 04:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:35 INFO - 'PWD': '/builds/slave/test', 04:13:35 INFO - 'SHELL': '/bin/bash', 04:13:35 INFO - 'SHLVL': '1', 04:13:35 INFO - 'TERM': 'linux', 04:13:35 INFO - 'TMOUT': '86400', 04:13:35 INFO - 'USER': 'cltbld', 04:13:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:35 INFO - '_': '/tools/buildbot/bin/python'} 04:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:35 INFO - Running setup.py (path:/tmp/pip-UcNUO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13: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)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:35 INFO - Running setup.py (path:/tmp/pip-bApWjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13: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)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:35 INFO - Running setup.py (path:/tmp/pip-qY47ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13: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)) 04:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:35 INFO - Running setup.py (path:/tmp/pip-0Aa89o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:36 INFO - Running setup.py (path:/tmp/pip-ZklkmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:36 INFO - Running setup.py (path:/tmp/pip-uXloAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:36 INFO - Running setup.py (path:/tmp/pip-RBTC7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:36 INFO - Running setup.py (path:/tmp/pip-llc3Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:36 INFO - Running setup.py (path:/tmp/pip-Rk8cZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:36 INFO - Running setup.py (path:/tmp/pip-M_wSp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:36 INFO - Running setup.py (path:/tmp/pip-fEVuDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:36 INFO - Running setup.py (path:/tmp/pip-gp6O2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:36 INFO - Running setup.py (path:/tmp/pip-sAvX1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:36 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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:36 INFO - Running setup.py (path:/tmp/pip-uAAjld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13: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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:36 INFO - Running setup.py (path:/tmp/pip-cTQmIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13: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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:36 INFO - Running setup.py (path:/tmp/pip-K3phhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13: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)) 04:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:36 INFO - Running setup.py (path:/tmp/pip-mTxLSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13: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)) 04:13: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)) 04:13: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)) 04:13: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)) 04:13: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)) 04:13: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)) 04:13:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:38 INFO - Downloading blessings-1.6.tar.gz 04:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:38 INFO - Installing collected packages: blessings 04:13:38 INFO - Running setup.py install for blessings 04:13:39 INFO - Successfully installed blessings 04:13:39 INFO - Cleaning up... 04:13:39 INFO - Return code: 0 04:13:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:39 INFO - Reading from file tmpfile_stdout 04:13:39 INFO - Current package versions: 04:13:39 INFO - argparse == 1.2.1 04:13:39 INFO - blessings == 1.6 04:13:39 INFO - blobuploader == 1.2.4 04:13:39 INFO - docopt == 0.6.1 04:13:39 INFO - functools32 == 3.2.3-2 04:13:39 INFO - jsonschema == 2.5.1 04:13:39 INFO - manifestparser == 1.1 04:13:39 INFO - mozInstall == 1.12 04:13:39 INFO - mozcrash == 0.17 04:13:39 INFO - mozdebug == 0.1 04:13:39 INFO - mozdevice == 0.48 04:13:39 INFO - mozfile == 1.2 04:13:39 INFO - mozhttpd == 0.7 04:13:39 INFO - mozinfo == 0.9 04:13:39 INFO - mozleak == 0.1 04:13:39 INFO - mozlog == 3.3 04:13:39 INFO - moznetwork == 0.27 04:13:39 INFO - mozprocess == 0.23 04:13:39 INFO - mozprofile == 0.28 04:13:39 INFO - mozrunner == 6.12 04:13:39 INFO - mozscreenshot == 0.1 04:13:39 INFO - mozsystemmonitor == 0.3 04:13:39 INFO - moztest == 0.7 04:13:39 INFO - mozversion == 1.4 04:13:39 INFO - psutil == 3.1.1 04:13:39 INFO - requests == 1.2.3 04:13:39 INFO - wsgiref == 0.1.2 04:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:39 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')]} 04:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 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')]} 04:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:39 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 04:13:39 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 04:13:39 INFO - Using env: {'DISPLAY': ':0', 04:13:39 INFO - 'HOME': '/home/cltbld', 04:13:39 INFO - 'LANG': 'en_US.UTF-8', 04:13:39 INFO - 'LANGUAGE': 'en_US:en', 04:13:39 INFO - 'LOGNAME': 'cltbld', 04:13:39 INFO - 'MAIL': '/var/mail/cltbld', 04:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:39 INFO - 'MOZ_NO_REMOTE': '1', 04:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:39 INFO - 'NO_EM_RESTART': '1', 04:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:39 INFO - 'PWD': '/builds/slave/test', 04:13:39 INFO - 'SHELL': '/bin/bash', 04:13:39 INFO - 'SHLVL': '1', 04:13:39 INFO - 'TERM': 'linux', 04:13:39 INFO - 'TMOUT': '86400', 04:13:39 INFO - 'USER': 'cltbld', 04:13:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:39 INFO - '_': '/tools/buildbot/bin/python'} 04:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 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)) 04:13:39 INFO - Cleaning up... 04:13:39 INFO - Return code: 0 04:13:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:39 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')]} 04:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 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')]} 04:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:39 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 0x1a95030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d56180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, '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': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', '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 04:13:39 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 04:13:39 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 04:13:39 INFO - Using env: {'DISPLAY': ':0', 04:13:39 INFO - 'HOME': '/home/cltbld', 04:13:39 INFO - 'LANG': 'en_US.UTF-8', 04:13:39 INFO - 'LANGUAGE': 'en_US:en', 04:13:39 INFO - 'LOGNAME': 'cltbld', 04:13:39 INFO - 'MAIL': '/var/mail/cltbld', 04:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:39 INFO - 'MOZ_NO_REMOTE': '1', 04:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:39 INFO - 'NO_EM_RESTART': '1', 04:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:39 INFO - 'PWD': '/builds/slave/test', 04:13:39 INFO - 'SHELL': '/bin/bash', 04:13:39 INFO - 'SHLVL': '1', 04:13:39 INFO - 'TERM': 'linux', 04:13:39 INFO - 'TMOUT': '86400', 04:13:39 INFO - 'USER': 'cltbld', 04:13:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:39 INFO - '_': '/tools/buildbot/bin/python'} 04:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:39 INFO - Cleaning up... 04:13:39 INFO - Return code: 0 04:13:39 INFO - Running post-action listener: _resource_record_post_action 04:13:39 INFO - Running post-action listener: _start_resource_monitoring 04:13:39 INFO - Starting resource monitoring. 04:13:39 INFO - [mozharness: 2016-08-18 11:13:39.743822Z] Finished create-virtualenv step (success) 04:13:39 INFO - [mozharness: 2016-08-18 11:13:39.744254Z] Running install step. 04:13:39 INFO - Running pre-action listener: _resource_record_pre_action 04:13:39 INFO - Running main action method: install 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:39 INFO - Reading from file tmpfile_stdout 04:13:39 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:40 INFO - Reading from file tmpfile_stdout 04:13:40 INFO - Output received: 04:13:40 INFO - Usage: mozinstall [options] installer 04:13:40 INFO - Options: 04:13:40 INFO - -h, --help show this help message and exit 04:13:40 INFO - -d DEST, --destination=DEST 04:13:40 INFO - Directory to install application into. [default: 04:13:40 INFO - "/builds/slave/test"] 04:13:40 INFO - --app=APP Application being installed. [default: firefox] 04:13:40 INFO - mkdir: /builds/slave/test/build/application 04:13:40 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'] 04:13:40 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 04:13:56 INFO - Reading from file tmpfile_stdout 04:13:56 INFO - Output received: 04:13:56 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:56 INFO - Running post-action listener: _resource_record_post_action 04:13:56 INFO - [mozharness: 2016-08-18 11:13:56.023453Z] Finished install step (success) 04:13:56 INFO - [mozharness: 2016-08-18 11:13:56.023737Z] Running run-tests step. 04:13:56 INFO - Running pre-action listener: _resource_record_pre_action 04:13:56 INFO - Running main action method: run_tests 04:13:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:56 INFO - Python 2.7.3 04:13:56 INFO - Return code: 0 04:13:56 INFO - grabbing minidump binary from tooltool 04:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:13:56 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 0x1d543b0>, '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 0x1d552b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d55740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:56 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 04:13:56 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 04:13:56 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 04:13:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:56 INFO - Return code: 0 04:13:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13: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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 04:13: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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 04:13:56 INFO - Using env: {'DISPLAY': ':0', 04:13:56 INFO - 'HOME': '/home/cltbld', 04:13:56 INFO - 'LANG': 'en_US.UTF-8', 04:13:56 INFO - 'LANGUAGE': 'en_US:en', 04:13:56 INFO - 'LOGNAME': 'cltbld', 04:13:56 INFO - 'MAIL': '/var/mail/cltbld', 04:13:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:56 INFO - 'MOZ_NO_REMOTE': '1', 04:13:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:56 INFO - 'NO_EM_RESTART': '1', 04:13:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:56 INFO - 'PWD': '/builds/slave/test', 04:13:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:56 INFO - 'SHELL': '/bin/bash', 04:13:56 INFO - 'SHLVL': '1', 04:13:56 INFO - 'TERM': 'linux', 04:13:56 INFO - 'TMOUT': '86400', 04:13:56 INFO - 'USER': 'cltbld', 04:13:56 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471517521.797594-550918150', 04:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:56 INFO - '_': '/tools/buildbot/bin/python'} 04:13: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/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 04:13:56 INFO - mozversion application_buildid: 20160817221914 04:13:56 INFO - mozversion application_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 04:13:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:56 INFO - mozversion application_name: Firefox 04:13:56 INFO - mozversion application_remotingname: firefox-dev 04:13:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:56 INFO - mozversion application_vendor: Mozilla 04:13:56 INFO - mozversion application_version: 50.0a2 04:13:56 INFO - mozversion platform_buildid: 20160817221914 04:13:56 INFO - mozversion platform_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 04:13:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:56 INFO - mozversion platform_version: 50.0a2 04:13:56 INFO - using testdate: 1471518836 04:13:56 INFO - actual date: 1471518836 04:13:56 INFO - starting webserver on 'localhost:44386' 04:13:56 INFO - SUITE-START | Running 2 tests 04:13:56 INFO - TEST-START | dromaeo_css 04:13:56 INFO - operating with platform_type : linux_ 04:13:56 INFO - Initialising browser for dromaeo_css test... 04:13:56 INFO - TEST-INFO | started process 22957 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzC88BX/profile http://localhost:44386/getInfo.html) 04:14:00 INFO - PROCESS | 22957 | __metrics Screen width/height:1600/1200 04:14:00 INFO - PROCESS | 22957 | colorDepth:24 04:14:00 INFO - PROCESS | 22957 | Browser inner width/height: 1024/697 04:14:00 INFO - PROCESS | 22957 | __metrics 04:14:01 INFO - PROCESS | 22957 | 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" 04:14:01 INFO - PROCESS | 22957 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:02 INFO - TEST-INFO | 22957: exit 0 04:14:02 INFO - Browser initialized. 04:14:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:02 INFO - TEST-INFO | started process 23047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzC88BX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:14 INFO - PROCESS | 23047 | RSS: Main: 145072128 04:14:14 INFO - PROCESS | 23047 | 04:15:04 INFO - PROCESS | 23047 | 1471518904916 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] 04:17:14 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44386/tests/dromaeo/cssquery-ext.html) 04:17:15 INFO - PROCESS | 23047 | RSS: Main: 274341888 04:17:15 INFO - PROCESS | 23047 | 04:20:17 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-ext.html (next: http://localhost:44386/tests/dromaeo/cssquery-jquery.html) 04:20:18 INFO - PROCESS | 23047 | RSS: Main: 234688512 04:20:18 INFO - PROCESS | 23047 | 04:23:20 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44386/tests/dromaeo/cssquery-mootools.html) 04:23:21 INFO - PROCESS | 23047 | RSS: Main: 174284800 04:23:21 INFO - PROCESS | 23047 | 04:26:23 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44386/tests/dromaeo/cssquery-prototype.html) 04:26:23 INFO - PROCESS | 23047 | RSS: Main: 172949504 04:26:23 INFO - PROCESS | 23047 | 04:29:26 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44386/tests/dromaeo/cssquery-yui.html) 04:29:26 INFO - PROCESS | 23047 | RSS: Main: 175595520 04:29:26 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | Cycle 1(1): loaded http://localhost:44386/tests/dromaeo/cssquery-yui.html (next: http://localhost:44386/tests/dromaeo/cssquery-dojo.html) 04:32:29 INFO - PROCESS | 23047 | RSS: Main: 262365184 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | __start_tp_report 04:32:29 INFO - PROCESS | 23047 | _x_x_mozilla_page_load 04:32:29 INFO - PROCESS | 23047 | _x_x_mozilla_page_load_details 04:32:29 INFO - PROCESS | 23047 | |i|pagename|runs| 04:32:29 INFO - PROCESS | 23047 | |0;dojo.html;3240.481522956327;3242.351529694062;3250.0412378348383;3260.75544334175;3298.0930244182846;5605.803796867111;5650.74571644276;5689.0044242257645;5697.145428185774;5710.942890571093;5709.2292540507015;5792.275965504312;5815.941840581593;5820.447057529538;5821.941780582193;4944.87637809054;5033.949660503403;5249.028929648009;5252.579793616521;5303.124984377573;5075.873103172432;5124.8975020499565;5127.410347809993;5130.07658621448;5131.409887862887;5125.923111153317;5165.354330708661;5206.739663016853;5217.895642087156;5219.164933610623;5271.341082364705;5310.4424035476495;5311.2298716686255;5315.388730296027;5341.813036543741;5001.099802035632;5030.748462576893;5070.543651071422;5214.139666954948;5215.009148261818;5577.358603760538;5601.495865372136;5604.85987850307;5630.268065151545;5639.661620302795;6157.076438534255;6167.044108163242;6167.352427995041;6168.352323006109;6168.629882207082;5918.674472903988;6066.696665166724;6074.696265186767;6085.6348619083;6206.968965155145;5739.053056245714;5750.913736293959;5760.222369980041;5761.48146666802;5768.567357448208;6217.813465596039;6222.315545290015;6233.625982441068;6234.0337247726675;6249.562530622813;5775.249217601685;5950.821475355746;5998.400159983967;6004.159417681528;6069.908951365733;6096;6100.664463454553;6102.474381404649;6115.440562656494;6137.438273608292;6038.245219347582;6094.634321940698;6110.694465276719;6111.083337499411;6129.295131059896;5837.6497410155525;6005.219321488266;6084.330723620398;6085.6348619083;6137.447629713347;4610.74640894754;4644.326572646947;4651.604613607881;4654.883627909262;4736.242201247784;4148.377743338463;4167.91664166715;4173.227952828675;4186.979065104716;4192.224438478891;5560.582956278296;5574.777008919597;5737.454941780524;5786.132080188006;5817.854553636193;6163.506919446433;6165.8458538536;6173.043178307369;6191.380861913773;6191.504679625709;5849.853753656193;5851.561132915049;5852.385499522489;5859.033259512218;5873.588848780544;4977.278294647328;5110.821121260738;5132.666376685504;5149.510796474329;5160.587153027749;6382.297947225708;6428.646424446607;6440.806775796734;6456.838579035561;6457.225132984072;6152.692365381802;6226.750929962751;6236.563440559117;6239.06414037898;6250.406211409909;6063.5452341073715;6192.411720886508;6260.123582698335;6432.388923052303;6441.806745797634;6014.939850601438;6036.3058248302;6049.485793707584;6072.483838873745;6083.66539840313;6130.540209484396;6142.9078563820685;6147.293061297829;6147.754089836357;6155.415235552616;5734;5948.613340132878;6000.519958403404;6014.1580178775885;6016.157737916607;5930.555208359304;5989.910151347646;5995.430434108753;6011.308699499614;6019.398060193946;5900.5574581907385;5948.196993405835;5972.970135149297;5985.341612422543;5986.9401305986385;5860.355360910211;5879.794207202727;5894.528437725057;6039.547033972557;6069.92965214414;5826.388229235869;5832.825015249549;5840.182374467663;5852.122181672783;5872.354041055566 04:32:29 INFO - PROCESS | 23047 | |1;ext.html;604.0456079394554;646.5603389695007;661.9735210591575;671.9596824190548;684.4182444921814;34608;36978.26043479132;46761.5323846761;47454;47480.91374060683;47641.28538071932;48218.03563928719;48263;48288.758556207256;48302.51697483024;8527.275181609539;9222.584983675717;9374.765630859214;9374.906250937505;9376.343655944052;10967.780644387107;11188.496517656726;11215.102791776679;11574.594889178881;11581.826272605875;11429.942850285737;11525.769484610302;11527.596534121307;11530.135239857002;11538.307701537893;11171.273867198646;11217.102631789438;11256.718582035433;11304.660860174208;11310.830337544903;11109.44452777358;11183.217174797768;11197.272177308449;11197.44012799365;11205.719857003558;43474.56525434737;43568.564314356765;44008.779956100334;44037;44060;3897.2984862724697;3957.3668213086057;4088.6729061674996;4409.735415875058;4774.164521208774;34494;34573.13567160841;34682;34837;34878.12804679878;4728.739919304437;4744.051189762062;4765.928511072337;4788.11419887322;4795.328653988445;363112;363957;364317;365283.17358413304;366377;61039.38960610426;61273.38726612677;61403.692981534805;61424.69287653533;61439;39104.2179156421;39789;39983.20033599312;42266;64484;19398.224071037283;19522.609547809247;19562.70655940162;19569.8043019568;19576.216951322072;13204.141730787467;13326.267055312052;13562.93218533901;14972.850271497146;15088.094714317176;20653.79346206549;20991;21019;21025.78974210269;21126.366209013755;7364.374028207662;7368.926310736932;7563;7698.230176982257;7720.266574675396;1069.652362982034;1097.9451027448756;1106.8837772033821;1131.6944425005202;1196.3121205306809;35270.47094293588;35287.64712352895;35614.46578301328;35651.82174089113;35772.46341304883;1152.9020033297263;1165.0213820390743;1171.94140292985;1176.4294317256124;1292.6445227562494;18303.72544411835;18403.72394414085;18476.630467390576;18561.5359616008;18693;647726;647870;652437;655976;662874;43141.784291078344;43581.12837743227;43581.56418435839;43603.99999999937;43696;1357.4027427931678;1452.1287227663317;1455.7379671658991;1461.9561413157621;1499.197929107922;35010.474842876865;35666.286674266885;36652;36752;36920.26159476742;62823.05765413431;63628;63876;63961.36038639554;63976.68011659912;629693;629753;634695;634950;635987;30325.241868953453;30496.390072197988;31252.37495250037;32608.34783304368;48658.75670621624;53010.734946326564;53454;53904.19143712926;54076.45923540714;54169.187462187314;41857.58142418659;45670;46222.53777462317;46911.29633055575;47427;44525.33212001758;46314.53685463102;46385.30422043739;47066.29400559063;47476.287855681505;43522.78238608786;44109;44203;44431.55568444274;44481.11037779291;38315;39523;40215;40577.59422405738;40978.385324220755;30412.5438118424;30479.238019049702;30666.693333067276;30744.538831917092;30975.84512077425 04:32:29 INFO - PROCESS | 23047 | |2;jquery.html;402.65170627407264;407.9612436818503;420.8821529971608;422.74846466352517;434.726122542798;15614.609634759137;20230.29193978211;20550.48623784404;20803.583928321423;21210.46973825655;20696.379108626767;21126.788732112713;21378.144874205012;21547.569048619018;21618.351449456502;5698.43015698431;6051.334353221142;6121.418465245817;6121.632702037869;6153.723082461278;4433.4901486329;6041;6053;6505.0892874997535;6721.2270588882175;6808.6936087876165;6819.897701534456;6827.453803695693;6830.214525329601;6830.214525329601;7397.371223445986;7705.614719264015;7708.614569271514;7710;7720.498167619086;7088.929110708878;7392.223816499244;7427.888581671279;7440.0327957365735;7496.587687677175;23070.307890763303;27206.5919011213;29777.553336699966;29811.552826707615;29878;2726.768224700883;2749.6150538924567;2804.761595264405;3072.5391191321482;3110.0514343125337;16767.748483772753;16873.746893796604;16940;17705.38031168903;18372.816271837244;2163.102312540294;2310.191432998437;2317.6986991690974;2373.4185124644573;2412.360724408033;95844.52077739636;103061.99999999924;103833;107282;109189.45405272923;18077.01152873183;18401.619878508896;18562.44312670622;18809.435716928514;19738.40784776459;35231.82384088063;35239.647603523634;35396.92935312421;35453.64546354555;35635.28729425397;10662.893371066346;11221.438928053727;11228.438578071064;11232.775344493064;11451.942740286246;9466.242700583936;9528.523573821281;9552.37909535878;9704.417734935927;9735.853962190573;9462;9702;9757.560909758937;10006.79986400268;10018.64934727281;3337.666233376643;3379.6113446953423;3393.8642454301553;3751.2872554214614;3759.6240375962734;978.9363691360039;1061.7292590389402;1095.2990086344746;1126.8760436351995;1148.1675785055768;20799.8960005199;20931.581368372546;21276.468088297916;21387.25144619931;21749.565008699738;1035.808375450529;1050.894910508943;1098.4782228441427;1105.1545567640762;1128.8588926384202;11418.771624567627;11772.411379430994;11840.763184736257;11844.644660660195;11890.464929078213;477277;483835;485949;491860;492257;89558.55220723855;93964.53017734867;98105;101153.49423252983;101715.49142254241;1020.8264595018865;1037.2842738510403;1049;1064.1593141418193;1109.578360223126;19118.617627647647;19339.709904351166;19390.515237119183;19424.223031079164;19538.21847126156;47029.764851175525;47525.01920716752;48008;48179;50102;64196;65330.346696534325;65455.34544654683;67165.32834671786;67868;9029.548522573714;9099.681511147079;9215.539223038955;9219.354645174773;9328.95335523318;10024.699259022242;13583.52457663977;13851.792223116863;13952.16287022782;14152.78770818459;12526.37368131608;12791.80812287835;12817.9745620352;12856.614301570968;12884.549040783528;12020.158588898694;12141.453634586289;12339.321337326533;12498.187617804813;12800.551980680631;8733.606987685442;8998.280137588847;9610.135087842127;12887;13063.085584009028;13054.216746995211;13443.46226150943;14081.08472949255;14169.079009864327;14286.928565357106;6527.67361631926;10305.793884122426;11463.426828658701;13096.148750331198;13658.590242292747 04:32:29 INFO - PROCESS | 23047 | |3;mootools.html;1525.1459182857598;1591.1964457948736;1768.469459162251;1878.840298574621;1911.9808801911977;9159.129882661146;9947.253955953314;9947.701568952925;9949.950250248758;9951.353162044463;8427.83144337113;9056;9411.764705882357;9413.058694130583;9699.757506062351;2500.9124680636182;2530.633058206559;2533.1640558615613;2541.0598078710905;2545.071049067096;2497.7876880465183;2565.2047865161767;2569.139338321655;2589.3267750384944;2591.1708253358934;2521.621756736486;2523.129520315488;2570.7043689975612;2570.910018149356;2578.265194419591;2512;2525.6716626838393;2531.936701582457;2538.5430622487943;2539.733328000561;2431.3921519620094;2496.712878019033;2506.486170335077;2508.4983003399393;2511.0458025950206;9825.508724563742;9907.108360247541;9921.801563968753;9927.305088643798;9942.403455792675;5718.199452076671;5916;5929.851753706192;5933.080372542263;5934.228550288478;3540.398132317512;3672.1737609037755;3676.613955534684;3699.741018128732;3708.406654935224;1209.1789943777621;1263.2925561685395;1269.5302737986913;1274.7832868412393;1287.2405280884323;13077.673058173526;13396.93301533486;13487.055906086578;13738.587842364745;13879.167249965034;4961.404631444249;4970.826021089317;5069.569086627678;5085.949140508547;5137.5376216139975;10870.836937445945;10877.456127193609;10879.238453308351;10881.727956801144;10888.891111088946;5869.8239052828485;5873.295204575478;5880.323762767251;5884.117382392676;5887.0580707086665;4535.931961020588;4625.884352891205;5365.141577347606;5402.108652072364;5420.376656684453;4188.225178342016;4228.619424251832;4307.590778876001;4339.7396156230725;4352.695311328177;1279.993600031994;1314.8750868667462;1322.8875545578733;1325.224199572558;1328.1632571479906;952.5713428956997;1006.335818359879;1061.1298735037196;1063.31416236527;1069.6149386220955;6180.6291622502795;6400.167978162809;6428.742850286029;6602.537822352389;6702.296258892844;467.2150786678345;475.9286107083965;478.5095277340684;485.7911098227796;492.46567474290794;4297.441332626739;4400.295952647625;4411.250087485135;4491.236489796743;4534.79593418297;11825.940870295593;12383.814242786366;12400.503979840705;12408.937955310166;12421.627351179479;11058.668239952814;12280.324582147889;12318.076144289214;12320.137590368588;12337.321447320304;8676.262517686067;9075.047120052432;9093.090690930987;9104.271658267322;9122.86315705265;6468.2561505427475;6486.318936511599;6550.377714117151;6610.702518386588;6744.494162937701;11215.327080374876;11296.209265351676;11307.660770176908;11402.258853174517;11431;11596.188266821242;11770.705732356759;11803.645890623295;11851.111166662708;11866.525338986345;8089.676412943416;8247.835043299221;8310.83378332442;8500.064992850905;8639;9586.952065239908;9639.277054221102;9646.372985755903;9653.855192171983;9661.855072173783;8354.62404191825;8424.873626895478;8490.15098490146;8512.40413171072;8633.568321584022;8240.381971352053;8328.208820162075;8382.706605268786;8427;8496.447730897471;7605.6957721692725;7681.69273229087;7720.382369410321;7725.961370193113;7812.687492500464;5617.185508101385;5768.077107662753;5786.218860453785;5852.824415267549;5883.117532370179;5637.46444087811;5731.19763233156;5757.97121014409;5875.794347197828;5901.498372638373 04:32:29 INFO - PROCESS | 23047 | |4;prototype.html;349.5944704143193;350.43404901084745;357.3442732585705;359.56133517109134;362.34777400678786;5571.387147413781;5634.4928956393915;5676.8580785480335;5692.975264452397;5701.230333904921;5552.77788888445;5553.139263414178;5568.276124103871;5583.5254003409655;5619.831405057845;3889.44422231107;4145.440365550649;4237.457525484715;4387.583179597949;4404.537523560028;4387.539308372623;4413.205622987861;4425.181341451826;4443;4451.243288640922;4197.8530751423705;4377.934330985037;4383.698558256909;4419.580139886705;4436.64506839452;4272.393839757745;4326.6754993375625;4356.128774245132;4365.454318210223;4376.627986621141;3872.612738726133;3879.9806000969816;3889.144388234584;3890.9416358754643;3890.9883430308323;5256.106461901448;5437.764530104581;5451.672899626035;5455.781768729246;5458.9454105458835;4969.304297398367;4978.203487442028;5004.199328107522;5009.874753131165;5044.672096313765;5809.854753631151;5817.4002149408825;5838.883222335529;5851.678157701324;5879.823605291848;3982.123932734794;4275.508316543606;4285.849995250152;4307.375430562551;4339.891502712426;5801.244408905736;5880.764769409261;5968.22413086296;6031.039912028608;6037.637741735466;4284.464441944757;4290.970167159921;4293.2701440755145;4301.569843015736;4309.439772829513;4344.174606824693;4345.326474396474;4356.847510337187;4357.193919124972;4374.431323927869;4861.565838077776;4925.482824303468;5008.499150084962;5022.573081288131;5026.371703537058;5850.12248162779;5883.264591926009;5886.05823068307;5895.64626122434;5941.435563621449;1485.7028594281187;1494.5068127517893;1507.758758598619;1514.2655811931204;1515.8181018277658;908.5457271364318;948.8434408322549;960.9231261499064;965.6282331302499;966.4442945306477;4999.325091112726;5049.8737531562;5053.140966035783;5074.2895994560795;5122.437656514724;5287.339082614673;5289.307421625043;5293.708846013429;5303.575713942875;5317.813876514303;5014.548690617789;5030.7171671223605;5046.561729907033;5292.708901010405;5687.573431992617;3154.7381047581152;3187.9362412751616;3191.96261215106;3203.2312245061016;3211.0527394418705;6124.601900876429;6256.374362563707;6266.279377871512;6281.96859015702;6288.119663247149;6082.60463069899;6092.603980741238;6104.694765261719;6111.51107911366;6136.294326152461;6229.065640153923;6233.189685340967;6238.532110091761;6279.937200627935;6287.528435367274;5815.244018277597;5991.262533865304;6006.369331220334;6018.3379828217985;6070.423309785563;5941.37615550361;5948.583599148191;5959.28488581373;5972.641641501524;5990.430909063632;5958.851028724316;6040.939590604038;6129.84675383119;6183.474404675653;6237.1891654084675;4262.7016108872085;4280.871573852789;4281.91436171281;4282.678799090143;4298.785060746887;5816.36020037804;6028;6046.848828779316;6055.333913269449;6070.969645151746;5829.571754920072;5848.473637372657;5861.706914654335;5861.824145275648;5868.2077919480325;5824.359320474659;5838.32859221195;5861.413858614275;5864.765409383747;5880.558958078247;5524.58565607573;5807.448292412214;5838.912416313844;5841.948449279171;5870.26621672291;5920.585559010828;5929.644221346561;5937.6046629043;5938.2280303560265;5948;5616.354119276336;5772.249607550992;5824.301083869793;5827.737751801264;5830.5044071254415 04:32:29 INFO - PROCESS | 23047 | |5;yui.html;839.46274384394;857.4041041476173;905.9184673379397;926.1201858234671;939.4785893828924;14772.482963096294;15901.284442200114;15901.840981590181;15926.84073159268;16031.919840400797;15290;15387.69224615507;15425.614359641015;15426.074435533876;15433.92283038586;735.7204262380288;883.8541640629288;887.3344991256573;905.266733945503;912.9543522823866;894.5929602031093;898.6360523987795;899.9775005624879;901.2519608724742;902.5261737587754;946.1720994130136;946.6544711180404;948.9715308540755;950.3727539823718;950.6245033211877;781.8553567589986;919.7148883846029;938.1275413865137;948.7628092976756;950.2493030505892;695.8086526205334;696.9024336592881;698.1587187439123;698.6925752668809;698.6995591895483;15848.366065357372;15889.841101588952;15898.125603091941;15899;15904.443344483003;15067.4726384576;15085.849141508665;15367.769483457758;15526.068435893769;15755.2122393881;13643.454261829515;13692.383842727102;13734.519291824838;13911.234882081482;14278;4746.074515469485;4815.49437309081;4845.248986407078;4935.333729946447;4936.456989731127;84697;85164;88040.55979720125;92004;94941.5252923731;12492.875071249171;12515;12596.307203103734;12731.299778512272;13003.609891703263;24680.629790553157;24745.505089898463;24759.62860557093;24802.875985619958;24829.751702482743;9225.861612075823;9309.767255818659;9491.715248542554;9541.952290238505;9615.278854085971;5121.359830021247;5130.666506677054;5142.100132476801;5213.218017297436;5285.630005899627;3587.51568538244;3678.6137455567314;3703.6481534254203;3708.295423869456;3727.683146932487;659.5646873063755;666.7099811581977;674.254948282151;677.654396257912;680.251723104581;759.5404780108065;761.1285078584989;768.2240936654019;771.8842173673881;778.992210077892;9530.714078577654;10348.637797677196;10490.580376784996;10558.894411055791;10923.726906827234;727.3490226247479;735.8197241675716;748.7566540874129;769.9884501732479;772.2817779465039;4918.926216106761;4938.950610493849;4973.502649734997;5009.173486374781;5031.77357018935;111913;123418.38290808488;124274;124659;124851;68171.83414269704;112246.43876780564;114963.42518287522;116163.41918290354;116386;95654;98485;99616;100273;102568;9476;9562.713118606453;9696.806063878825;9703.708888733348;9732.41605503672;56750.43249567451;59572.70213648904;59882.40117598768;60448.697756510934;60639;83775.16224837674;84892;86001;86260;89358.55320723355;13024.934875325942;13123.60629181126;13374.264415456853;13510.324483775576;13630.182189068688;13730.588082357146;13732.382042807898;13932.58202253934;13942.651433714238;14183.14901105937;12925.741485170432;13074.738505229652;13708.3831227593;13833.170009799444;14068.859311406755;13377.732445351234;13569.592912212649;13649.522266721015;14128.717425651224;14475;12534.247945123321;12631.242125472501;12664.683382915133;13196.60410187696;13681.179129252278;10592.364458132537;10881.891181088087;10883.455827208767;11018.173636977419;11293.774124517628;10989.78723827632;11799.292042477478;12120.696982575506;12293;12333.50665973351 04:32:29 INFO - PROCESS | 23047 | __end_tp_report 04:32:29 INFO - PROCESS | 23047 | __start_cc_report 04:32:29 INFO - PROCESS | 23047 | _x_x_mozilla_cycle_collect,1105 04:32:29 INFO - PROCESS | 23047 | __end_cc_report 04:32:29 INFO - PROCESS | 23047 | __startTimestamp1471519949549__endTimestamp 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | ------- Summary: start ------- 04:32:29 INFO - PROCESS | 23047 | Number of tests: 6 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#0] dojo.html Cycles:165 Average:5670.08 Median:5872.35 stddev:662.49 (11.3%) stddev-sans-first:636.51 04:32:29 INFO - PROCESS | 23047 | Values: 3240.5 3242.4 3250.0 3260.8 3298.1 5605.8 5650.7 5689.0 5697.1 5710.9 5709.2 5792.3 5815.9 5820.4 5821.9 4944.9 5033.9 5249.0 5252.6 5303.1 5075.9 5124.9 5127.4 5130.1 5131.4 5125.9 5165.4 5206.7 5217.9 5219.2 5271.3 5310.4 5311.2 5315.4 5341.8 5001.1 5030.7 5070.5 5214.1 5215.0 5577.4 5601.5 5604.9 5630.3 5639.7 6157.1 6167.0 6167.4 6168.4 6168.6 5918.7 6066.7 6074.7 6085.6 6207.0 5739.1 5750.9 5760.2 5761.5 5768.6 6217.8 6222.3 6233.6 6234.0 6249.6 5775.2 5950.8 5998.4 6004.2 6069.9 6096.0 6100.7 6102.5 6115.4 6137.4 6038.2 6094.6 6110.7 6111.1 6129.3 5837.6 6005.2 6084.3 6085.6 6137.4 4610.7 4644.3 4651.6 4654.9 4736.2 4148.4 4167.9 4173.2 4187.0 4192.2 5560.6 5574.8 5737.5 5786.1 5817.9 6163.5 6165.8 6173.0 6191.4 6191.5 5849.9 5851.6 5852.4 5859.0 5873.6 4977.3 5110.8 5132.7 5149.5 5160.6 6382.3 6428.6 6440.8 6456.8 6457.2 6152.7 6226.8 6236.6 6239.1 6250.4 6063.5 6192.4 6260.1 6432.4 6441.8 6014.9 6036.3 6049.5 6072.5 6083.7 6130.5 6142.9 6147.3 6147.8 6155.4 5734.0 5948.6 6000.5 6014.2 6016.2 5930.6 5989.9 5995.4 6011.3 6019.4 5900.6 5948.2 5973.0 5985.3 5986.9 5860.4 5879.8 5894.5 6039.5 6069.9 5826.4 5832.8 5840.2 5852.1 5872.4 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#1] ext.html Cycles:180 Average:70808.92 Median:21873.18 stddev:151040.02 (690.5%) stddev-sans-first:151371.75 04:32:29 INFO - PROCESS | 23047 | Values: 604.0 646.6 662.0 672.0 684.4 34608.0 36978.3 46761.5 47454.0 47480.9 47641.3 48218.0 48263.0 48288.8 48302.5 8527.3 9222.6 9374.8 9374.9 9376.3 10967.8 11188.5 11215.1 11574.6 11581.8 11429.9 11525.8 11527.6 11530.1 11538.3 11171.3 11217.1 11256.7 11304.7 11310.8 11109.4 11183.2 11197.3 11197.4 11205.7 43474.6 43568.6 44008.8 44037.0 44060.0 3897.3 3957.4 4088.7 4409.7 4774.2 34494.0 34573.1 34682.0 34837.0 34878.1 4728.7 4744.1 4765.9 4788.1 4795.3 363112.0 363957.0 364317.0 365283.2 366377.0 61039.4 61273.4 61403.7 61424.7 61439.0 39104.2 39789.0 39983.2 42266.0 64484.0 19398.2 19522.6 19562.7 19569.8 19576.2 13204.1 13326.3 13562.9 14972.9 15088.1 20653.8 20991.0 21019.0 21025.8 21126.4 7364.4 7368.9 7563.0 7698.2 7720.3 1069.7 1097.9 1106.9 1131.7 1196.3 35270.5 35287.6 35614.5 35651.8 35772.5 1152.9 1165.0 1171.9 1176.4 1292.6 18303.7 18403.7 18476.6 18561.5 18693.0 647726.0 647870.0 652437.0 655976.0 662874.0 43141.8 43581.1 43581.6 43604.0 43696.0 1357.4 1452.1 1455.7 1462.0 1499.2 35010.5 35666.3 36652.0 36752.0 36920.3 62823.1 63628.0 63876.0 63961.4 63976.7 629693.0 629753.0 634695.0 634950.0 635987.0 30325.2 30496.4 31252.4 32608.3 48658.8 53010.7 53454.0 53904.2 54076.5 54169.2 41857.6 45670.0 46222.5 46911.3 47427.0 44525.3 46314.5 46385.3 47066.3 47476.3 43522.8 44109.0 44203.0 44431.6 44481.1 38315.0 39523.0 40215.0 40577.6 40978.4 30412.5 30479.2 30666.7 30744.5 30975.8 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#2] jquery.html Cycles:180 Average:32041.15 Median:2313.95 stddev:80583.82 (3482.5%) stddev-sans-first:80774.86 04:32:29 INFO - PROCESS | 23047 | Values: 402.7 408.0 420.9 422.7 434.7 15614.6 20230.3 20550.5 20803.6 21210.5 20696.4 21126.8 21378.1 21547.6 21618.4 5698.4 6051.3 6121.4 6121.6 6153.7 4433.5 6041.0 6053.0 6505.1 6721.2 6808.7 6819.9 6827.5 6830.2 6830.2 7397.4 7705.6 7708.6 7710.0 7720.5 7088.9 7392.2 7427.9 7440.0 7496.6 23070.3 27206.6 29777.6 29811.6 29878.0 2726.8 2749.6 2804.8 3072.5 3110.1 16767.7 16873.7 16940.0 17705.4 18372.8 2163.1 2310.2 2317.7 2373.4 2412.4 95844.5 103062.0 103833.0 107282.0 109189.5 18077.0 18401.6 18562.4 18809.4 19738.4 35231.8 35239.6 35396.9 35453.6 35635.3 10662.9 11221.4 11228.4 11232.8 11451.9 9466.2 9528.5 9552.4 9704.4 9735.9 9462.0 9702.0 9757.6 10006.8 10018.6 3337.7 3379.6 3393.9 3751.3 3759.6 978.9 1061.7 1095.3 1126.9 1148.2 20799.9 20931.6 21276.5 21387.3 21749.6 1035.8 1050.9 1098.5 1105.2 1128.9 11418.8 11772.4 11840.8 11844.6 11890.5 477277.0 483835.0 485949.0 491860.0 492257.0 89558.6 93964.5 98105.0 101153.5 101715.5 1020.8 1037.3 1049.0 1064.2 1109.6 19118.6 19339.7 19390.5 19424.2 19538.2 47029.8 47525.0 48008.0 48179.0 50102.0 64196.0 65330.3 65455.3 67165.3 67868.0 9029.5 9099.7 9215.5 9219.4 9329.0 10024.7 13583.5 13851.8 13952.2 14152.8 12526.4 12791.8 12818.0 12856.6 12884.5 12020.2 12141.5 12339.3 12498.2 12800.6 8733.6 8998.3 9610.1 12887.0 13063.1 13054.2 13443.5 14081.1 14169.1 14286.9 6527.7 10305.8 11463.4 13096.1 13658.6 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#3] mootools.html Cycles:180 Average:6379.53 Median:2546.55 stddev:3691.86 (145.0%) stddev-sans-first:3684.20 04:32:29 INFO - PROCESS | 23047 | Values: 1525.1 1591.2 1768.5 1878.8 1912.0 9159.1 9947.3 9947.7 9950.0 9951.4 8427.8 9056.0 9411.8 9413.1 9699.8 2500.9 2530.6 2533.2 2541.1 2545.1 2497.8 2565.2 2569.1 2589.3 2591.2 2521.6 2523.1 2570.7 2570.9 2578.3 2512.0 2525.7 2531.9 2538.5 2539.7 2431.4 2496.7 2506.5 2508.5 2511.0 9825.5 9907.1 9921.8 9927.3 9942.4 5718.2 5916.0 5929.9 5933.1 5934.2 3540.4 3672.2 3676.6 3699.7 3708.4 1209.2 1263.3 1269.5 1274.8 1287.2 13077.7 13396.9 13487.1 13738.6 13879.2 4961.4 4970.8 5069.6 5085.9 5137.5 10870.8 10877.5 10879.2 10881.7 10888.9 5869.8 5873.3 5880.3 5884.1 5887.1 4535.9 4625.9 5365.1 5402.1 5420.4 4188.2 4228.6 4307.6 4339.7 4352.7 1280.0 1314.9 1322.9 1325.2 1328.2 952.6 1006.3 1061.1 1063.3 1069.6 6180.6 6400.2 6428.7 6602.5 6702.3 467.2 475.9 478.5 485.8 492.5 4297.4 4400.3 4411.3 4491.2 4534.8 11825.9 12383.8 12400.5 12408.9 12421.6 11058.7 12280.3 12318.1 12320.1 12337.3 8676.3 9075.0 9093.1 9104.3 9122.9 6468.3 6486.3 6550.4 6610.7 6744.5 11215.3 11296.2 11307.7 11402.3 11431.0 11596.2 11770.7 11803.6 11851.1 11866.5 8089.7 8247.8 8310.8 8500.1 8639.0 9587.0 9639.3 9646.4 9653.9 9661.9 8354.6 8424.9 8490.2 8512.4 8633.6 8240.4 8328.2 8382.7 8427.0 8496.4 7605.7 7681.7 7720.4 7726.0 7812.7 5617.2 5768.1 5786.2 5852.8 5883.1 5637.5 5731.2 5758.0 5875.8 5901.5 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#4] prototype.html Cycles:180 Average:4891.87 Median:5552.96 stddev:1435.45 (25.9%) stddev-sans-first:1398.41 04:32:29 INFO - PROCESS | 23047 | Values: 349.6 350.4 357.3 359.6 362.3 5571.4 5634.5 5676.9 5693.0 5701.2 5552.8 5553.1 5568.3 5583.5 5619.8 3889.4 4145.4 4237.5 4387.6 4404.5 4387.5 4413.2 4425.2 4443.0 4451.2 4197.9 4377.9 4383.7 4419.6 4436.6 4272.4 4326.7 4356.1 4365.5 4376.6 3872.6 3880.0 3889.1 3890.9 3891.0 5256.1 5437.8 5451.7 5455.8 5458.9 4969.3 4978.2 5004.2 5009.9 5044.7 5809.9 5817.4 5838.9 5851.7 5879.8 3982.1 4275.5 4285.8 4307.4 4339.9 5801.2 5880.8 5968.2 6031.0 6037.6 4284.5 4291.0 4293.3 4301.6 4309.4 4344.2 4345.3 4356.8 4357.2 4374.4 4861.6 4925.5 5008.5 5022.6 5026.4 5850.1 5883.3 5886.1 5895.6 5941.4 1485.7 1494.5 1507.8 1514.3 1515.8 908.5 948.8 960.9 965.6 966.4 4999.3 5049.9 5053.1 5074.3 5122.4 5287.3 5289.3 5293.7 5303.6 5317.8 5014.5 5030.7 5046.6 5292.7 5687.6 3154.7 3187.9 3192.0 3203.2 3211.1 6124.6 6256.4 6266.3 6282.0 6288.1 6082.6 6092.6 6104.7 6111.5 6136.3 6229.1 6233.2 6238.5 6279.9 6287.5 5815.2 5991.3 6006.4 6018.3 6070.4 5941.4 5948.6 5959.3 5972.6 5990.4 5958.9 6040.9 6129.8 6183.5 6237.2 4262.7 4280.9 4281.9 4282.7 4298.8 5816.4 6028.0 6046.8 6055.3 6071.0 5829.6 5848.5 5861.7 5861.8 5868.2 5824.4 5838.3 5861.4 5864.8 5880.6 5524.6 5807.4 5838.9 5841.9 5870.3 5920.6 5929.6 5937.6 5938.2 5948.0 5616.4 5772.2 5824.3 5827.7 5830.5 04:32:29 INFO - PROCESS | 23047 | 04:32:29 INFO - PROCESS | 23047 | [#5] yui.html Cycles:180 Average:22798.93 Median:4780.78 stddev:33384.04 (698.3%) stddev-sans-first:33436.97 04:32:29 INFO - PROCESS | 23047 | Values: 839.5 857.4 905.9 926.1 939.5 14772.5 15901.3 15901.8 15926.8 16031.9 15290.0 15387.7 15425.6 15426.1 15433.9 735.7 883.9 887.3 905.3 913.0 894.6 898.6 900.0 901.3 902.5 946.2 946.7 949.0 950.4 950.6 781.9 919.7 938.1 948.8 950.2 695.8 696.9 698.2 698.7 698.7 15848.4 15889.8 15898.1 15899.0 15904.4 15067.5 15085.8 15367.8 15526.1 15755.2 13643.5 13692.4 13734.5 13911.2 14278.0 4746.1 4815.5 4845.2 4935.3 4936.5 84697.0 85164.0 88040.6 92004.0 94941.5 12492.9 12515.0 12596.3 12731.3 13003.6 24680.6 24745.5 24759.6 24802.9 24829.8 9225.9 9309.8 9491.7 9542.0 9615.3 5121.4 5130.7 5142.1 5213.2 5285.6 3587.5 3678.6 3703.6 3708.3 3727.7 659.6 666.7 674.3 677.7 680.3 759.5 761.1 768.2 771.9 779.0 9530.7 10348.6 10490.6 10558.9 10923.7 727.3 735.8 748.8 770.0 772.3 4918.9 4939.0 4973.5 5009.2 5031.8 111913.0 123418.4 124274.0 124659.0 124851.0 68171.8 112246.4 114963.4 116163.4 116386.0 95654.0 98485.0 99616.0 100273.0 102568.0 9476.0 9562.7 9696.8 9703.7 9732.4 56750.4 59572.7 59882.4 60448.7 60639.0 83775.2 84892.0 86001.0 86260.0 89358.6 13024.9 13123.6 13374.3 13510.3 13630.2 13730.6 13732.4 13932.6 13942.7 14183.1 12925.7 13074.7 13708.4 13833.2 14068.9 13377.7 13569.6 13649.5 14128.7 14475.0 12534.2 12631.2 12664.7 13196.6 13681.2 10592.4 10881.9 10883.5 11018.2 11293.8 10989.8 11799.3 12120.7 12293.0 12333.5 04:32:29 INFO - PROCESS | 23047 | -------- Summary: end -------- 04:32:29 INFO - PROCESS | 23047 | 04:32:30 INFO - TEST-INFO | 23047: exit 0 04:32:30 INFO - TEST-OK | dromaeo_css | took 1114010ms 04:32:30 INFO - TEST-START | kraken 04:32:30 INFO - operating with platform_type : linux_ 04:32:30 INFO - Initialising browser for kraken test... 04:32:30 INFO - TEST-INFO | started process 23251 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8J1og9/profile http://localhost:44386/getInfo.html) 04:32:34 INFO - PROCESS | 23251 | __metrics Screen width/height:1600/1200 04:32:34 INFO - PROCESS | 23251 | colorDepth:24 04:32:34 INFO - PROCESS | 23251 | Browser inner width/height: 1024/697 04:32:34 INFO - PROCESS | 23251 | __metrics 04:32:35 INFO - PROCESS | 23251 | 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" 04:32:35 INFO - PROCESS | 23251 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:36 INFO - TEST-INFO | 23251: exit 0 04:32:36 INFO - Browser initialized. 04:32:36 INFO - Running cycle 1/1 for kraken test... 04:32:36 INFO - TEST-INFO | started process 23340 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8J1og9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:48 INFO - PROCESS | 23340 | RSS: Main: 145469440 04:32:48 INFO - PROCESS | 23340 | 04:32:50 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44386/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:51 INFO - PROCESS | 23340 | RSS: Main: 191336448 04:32:51 INFO - PROCESS | 23340 | 04:32:53 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44386/tests/kraken/driver.html?testName=audio-dft) 04:32:54 INFO - PROCESS | 23340 | RSS: Main: 181633024 04:32:54 INFO - PROCESS | 23340 | 04:32:57 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44386/tests/kraken/driver.html?testName=audio-fft) 04:32:57 INFO - PROCESS | 23340 | RSS: Main: 268902400 04:32:57 INFO - PROCESS | 23340 | 04:32:59 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44386/tests/kraken/driver.html?testName=audio-oscillator) 04:33:00 INFO - PROCESS | 23340 | RSS: Main: 174944256 04:33:00 INFO - PROCESS | 23340 | 04:33:02 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44386/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:03 INFO - PROCESS | 23340 | RSS: Main: 176885760 04:33:03 INFO - PROCESS | 23340 | 04:33:06 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44386/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:06 INFO - PROCESS | 23340 | RSS: Main: 212992000 04:33:06 INFO - PROCESS | 23340 | 04:33:10 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44386/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:10 INFO - PROCESS | 23340 | RSS: Main: 211787776 04:33:10 INFO - PROCESS | 23340 | 04:33:13 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44386/tests/kraken/driver.html?testName=json-parse-financial) 04:33:14 INFO - PROCESS | 23340 | RSS: Main: 211771392 04:33:14 INFO - PROCESS | 23340 | 04:33:15 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44386/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:16 INFO - PROCESS | 23340 | RSS: Main: 176463872 04:33:16 INFO - PROCESS | 23340 | 04:33:18 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:18 INFO - PROCESS | 23340 | RSS: Main: 180867072 04:33:18 INFO - PROCESS | 23340 | 04:33:20 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:21 INFO - PROCESS | 23340 | RSS: Main: 185679872 04:33:21 INFO - PROCESS | 23340 | 04:33:23 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:24 INFO - PROCESS | 23340 | RSS: Main: 179920896 04:33:24 INFO - PROCESS | 23340 | 04:33:27 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:27 INFO - PROCESS | 23340 | RSS: Main: 184115200 04:33:27 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | Cycle 1(1): loaded http://localhost:44386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44386/tests/kraken/driver.html?testName=ai-astar) 04:33:29 INFO - PROCESS | 23340 | RSS: Main: 236879872 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | __start_tp_report 04:33:29 INFO - PROCESS | 23340 | _x_x_mozilla_page_load 04:33:29 INFO - PROCESS | 23340 | _x_x_mozilla_page_load_details 04:33:29 INFO - PROCESS | 23340 | |i|pagename|runs| 04:33:29 INFO - PROCESS | 23340 | |0;ai-astar;98;110;110;109;109;109;109;111;108;109 04:33:29 INFO - PROCESS | 23340 | |1;audio-beat-detection;143;115;120;176;120;117;146;115;150;117 04:33:29 INFO - PROCESS | 23340 | |2;audio-dft;161;159;161;161;161;162;160;161;161;160 04:33:29 INFO - PROCESS | 23340 | |3;audio-fft;75;85;77;115;76;76;73;75;103;76 04:33:29 INFO - PROCESS | 23340 | |4;audio-oscillator;83;83;120;83;83;83;129;82;83;127 04:33:29 INFO - PROCESS | 23340 | |5;imaging-gaussian-blur;116;116;117;117;117;117;116;117;116;117 04:33:29 INFO - PROCESS | 23340 | |6;imaging-darkroom;146;146;145;145;146;146;146;145;145;146 04:33:29 INFO - PROCESS | 23340 | |7;imaging-desaturate;95;95;95;94;95;94;94;95;95;94 04:33:29 INFO - PROCESS | 23340 | |8;json-parse-financial;52;50;51;50;50;50;52;50;51;51 04:33:29 INFO - PROCESS | 23340 | |9;json-stringify-tinderbox;59;62;60;59;60;60;60;60;59;59 04:33:29 INFO - PROCESS | 23340 | |10;stanford-crypto-aes;72;73;71;71;74;72;73;72;75;75 04:33:29 INFO - PROCESS | 23340 | |11;stanford-crypto-ccm;119;116;126;124;116;116;116;124;190;119 04:33:29 INFO - PROCESS | 23340 | |12;stanford-crypto-pbkdf2;152;154;152;157;153;153;152;153;157;152 04:33:29 INFO - PROCESS | 23340 | |13;stanford-crypto-sha256-iterative;62;62;63;63;61;63;61;61;60;60 04:33:29 INFO - PROCESS | 23340 | __end_tp_report 04:33:29 INFO - PROCESS | 23340 | __start_cc_report 04:33:29 INFO - PROCESS | 23340 | _x_x_mozilla_cycle_collect,1603 04:33:29 INFO - PROCESS | 23340 | __end_cc_report 04:33:29 INFO - PROCESS | 23340 | __startTimestamp1471520009482__endTimestamp 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | ------- Summary: start ------- 04:33:29 INFO - PROCESS | 23340 | Number of tests: 14 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:3.68 (3.4%) stddev-sans-first:0.87 04:33:29 INFO - PROCESS | 23340 | Values: 98.0 110.0 110.0 109.0 109.0 109.0 109.0 111.0 108.0 109.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:131.50 stddev:20.80 (15.8%) stddev-sans-first:21.67 04:33:29 INFO - PROCESS | 23340 | Values: 143.0 115.0 120.0 176.0 120.0 117.0 146.0 115.0 150.0 117.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 04:33:29 INFO - PROCESS | 23340 | Values: 161.0 159.0 161.0 161.0 161.0 162.0 160.0 161.0 161.0 160.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.00 stddev:14.29 (18.8%) stddev-sans-first:14.86 04:33:29 INFO - PROCESS | 23340 | Values: 75.0 85.0 77.0 115.0 76.0 76.0 73.0 75.0 103.0 76.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:83.00 stddev:20.64 (24.9%) stddev-sans-first:21.38 04:33:29 INFO - PROCESS | 23340 | Values: 83.0 83.0 120.0 83.0 83.0 83.0 129.0 82.0 83.0 127.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:33:29 INFO - PROCESS | 23340 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:33:29 INFO - PROCESS | 23340 | Values: 146.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 146.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:33:29 INFO - PROCESS | 23340 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 94.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.73 04:33:29 INFO - PROCESS | 23340 | Values: 52.0 50.0 51.0 50.0 50.0 50.0 52.0 50.0 51.0 51.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 04:33:29 INFO - PROCESS | 23340 | Values: 59.0 62.0 60.0 59.0 60.0 60.0 60.0 60.0 59.0 59.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:73.00 stddev:1.48 (2.0%) stddev-sans-first:1.54 04:33:29 INFO - PROCESS | 23340 | Values: 72.0 73.0 71.0 71.0 74.0 72.0 73.0 72.0 75.0 75.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:121.50 stddev:22.60 (18.6%) stddev-sans-first:23.81 04:33:29 INFO - PROCESS | 23340 | Values: 119.0 116.0 126.0 124.0 116.0 116.0 116.0 124.0 190.0 119.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:153.00 stddev:1.96 (1.3%) stddev-sans-first:2.00 04:33:29 INFO - PROCESS | 23340 | Values: 152.0 154.0 152.0 157.0 153.0 153.0 152.0 153.0 157.0 152.0 04:33:29 INFO - PROCESS | 23340 | 04:33:29 INFO - PROCESS | 23340 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.17 (1.9%) stddev-sans-first:1.24 04:33:29 INFO - PROCESS | 23340 | Values: 62.0 62.0 63.0 63.0 61.0 63.0 61.0 61.0 60.0 60.0 04:33:29 INFO - PROCESS | 23340 | -------- Summary: end -------- 04:33:29 INFO - PROCESS | 23340 | 04:33:30 INFO - TEST-INFO | 23340: exit 0 04:33:30 INFO - TEST-OK | kraken | took 60143ms 04:33:30 INFO - SUITE-END | took 1174s 04:33:30 INFO - Completed test suite (00:19:34) 04:33:30 INFO - javascript benchmark 04:33:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3240.481522956327, 3242.351529694062, 3250.0412378348383, 3260.75544334175, 3298.0930244182846, 5605.803796867111, 5650.74571644276, 5689.0044242257645, 5697.145428185774, 5710.942890571093, 5709.2292540507015, 5792.275965504312, 5815.941840581593, 5820.447057529538, 5821.941780582193, 4944.87637809054, 5033.949660503403, 5249.028929648009, 5252.579793616521, 5303.124984377573, 5075.873103172432, 5124.8975020499565, 5127.410347809993, 5130.07658621448, 5131.409887862887, 5125.923111153317, 5165.354330708661, 5206.739663016853, 5217.895642087156, 5219.164933610623, 5271.341082364705, 5310.4424035476495, 5311.2298716686255, 5315.388730296027, 5341.813036543741, 5001.099802035632, 5030.748462576893, 5070.543651071422, 5214.139666954948, 5215.009148261818, 5577.358603760538, 5601.495865372136, 5604.85987850307, 5630.268065151545, 5639.661620302795, 6157.076438534255, 6167.044108163242, 6167.352427995041, 6168.352323006109, 6168.629882207082, 5918.674472903988, 6066.696665166724, 6074.696265186767, 6085.6348619083, 6206.968965155145, 5739.053056245714, 5750.913736293959, 5760.222369980041, 5761.48146666802, 5768.567357448208, 6217.813465596039, 6222.315545290015, 6233.625982441068, 6234.0337247726675, 6249.562530622813, 5775.249217601685, 5950.821475355746, 5998.400159983967, 6004.159417681528, 6069.908951365733, 6096.0, 6100.664463454553, 6102.474381404649, 6115.440562656494, 6137.438273608292, 6038.245219347582, 6094.634321940698, 6110.694465276719, 6111.083337499411, 6129.295131059896, 5837.6497410155525, 6005.219321488266, 6084.330723620398, 6085.6348619083, 6137.447629713347, 4610.74640894754, 4644.326572646947, 4651.604613607881, 4654.883627909262, 4736.242201247784, 4148.377743338463, 4167.91664166715, 4173.227952828675, 4186.979065104716, 4192.224438478891, 5560.582956278296, 5574.777008919597, 5737.454941780524, 5786.132080188006, 5817.854553636193, 6163.506919446433, 6165.8458538536, 6173.043178307369, 6191.380861913773, 6191.504679625709, 5849.853753656193, 5851.561132915049, 5852.385499522489, 5859.033259512218, 5873.588848780544, 4977.278294647328, 5110.821121260738, 5132.666376685504, 5149.510796474329, 5160.587153027749, 6382.297947225708, 6428.646424446607, 6440.806775796734, 6456.838579035561, 6457.225132984072, 6152.692365381802, 6226.750929962751, 6236.563440559117, 6239.06414037898, 6250.406211409909, 6063.5452341073715, 6192.411720886508, 6260.123582698335, 6432.388923052303, 6441.806745797634, 6014.939850601438, 6036.3058248302, 6049.485793707584, 6072.483838873745, 6083.66539840313, 6130.540209484396, 6142.9078563820685, 6147.293061297829, 6147.754089836357, 6155.415235552616, 5734.0, 5948.613340132878, 6000.519958403404, 6014.1580178775885, 6016.157737916607, 5930.555208359304, 5989.910151347646, 5995.430434108753, 6011.308699499614, 6019.398060193946, 5900.5574581907385, 5948.196993405835, 5972.970135149297, 5985.341612422543, 5986.9401305986385, 5860.355360910211, 5879.794207202727, 5894.528437725057, 6039.547033972557, 6069.92965214414, 5826.388229235869, 5832.825015249549, 5840.182374467663, 5852.122181672783, 5872.354041055566], "value": 5624.158702728829, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.0456079394554, 646.5603389695007, 661.9735210591575, 671.9596824190548, 684.4182444921814, 34608.0, 36978.26043479132, 46761.5323846761, 47454.0, 47480.91374060683, 47641.28538071932, 48218.03563928719, 48263.0, 48288.758556207256, 48302.51697483024, 8527.275181609539, 9222.584983675717, 9374.765630859214, 9374.906250937505, 9376.343655944052, 10967.780644387107, 11188.496517656726, 11215.102791776679, 11574.594889178881, 11581.826272605875, 11429.942850285737, 11525.769484610302, 11527.596534121307, 11530.135239857002, 11538.307701537893, 11171.273867198646, 11217.102631789438, 11256.718582035433, 11304.660860174208, 11310.830337544903, 11109.44452777358, 11183.217174797768, 11197.272177308449, 11197.44012799365, 11205.719857003558, 43474.56525434737, 43568.564314356765, 44008.779956100334, 44037.0, 44060.0, 3897.2984862724697, 3957.3668213086057, 4088.6729061674996, 4409.735415875058, 4774.164521208774, 34494.0, 34573.13567160841, 34682.0, 34837.0, 34878.12804679878, 4728.739919304437, 4744.051189762062, 4765.928511072337, 4788.11419887322, 4795.328653988445, 363112.0, 363957.0, 364317.0, 365283.17358413304, 366377.0, 61039.38960610426, 61273.38726612677, 61403.692981534805, 61424.69287653533, 61439.0, 39104.2179156421, 39789.0, 39983.20033599312, 42266.0, 64484.0, 19398.224071037283, 19522.609547809247, 19562.70655940162, 19569.8043019568, 19576.216951322072, 13204.141730787467, 13326.267055312052, 13562.93218533901, 14972.850271497146, 15088.094714317176, 20653.79346206549, 20991.0, 21019.0, 21025.78974210269, 21126.366209013755, 7364.374028207662, 7368.926310736932, 7563.0, 7698.230176982257, 7720.266574675396, 1069.652362982034, 1097.9451027448756, 1106.8837772033821, 1131.6944425005202, 1196.3121205306809, 35270.47094293588, 35287.64712352895, 35614.46578301328, 35651.82174089113, 35772.46341304883, 1152.9020033297263, 1165.0213820390743, 1171.94140292985, 1176.4294317256124, 1292.6445227562494, 18303.72544411835, 18403.72394414085, 18476.630467390576, 18561.5359616008, 18693.0, 647726.0, 647870.0, 652437.0, 655976.0, 662874.0, 43141.784291078344, 43581.12837743227, 43581.56418435839, 43603.99999999937, 43696.0, 1357.4027427931678, 1452.1287227663317, 1455.7379671658991, 1461.9561413157621, 1499.197929107922, 35010.474842876865, 35666.286674266885, 36652.0, 36752.0, 36920.26159476742, 62823.05765413431, 63628.0, 63876.0, 63961.36038639554, 63976.68011659912, 629693.0, 629753.0, 634695.0, 634950.0, 635987.0, 30325.241868953453, 30496.390072197988, 31252.37495250037, 32608.34783304368, 48658.75670621624, 53010.734946326564, 53454.0, 53904.19143712926, 54076.45923540714, 54169.187462187314, 41857.58142418659, 45670.0, 46222.53777462317, 46911.29633055575, 47427.0, 44525.33212001758, 46314.53685463102, 46385.30422043739, 47066.29400559063, 47476.287855681505, 43522.78238608786, 44109.0, 44203.0, 44431.55568444274, 44481.11037779291, 38315.0, 39523.0, 40215.0, 40577.59422405738, 40978.385324220755, 30412.5438118424, 30479.238019049702, 30666.693333067276, 30744.538831917092, 30975.84512077425], "value": 22218.021662159274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.65170627407264, 407.9612436818503, 420.8821529971608, 422.74846466352517, 434.726122542798, 15614.609634759137, 20230.29193978211, 20550.48623784404, 20803.583928321423, 21210.46973825655, 20696.379108626767, 21126.788732112713, 21378.144874205012, 21547.569048619018, 21618.351449456502, 5698.43015698431, 6051.334353221142, 6121.418465245817, 6121.632702037869, 6153.723082461278, 4433.4901486329, 6041.0, 6053.0, 6505.0892874997535, 6721.2270588882175, 6808.6936087876165, 6819.897701534456, 6827.453803695693, 6830.214525329601, 6830.214525329601, 7397.371223445986, 7705.614719264015, 7708.614569271514, 7710.0, 7720.498167619086, 7088.929110708878, 7392.223816499244, 7427.888581671279, 7440.0327957365735, 7496.587687677175, 23070.307890763303, 27206.5919011213, 29777.553336699966, 29811.552826707615, 29878.0, 2726.768224700883, 2749.6150538924567, 2804.761595264405, 3072.5391191321482, 3110.0514343125337, 16767.748483772753, 16873.746893796604, 16940.0, 17705.38031168903, 18372.816271837244, 2163.102312540294, 2310.191432998437, 2317.6986991690974, 2373.4185124644573, 2412.360724408033, 95844.52077739636, 103061.99999999924, 103833.0, 107282.0, 109189.45405272923, 18077.01152873183, 18401.619878508896, 18562.44312670622, 18809.435716928514, 19738.40784776459, 35231.82384088063, 35239.647603523634, 35396.92935312421, 35453.64546354555, 35635.28729425397, 10662.893371066346, 11221.438928053727, 11228.438578071064, 11232.775344493064, 11451.942740286246, 9466.242700583936, 9528.523573821281, 9552.37909535878, 9704.417734935927, 9735.853962190573, 9462.0, 9702.0, 9757.560909758937, 10006.79986400268, 10018.64934727281, 3337.666233376643, 3379.6113446953423, 3393.8642454301553, 3751.2872554214614, 3759.6240375962734, 978.9363691360039, 1061.7292590389402, 1095.2990086344746, 1126.8760436351995, 1148.1675785055768, 20799.8960005199, 20931.581368372546, 21276.468088297916, 21387.25144619931, 21749.565008699738, 1035.808375450529, 1050.894910508943, 1098.4782228441427, 1105.1545567640762, 1128.8588926384202, 11418.771624567627, 11772.411379430994, 11840.763184736257, 11844.644660660195, 11890.464929078213, 477277.0, 483835.0, 485949.0, 491860.0, 492257.0, 89558.55220723855, 93964.53017734867, 98105.0, 101153.49423252983, 101715.49142254241, 1020.8264595018865, 1037.2842738510403, 1049.0, 1064.1593141418193, 1109.578360223126, 19118.617627647647, 19339.709904351166, 19390.515237119183, 19424.223031079164, 19538.21847126156, 47029.764851175525, 47525.01920716752, 48008.0, 48179.0, 50102.0, 64196.0, 65330.346696534325, 65455.34544654683, 67165.32834671786, 67868.0, 9029.548522573714, 9099.681511147079, 9215.539223038955, 9219.354645174773, 9328.95335523318, 10024.699259022242, 13583.52457663977, 13851.792223116863, 13952.16287022782, 14152.78770818459, 12526.37368131608, 12791.80812287835, 12817.9745620352, 12856.614301570968, 12884.549040783528, 12020.158588898694, 12141.453634586289, 12339.321337326533, 12498.187617804813, 12800.551980680631, 8733.606987685442, 8998.280137588847, 9610.135087842127, 12887.0, 13063.085584009028, 13054.216746995211, 13443.46226150943, 14081.08472949255, 14169.079009864327, 14286.928565357106, 6527.67361631926, 10305.793884122426, 11463.426828658701, 13096.148750331198, 13658.590242292747], "value": 11178.827077114649, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.1459182857598, 1591.1964457948736, 1768.469459162251, 1878.840298574621, 1911.9808801911977, 9159.129882661146, 9947.253955953314, 9947.701568952925, 9949.950250248758, 9951.353162044463, 8427.83144337113, 9056.0, 9411.764705882357, 9413.058694130583, 9699.757506062351, 2500.9124680636182, 2530.633058206559, 2533.1640558615613, 2541.0598078710905, 2545.071049067096, 2497.7876880465183, 2565.2047865161767, 2569.139338321655, 2589.3267750384944, 2591.1708253358934, 2521.621756736486, 2523.129520315488, 2570.7043689975612, 2570.910018149356, 2578.265194419591, 2512.0, 2525.6716626838393, 2531.936701582457, 2538.5430622487943, 2539.733328000561, 2431.3921519620094, 2496.712878019033, 2506.486170335077, 2508.4983003399393, 2511.0458025950206, 9825.508724563742, 9907.108360247541, 9921.801563968753, 9927.305088643798, 9942.403455792675, 5718.199452076671, 5916.0, 5929.851753706192, 5933.080372542263, 5934.228550288478, 3540.398132317512, 3672.1737609037755, 3676.613955534684, 3699.741018128732, 3708.406654935224, 1209.1789943777621, 1263.2925561685395, 1269.5302737986913, 1274.7832868412393, 1287.2405280884323, 13077.673058173526, 13396.93301533486, 13487.055906086578, 13738.587842364745, 13879.167249965034, 4961.404631444249, 4970.826021089317, 5069.569086627678, 5085.949140508547, 5137.5376216139975, 10870.836937445945, 10877.456127193609, 10879.238453308351, 10881.727956801144, 10888.891111088946, 5869.8239052828485, 5873.295204575478, 5880.323762767251, 5884.117382392676, 5887.0580707086665, 4535.931961020588, 4625.884352891205, 5365.141577347606, 5402.108652072364, 5420.376656684453, 4188.225178342016, 4228.619424251832, 4307.590778876001, 4339.7396156230725, 4352.695311328177, 1279.993600031994, 1314.8750868667462, 1322.8875545578733, 1325.224199572558, 1328.1632571479906, 952.5713428956997, 1006.335818359879, 1061.1298735037196, 1063.31416236527, 1069.6149386220955, 6180.6291622502795, 6400.167978162809, 6428.742850286029, 6602.537822352389, 6702.296258892844, 467.2150786678345, 475.9286107083965, 478.5095277340684, 485.7911098227796, 492.46567474290794, 4297.441332626739, 4400.295952647625, 4411.250087485135, 4491.236489796743, 4534.79593418297, 11825.940870295593, 12383.814242786366, 12400.503979840705, 12408.937955310166, 12421.627351179479, 11058.668239952814, 12280.324582147889, 12318.076144289214, 12320.137590368588, 12337.321447320304, 8676.262517686067, 9075.047120052432, 9093.090690930987, 9104.271658267322, 9122.86315705265, 6468.2561505427475, 6486.318936511599, 6550.377714117151, 6610.702518386588, 6744.494162937701, 11215.327080374876, 11296.209265351676, 11307.660770176908, 11402.258853174517, 11431.0, 11596.188266821242, 11770.705732356759, 11803.645890623295, 11851.111166662708, 11866.525338986345, 8089.676412943416, 8247.835043299221, 8310.83378332442, 8500.064992850905, 8639.0, 9586.952065239908, 9639.277054221102, 9646.372985755903, 9653.855192171983, 9661.855072173783, 8354.62404191825, 8424.873626895478, 8490.15098490146, 8512.40413171072, 8633.568321584022, 8240.381971352053, 8328.208820162075, 8382.706605268786, 8427.0, 8496.447730897471, 7605.6957721692725, 7681.69273229087, 7720.382369410321, 7725.961370193113, 7812.687492500464, 5617.185508101385, 5768.077107662753, 5786.218860453785, 5852.824415267549, 5883.117532370179, 5637.46444087811, 5731.19763233156, 5757.97121014409, 5875.794347197828, 5901.498372638373], "value": 4988.529264133442, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.5944704143193, 350.43404901084745, 357.3442732585705, 359.56133517109134, 362.34777400678786, 5571.387147413781, 5634.4928956393915, 5676.8580785480335, 5692.975264452397, 5701.230333904921, 5552.77788888445, 5553.139263414178, 5568.276124103871, 5583.5254003409655, 5619.831405057845, 3889.44422231107, 4145.440365550649, 4237.457525484715, 4387.583179597949, 4404.537523560028, 4387.539308372623, 4413.205622987861, 4425.181341451826, 4443.0, 4451.243288640922, 4197.8530751423705, 4377.934330985037, 4383.698558256909, 4419.580139886705, 4436.64506839452, 4272.393839757745, 4326.6754993375625, 4356.128774245132, 4365.454318210223, 4376.627986621141, 3872.612738726133, 3879.9806000969816, 3889.144388234584, 3890.9416358754643, 3890.9883430308323, 5256.106461901448, 5437.764530104581, 5451.672899626035, 5455.781768729246, 5458.9454105458835, 4969.304297398367, 4978.203487442028, 5004.199328107522, 5009.874753131165, 5044.672096313765, 5809.854753631151, 5817.4002149408825, 5838.883222335529, 5851.678157701324, 5879.823605291848, 3982.123932734794, 4275.508316543606, 4285.849995250152, 4307.375430562551, 4339.891502712426, 5801.244408905736, 5880.764769409261, 5968.22413086296, 6031.039912028608, 6037.637741735466, 4284.464441944757, 4290.970167159921, 4293.2701440755145, 4301.569843015736, 4309.439772829513, 4344.174606824693, 4345.326474396474, 4356.847510337187, 4357.193919124972, 4374.431323927869, 4861.565838077776, 4925.482824303468, 5008.499150084962, 5022.573081288131, 5026.371703537058, 5850.12248162779, 5883.264591926009, 5886.05823068307, 5895.64626122434, 5941.435563621449, 1485.7028594281187, 1494.5068127517893, 1507.758758598619, 1514.2655811931204, 1515.8181018277658, 908.5457271364318, 948.8434408322549, 960.9231261499064, 965.6282331302499, 966.4442945306477, 4999.325091112726, 5049.8737531562, 5053.140966035783, 5074.2895994560795, 5122.437656514724, 5287.339082614673, 5289.307421625043, 5293.708846013429, 5303.575713942875, 5317.813876514303, 5014.548690617789, 5030.7171671223605, 5046.561729907033, 5292.708901010405, 5687.573431992617, 3154.7381047581152, 3187.9362412751616, 3191.96261215106, 3203.2312245061016, 3211.0527394418705, 6124.601900876429, 6256.374362563707, 6266.279377871512, 6281.96859015702, 6288.119663247149, 6082.60463069899, 6092.603980741238, 6104.694765261719, 6111.51107911366, 6136.294326152461, 6229.065640153923, 6233.189685340967, 6238.532110091761, 6279.937200627935, 6287.528435367274, 5815.244018277597, 5991.262533865304, 6006.369331220334, 6018.3379828217985, 6070.423309785563, 5941.37615550361, 5948.583599148191, 5959.28488581373, 5972.641641501524, 5990.430909063632, 5958.851028724316, 6040.939590604038, 6129.84675383119, 6183.474404675653, 6237.1891654084675, 4262.7016108872085, 4280.871573852789, 4281.91436171281, 4282.678799090143, 4298.785060746887, 5816.36020037804, 6028.0, 6046.848828779316, 6055.333913269449, 6070.969645151746, 5829.571754920072, 5848.473637372657, 5861.706914654335, 5861.824145275648, 5868.2077919480325, 5824.359320474659, 5838.32859221195, 5861.413858614275, 5864.765409383747, 5880.558958078247, 5524.58565607573, 5807.448292412214, 5838.912416313844, 5841.948449279171, 5870.26621672291, 5920.585559010828, 5929.644221346561, 5937.6046629043, 5938.2280303560265, 5948.0, 5616.354119276336, 5772.249607550992, 5824.301083869793, 5827.737751801264, 5830.5044071254415], "value": 4434.943845389203, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.46274384394, 857.4041041476173, 905.9184673379397, 926.1201858234671, 939.4785893828924, 14772.482963096294, 15901.284442200114, 15901.840981590181, 15926.84073159268, 16031.919840400797, 15290.0, 15387.69224615507, 15425.614359641015, 15426.074435533876, 15433.92283038586, 735.7204262380288, 883.8541640629288, 887.3344991256573, 905.266733945503, 912.9543522823866, 894.5929602031093, 898.6360523987795, 899.9775005624879, 901.2519608724742, 902.5261737587754, 946.1720994130136, 946.6544711180404, 948.9715308540755, 950.3727539823718, 950.6245033211877, 781.8553567589986, 919.7148883846029, 938.1275413865137, 948.7628092976756, 950.2493030505892, 695.8086526205334, 696.9024336592881, 698.1587187439123, 698.6925752668809, 698.6995591895483, 15848.366065357372, 15889.841101588952, 15898.125603091941, 15899.0, 15904.443344483003, 15067.4726384576, 15085.849141508665, 15367.769483457758, 15526.068435893769, 15755.2122393881, 13643.454261829515, 13692.383842727102, 13734.519291824838, 13911.234882081482, 14278.0, 4746.074515469485, 4815.49437309081, 4845.248986407078, 4935.333729946447, 4936.456989731127, 84697.0, 85164.0, 88040.55979720125, 92004.0, 94941.5252923731, 12492.875071249171, 12515.0, 12596.307203103734, 12731.299778512272, 13003.609891703263, 24680.629790553157, 24745.505089898463, 24759.62860557093, 24802.875985619958, 24829.751702482743, 9225.861612075823, 9309.767255818659, 9491.715248542554, 9541.952290238505, 9615.278854085971, 5121.359830021247, 5130.666506677054, 5142.100132476801, 5213.218017297436, 5285.630005899627, 3587.51568538244, 3678.6137455567314, 3703.6481534254203, 3708.295423869456, 3727.683146932487, 659.5646873063755, 666.7099811581977, 674.254948282151, 677.654396257912, 680.251723104581, 759.5404780108065, 761.1285078584989, 768.2240936654019, 771.8842173673881, 778.992210077892, 9530.714078577654, 10348.637797677196, 10490.580376784996, 10558.894411055791, 10923.726906827234, 727.3490226247479, 735.8197241675716, 748.7566540874129, 769.9884501732479, 772.2817779465039, 4918.926216106761, 4938.950610493849, 4973.502649734997, 5009.173486374781, 5031.77357018935, 111913.0, 123418.38290808488, 124274.0, 124659.0, 124851.0, 68171.83414269704, 112246.43876780564, 114963.42518287522, 116163.41918290354, 116386.0, 95654.0, 98485.0, 99616.0, 100273.0, 102568.0, 9476.0, 9562.713118606453, 9696.806063878825, 9703.708888733348, 9732.41605503672, 56750.43249567451, 59572.70213648904, 59882.40117598768, 60448.697756510934, 60639.0, 83775.16224837674, 84892.0, 86001.0, 86260.0, 89358.55320723355, 13024.934875325942, 13123.60629181126, 13374.264415456853, 13510.324483775576, 13630.182189068688, 13730.588082357146, 13732.382042807898, 13932.58202253934, 13942.651433714238, 14183.14901105937, 12925.741485170432, 13074.738505229652, 13708.3831227593, 13833.170009799444, 14068.859311406755, 13377.732445351234, 13569.592912212649, 13649.522266721015, 14128.717425651224, 14475.0, 12534.247945123321, 12631.242125472501, 12664.683382915133, 13196.60410187696, 13681.179129252278, 10592.364458132537, 10881.891181088087, 10883.455827208767, 11018.173636977419, 11293.774124517628, 10989.78723827632, 11799.292042477478, 12120.696982575506, 12293.0, 12333.50665973351], "value": 8144.301036432279, "unit": "score"}], "name": "dromaeo_css", "value": 7946.086229274239, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 111.0, 108.0, 109.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 120.0, 176.0, 120.0, 117.0, 146.0, 115.0, 150.0, 117.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 85.0, 77.0, 115.0, 76.0, 76.0, 73.0, 75.0, 103.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 120.0, 83.0, 83.0, 83.0, 129.0, 82.0, 83.0, 127.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 50.0, 50.0, 52.0, 50.0, 51.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 71.0, 71.0, 74.0, 72.0, 73.0, 72.0, 75.0, 75.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 126.0, 124.0, 116.0, 116.0, 116.0, 124.0, 190.0, 119.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 152.0, 157.0, 153.0, 153.0, 152.0, 153.0, 157.0, 152.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 61.0, 63.0, 61.0, 61.0, 60.0, 60.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1461.3, "alertThreshold": 2.0}]} 04:33:30 INFO - Return code: 0 04:33:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:30 INFO - # TBPL SUCCESS # 04:33:30 INFO - Running post-action listener: _resource_record_post_action 04:33:30 INFO - [mozharness: 2016-08-18 11:33:30.811006Z] Finished run-tests step (success) 04:33:30 INFO - Running post-run listener: _resource_record_post_run 04:33:30 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328196096; Read time: 8; Write time: 189496 04:33:30 INFO - TinderboxPrint: CPU usage
13.0% 04:33:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 04:33:30 INFO - TinderboxPrint: I/O write bytes / time
328,196,096 / 189,496 04:33:30 INFO - TinderboxPrint: CPU idle
8,273.5 (87.0%) 04:33:30 INFO - TinderboxPrint: CPU user
1,185.3 (12.5%) 04:33:30 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79081472; Read time: 0; Write time: 42956 04:33:30 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 248897536; Read time: 0; Write time: 146540 04:33:31 INFO - Running post-run listener: _upload_blobber_files 04:33:31 INFO - Blob upload gear active. 04:33:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:31 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 04:33:31 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'] 04:33:31 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 04:33:31 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:41 INFO - (blobuploader) - INFO - Done attempting. 04:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:42 INFO - (blobuploader) - INFO - Done attempting. 04:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:43 INFO - (blobuploader) - INFO - Done attempting. 04:33:43 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:43 INFO - Return code: 0 04:33:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc7fa2ede26152400e572bba91fe5e030f714f0d3eb181e4453b52de11857ed497c9c22b54b17e2a9cfa31c6b7678a6909b251bedf61d9f8c9c5814c558e217f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ec11b47c688e1cc395d04028b2b45fc74d37c5c6c40e19c24ddc860c42f4b84033cdf36e76b798b476c00ef81959df044efa6f71deb77122645bfff4c69e10f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81fe053e0d3ff843171f9af427478121d580e43d374833ec5bfcd92fca7cccc67c68238cbbf441bf4e02661c8bea409c5f2d1574640e26ca76cf80cec8f99ad0"} 04:33:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:43 INFO - Contents: 04:33:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc7fa2ede26152400e572bba91fe5e030f714f0d3eb181e4453b52de11857ed497c9c22b54b17e2a9cfa31c6b7678a6909b251bedf61d9f8c9c5814c558e217f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ec11b47c688e1cc395d04028b2b45fc74d37c5c6c40e19c24ddc860c42f4b84033cdf36e76b798b476c00ef81959df044efa6f71deb77122645bfff4c69e10f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81fe053e0d3ff843171f9af427478121d580e43d374833ec5bfcd92fca7cccc67c68238cbbf441bf4e02661c8bea409c5f2d1574640e26ca76cf80cec8f99ad0"} 04:33:43 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:43 INFO - Copying logs to upload dir... 04:33:43 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1692.791241 ========= master_lag: 1.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 13 secs) (at 2016-08-18 04:33:43.658196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:43.662051) ========= 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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 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/cc7fa2ede26152400e572bba91fe5e030f714f0d3eb181e4453b52de11857ed497c9c22b54b17e2a9cfa31c6b7678a6909b251bedf61d9f8c9c5814c558e217f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ec11b47c688e1cc395d04028b2b45fc74d37c5c6c40e19c24ddc860c42f4b84033cdf36e76b798b476c00ef81959df044efa6f71deb77122645bfff4c69e10f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81fe053e0d3ff843171f9af427478121d580e43d374833ec5bfcd92fca7cccc67c68238cbbf441bf4e02661c8bea409c5f2d1574640e26ca76cf80cec8f99ad0"} build_url:https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/TwtcBMXDRs2Kz51iIRVNAQ/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/cc7fa2ede26152400e572bba91fe5e030f714f0d3eb181e4453b52de11857ed497c9c22b54b17e2a9cfa31c6b7678a6909b251bedf61d9f8c9c5814c558e217f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ec11b47c688e1cc395d04028b2b45fc74d37c5c6c40e19c24ddc860c42f4b84033cdf36e76b798b476c00ef81959df044efa6f71deb77122645bfff4c69e10f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81fe053e0d3ff843171f9af427478121d580e43d374833ec5bfcd92fca7cccc67c68238cbbf441bf4e02661c8bea409c5f2d1574640e26ca76cf80cec8f99ad0"}' ========= master_lag: 0.61 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:44.286158) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-08-18 04:33:44.286481) ========= 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=d2a8c569965ab8367867b893000001a1-1471517521.797594-550918150 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004400 ========= master_lag: 4.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-08-18 04:33:48.637360) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:48.638078) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:48.997851) ========= ========= Total master_lag: 6.13 =========