builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1473318633.97 results: success (0) buildid: 20160907212216 builduid: ac9f4432c3ca46b399057a7f993163a3 revision: 7d3d4f307002cc8475800df191196b94999ffd0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:33.968945) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:33.969461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:33.969745) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:34.014196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:34.014479) ========= 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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 00:10:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-09-08 00:10:34 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093360 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:34.122488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:34.122772) ========= 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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019901 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:34.171367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 00:10:34.171671) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7d3d4f307002cc8475800df191196b94999ffd0a --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 7d3d4f307002cc8475800df191196b94999ffd0a --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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 00:10:34,432 truncating revision to first 12 chars 2016-09-08 00:10:34,432 Setting DEBUG logging. 2016-09-08 00:10:34,432 attempt 1/10 2016-09-08 00:10:34,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7d3d4f307002?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 00:10:35,407 unpacking tar archive at: mozilla-aurora-7d3d4f307002/testing/mozharness/ program finished with exit code 0 elapsedTime=1.327311 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 00:10:35.512396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:35.512700) ========= script_repo_revision: 7d3d4f307002cc8475800df191196b94999ffd0a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:35.513109) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:35.513377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 00:10:35.521609) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-08 00:10:35.521884) ========= /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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:10:35 INFO - MultiFileLogger online at 20160908 00:10:35 in /builds/slave/test 00:10:35 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 00:10:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:10:35 INFO - {'append_to_log': False, 00:10:35 INFO - 'base_work_dir': '/builds/slave/test', 00:10:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 00:10:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:10:35 INFO - 'branch': 'Mozilla-Aurora', 00:10:35 INFO - 'buildbot_json_path': 'buildprops.json', 00:10:35 INFO - 'config_files': ('talos/linux_config.py',), 00:10:35 INFO - 'default_actions': ('clobber', 00:10:35 INFO - 'read-buildbot-config', 00:10:35 INFO - 'download-and-extract', 00:10:35 INFO - 'populate-webroot', 00:10:35 INFO - 'create-virtualenv', 00:10:35 INFO - 'install', 00:10:35 INFO - 'run-tests'), 00:10:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:10:35 INFO - 'download_minidump_stackwalk': True, 00:10:35 INFO - 'download_symbols': 'ondemand', 00:10:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:10:35 INFO - 'tooltool.py': '/tools/tooltool.py', 00:10:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:10:35 INFO - '/tools/misc-python/virtualenv.py')}, 00:10:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:10:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:10:35 INFO - 'installer_path': 'installer.exe', 00:10:35 INFO - 'log_level': 'info', 00:10:35 INFO - 'log_name': 'talos', 00:10:35 INFO - 'log_to_console': True, 00:10:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:10:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:10:35 INFO - 'opt_config_files': (), 00:10:35 INFO - 'pip_index': False, 00:10:35 INFO - 'sps_profile': False, 00:10:35 INFO - 'sps_profile_interval': 0, 00:10:35 INFO - 'suite': 'dromaeojs', 00:10:35 INFO - 'system_bits': '32', 00:10:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:10:35 INFO - 'title': 'talos-linux64-ix-004', 00:10:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:10:35 INFO - 'use_talos_json': True, 00:10:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:10:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:10:35 INFO - 'work_dir': 'build'} 00:10:35 INFO - [mozharness: 2016-09-08 07:10:35.866361Z] Running clobber step. 00:10:35 INFO - Running pre-action listener: _resource_record_pre_action 00:10:35 INFO - Running main action method: clobber 00:10:35 INFO - rmtree: /builds/slave/test/build 00:10:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:10:36 INFO - Running post-action listener: _resource_record_post_action 00:10:36 INFO - [mozharness: 2016-09-08 07:10:36.325870Z] Finished clobber step (success) 00:10:36 INFO - [mozharness: 2016-09-08 07:10:36.325971Z] Running read-buildbot-config step. 00:10:36 INFO - Running pre-action listener: _resource_record_pre_action 00:10:36 INFO - Running main action method: read_buildbot_config 00:10:36 INFO - Using buildbot properties: 00:10:36 INFO - { 00:10:36 INFO - "project": "", 00:10:36 INFO - "product": "firefox", 00:10:36 INFO - "script_repo_revision": "production", 00:10:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 00:10:36 INFO - "repository": "", 00:10:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 00:10:36 INFO - "buildid": "20160907212216", 00:10:36 INFO - "pgo_build": "True", 00:10:36 INFO - "basedir": "/builds/slave/test", 00:10:36 INFO - "buildnumber": 455, 00:10:36 INFO - "slavename": "talos-linux64-ix-004", 00:10:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 00:10:36 INFO - "platform": "ubuntu64_hw", 00:10:36 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 00:10:36 INFO - "branch": "mozilla-aurora", 00:10:36 INFO - "repo_path": "releases/mozilla-aurora", 00:10:36 INFO - "revision": "7d3d4f307002cc8475800df191196b94999ffd0a", 00:10:36 INFO - "stage_platform": "linux64-pgo", 00:10:36 INFO - "builduid": "ac9f4432c3ca46b399057a7f993163a3", 00:10:36 INFO - "slavebuilddir": "test" 00:10:36 INFO - } 00:10:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 00:10:36 INFO - Running post-action listener: _resource_record_post_action 00:10:36 INFO - [mozharness: 2016-09-08 07:10:36.328398Z] Finished read-buildbot-config step (success) 00:10:36 INFO - [mozharness: 2016-09-08 07:10:36.328488Z] Running download-and-extract step. 00:10:36 INFO - Running pre-action listener: _resource_record_pre_action 00:10:36 INFO - Running main action method: download_and_extract 00:10:36 INFO - mkdir: /builds/slave/test/build/tests 00:10: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')]} 00:10:36 INFO - https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 00:10:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:10:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:10:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:10:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:10:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:10:36 INFO - retry: Failed, sleeping 30 seconds before retrying 00:11:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:11:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:11:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:11:06 INFO - retry: Failed, sleeping 60 seconds before retrying 00:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:12:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:12:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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! 00:12:06 INFO - Caught exception: HTTP Error 404: Not Found 00:12:06 INFO - Caught exception: HTTP Error 404: Not Found 00:12:06 INFO - Caught exception: HTTP Error 404: Not Found 00:12:06 INFO - trying https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:12:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:07 INFO - Downloaded 1361 bytes. 00:12:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 00:12:07 INFO - Using the following test package requirements: 00:12:07 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 00:12:07 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 00:12:07 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 00:12:07 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'jsshell-linux-x86_64.zip'], 00:12:07 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 00:12:07 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 00:12:07 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 00:12:07 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 00:12:07 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 00:12:07 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 00:12:07 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 00:12:07 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:07 INFO - https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:12:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:12:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:12:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:12:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:12:07 INFO - retry: Failed, sleeping 30 seconds before retrying 00:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:12:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:12:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:12:37 INFO - retry: Failed, sleeping 60 seconds before retrying 00:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:13:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:13:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:13:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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! 00:13:37 INFO - Caught exception: HTTP Error 404: Not Found 00:13:37 INFO - Caught exception: HTTP Error 404: Not Found 00:13:37 INFO - Caught exception: HTTP Error 404: Not Found 00:13:37 INFO - trying https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:13:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:13:37 INFO - Downloaded 21769429 bytes. 00:13:37 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'] 00:13:37 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 00:13:39 INFO - Return code: 0 00:13:39 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 00: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')]} 00:13:39 INFO - https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 00:13:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:13:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:13:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:13:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:13:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:13:39 INFO - retry: Failed, sleeping 30 seconds before retrying 00:14:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:14:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:14:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:14:09 INFO - retry: Failed, sleeping 60 seconds before retrying 00:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:15:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:15:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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! 00:15:09 INFO - Caught exception: HTTP Error 404: Not Found 00:15:09 INFO - Caught exception: HTTP Error 404: Not Found 00:15:09 INFO - Caught exception: HTTP Error 404: Not Found 00:15:09 INFO - trying https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 00:15:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:10 INFO - Downloaded 21769429 bytes. 00:15:10 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'] 00:15:10 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 00:15:11 INFO - Return code: 0 00:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:15:11 INFO - https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 00:15:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:15:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:15:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:15:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:15:11 INFO - retry: Failed, sleeping 30 seconds before retrying 00:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:15:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:15:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:15:41 INFO - retry: Failed, sleeping 60 seconds before retrying 00:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:16:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:16:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:16:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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! 00:16:41 INFO - Caught exception: HTTP Error 404: Not Found 00:16:41 INFO - Caught exception: HTTP Error 404: Not Found 00:16:41 INFO - Caught exception: HTTP Error 404: Not Found 00:16:41 INFO - trying https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 00:16:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:16:42 INFO - Downloaded 14413740 bytes. 00:16:42 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'] 00:16:42 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 00:16:42 INFO - Return code: 0 00:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:16:42 INFO - https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 00:16:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:16:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:16:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:16:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:16:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 00:16:42 INFO - retry: Failed, sleeping 30 seconds before retrying 00:17:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:17:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:17:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 00:17:12 INFO - retry: Failed, sleeping 60 seconds before retrying 00:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:18:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:18:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 00:18:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cime8-S0TOSnUvh7hU5NvA/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! 00:18:12 INFO - Caught exception: HTTP Error 404: Not Found 00:18:12 INFO - Caught exception: HTTP Error 404: Not Found 00:18:12 INFO - Caught exception: HTTP Error 404: Not Found 00:18:12 INFO - trying https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:18:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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 00:18:13 INFO - Downloaded 63918604 bytes. 00:18:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:18:13 INFO - mkdir: /builds/slave/test/properties 00:18:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:18:13 INFO - Writing to file /builds/slave/test/properties/build_url 00:18:13 INFO - Contents: 00:18:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 00:18:14 INFO - Running post-action listener: _resource_record_post_action 00:18:14 INFO - Running post-action listener: set_extra_try_arguments 00:18:14 INFO - [mozharness: 2016-09-08 07:18:14.421534Z] Finished download-and-extract step (success) 00:18:14 INFO - [mozharness: 2016-09-08 07:18:14.421766Z] Running populate-webroot step. 00:18:14 INFO - Running pre-action listener: _resource_record_pre_action 00:18:14 INFO - Running main action method: populate_webroot 00:18:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:18:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:18:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:18:14 INFO - '--tppagecycles', 00:18:14 INFO - '7'], 00:18:14 INFO - 'tests': ['tsvgm']}}, 00:18:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:18:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:18:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:18:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:18:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:18:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:18:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['damp', 'tps']}, 00:18:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'tests': ['damp', 'tps']}, 00:18:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['dromaeo_dom']}, 00:18:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:18:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['basic_compositor_video']}, 00:18:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:18:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'other-e10s': {'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['a11yr', 00:18:14 INFO - 'ts_paint', 00:18:14 INFO - 'tpaint', 00:18:14 INFO - 'sessionrestore', 00:18:14 INFO - 'sessionrestore_no_auto_restore', 00:18:14 INFO - 'tabpaint']}, 00:18:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['tsvgx', 00:18:14 INFO - 'tsvgr_opacity', 00:18:14 INFO - 'tart', 00:18:14 INFO - 'tscrollx', 00:18:14 INFO - 'cart']}, 00:18:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:18:14 INFO - 'tsvgr_opacity', 00:18:14 INFO - 'tart', 00:18:14 INFO - 'tscrollx', 00:18:14 INFO - 'cart']}, 00:18:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'talos_options': ['--disable-e10s'], 00:18:14 INFO - 'tests': ['tp5o']}, 00:18:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'tests': ['tp5o']}, 00:18:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'talos_options': ['--disable-e10s', 00:18:14 INFO - '--xperf_path', 00:18:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:18:14 INFO - 'tests': ['tp5n']}, 00:18:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:18:14 INFO - 'talos_options': ['--xperf_path', 00:18:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:18:14 INFO - 'tests': ['tp5n']}}, 00:18:14 INFO - 'talos.zip': {'path': '', 00:18:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:18:14 INFO - Running post-action listener: _resource_record_post_action 00:18:14 INFO - [mozharness: 2016-09-08 07:18:14.442454Z] Finished populate-webroot step (success) 00:18:14 INFO - [mozharness: 2016-09-08 07:18:14.442543Z] Running create-virtualenv step. 00:18:14 INFO - Running pre-action listener: _resource_record_pre_action 00:18:14 INFO - Running main action method: create_virtualenv 00:18:14 INFO - Creating virtualenv /builds/slave/test/build/venv 00:18:14 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 00:18:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:18:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:18:14 INFO - Using real prefix '/usr' 00:18:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:18:15 INFO - Installing distribute.............................................................................................................................................................................................done. 00:18:17 INFO - Installing pip.................done. 00:18:17 INFO - Return code: 0 00:18:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:18:17 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')]} 00:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:17 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')]} 00:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:17 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:17 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 00:18:17 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 00:18:17 INFO - Using env: {'DISPLAY': ':0', 00:18:17 INFO - 'HOME': '/home/cltbld', 00:18:17 INFO - 'LANG': 'en_US.UTF-8', 00:18:17 INFO - 'LANGUAGE': 'en_US:en', 00:18:17 INFO - 'LOGNAME': 'cltbld', 00:18:17 INFO - 'MAIL': '/var/mail/cltbld', 00:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:17 INFO - 'MOZ_NO_REMOTE': '1', 00:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:17 INFO - 'NO_EM_RESTART': '1', 00:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:17 INFO - 'PWD': '/builds/slave/test', 00:18:17 INFO - 'SHELL': '/bin/bash', 00:18:17 INFO - 'SHLVL': '1', 00:18:17 INFO - 'TERM': 'linux', 00:18:17 INFO - 'TMOUT': '86400', 00:18:17 INFO - 'USER': 'cltbld', 00:18:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:17 INFO - '_': '/tools/buildbot/bin/python'} 00:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:17 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 00:18:17 INFO - Cleaning up... 00:18:17 INFO - Return code: 0 00:18:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:18:17 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')]} 00:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:17 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')]} 00:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:17 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:17 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 00:18:17 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 00:18:17 INFO - Using env: {'DISPLAY': ':0', 00:18:17 INFO - 'HOME': '/home/cltbld', 00:18:17 INFO - 'LANG': 'en_US.UTF-8', 00:18:17 INFO - 'LANGUAGE': 'en_US:en', 00:18:17 INFO - 'LOGNAME': 'cltbld', 00:18:17 INFO - 'MAIL': '/var/mail/cltbld', 00:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:17 INFO - 'MOZ_NO_REMOTE': '1', 00:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:17 INFO - 'NO_EM_RESTART': '1', 00:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:17 INFO - 'PWD': '/builds/slave/test', 00:18:17 INFO - 'SHELL': '/bin/bash', 00:18:17 INFO - 'SHLVL': '1', 00:18:17 INFO - 'TERM': 'linux', 00:18:17 INFO - 'TMOUT': '86400', 00:18:17 INFO - 'USER': 'cltbld', 00:18:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:17 INFO - '_': '/tools/buildbot/bin/python'} 00:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:17 INFO - Downloading/unpacking psutil>=3.1.1 00:18:17 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 00:18:17 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 00:18:17 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 00:18:17 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 00:18:17 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 00:18:17 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 00:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:18:19 INFO - Installing collected packages: psutil 00:18:19 INFO - Running setup.py install for psutil 00:18:19 INFO - building 'psutil._psutil_linux' extension 00:18:19 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 00:18:19 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 00:18:19 INFO - building 'psutil._psutil_posix' extension 00:18:19 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 00:18:19 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 00:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:18:19 INFO - Successfully installed psutil 00:18:19 INFO - Cleaning up... 00:18:19 INFO - Return code: 0 00:18:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:18: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 mozsystemmonitor==0.3 00:18:19 INFO - Using env: {'DISPLAY': ':0', 00:18:19 INFO - 'HOME': '/home/cltbld', 00:18:19 INFO - 'LANG': 'en_US.UTF-8', 00:18:19 INFO - 'LANGUAGE': 'en_US:en', 00:18:19 INFO - 'LOGNAME': 'cltbld', 00:18:19 INFO - 'MAIL': '/var/mail/cltbld', 00:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:19 INFO - 'MOZ_NO_REMOTE': '1', 00:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:19 INFO - 'NO_EM_RESTART': '1', 00:18:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:19 INFO - 'PWD': '/builds/slave/test', 00:18:19 INFO - 'SHELL': '/bin/bash', 00:18:19 INFO - 'SHLVL': '1', 00:18:19 INFO - 'TERM': 'linux', 00:18:19 INFO - 'TMOUT': '86400', 00:18:19 INFO - 'USER': 'cltbld', 00:18:19 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:19 INFO - '_': '/tools/buildbot/bin/python'} 00:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:18:22 INFO - Installing collected packages: mozsystemmonitor 00:18:22 INFO - Running setup.py install for mozsystemmonitor 00:18:22 INFO - Successfully installed mozsystemmonitor 00:18:22 INFO - Cleaning up... 00:18:22 INFO - Return code: 0 00:18:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:18:22 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')]} 00:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:22 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')]} 00:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:22 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:22 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 00:18:22 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 00:18:22 INFO - Using env: {'DISPLAY': ':0', 00:18:22 INFO - 'HOME': '/home/cltbld', 00:18:22 INFO - 'LANG': 'en_US.UTF-8', 00:18:22 INFO - 'LANGUAGE': 'en_US:en', 00:18:22 INFO - 'LOGNAME': 'cltbld', 00:18:22 INFO - 'MAIL': '/var/mail/cltbld', 00:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:22 INFO - 'MOZ_NO_REMOTE': '1', 00:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:22 INFO - 'NO_EM_RESTART': '1', 00:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:22 INFO - 'PWD': '/builds/slave/test', 00:18:22 INFO - 'SHELL': '/bin/bash', 00:18:22 INFO - 'SHLVL': '1', 00:18:22 INFO - 'TERM': 'linux', 00:18:22 INFO - 'TMOUT': '86400', 00:18:22 INFO - 'USER': 'cltbld', 00:18:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:22 INFO - '_': '/tools/buildbot/bin/python'} 00:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:22 INFO - Downloading/unpacking jsonschema==2.5.1 00:18:22 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 00:18:22 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 00:18:22 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 00:18:22 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 00:18:22 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 00:18:22 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 00:18:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:18:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:18:24 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 00:18:24 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 00:18:24 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 00:18:24 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 00:18:24 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 00:18:24 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 00:18:24 INFO - Downloading functools32-3.2.3-2.tar.gz 00:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:18:24 INFO - warning: no files found matching '*.txt' 00:18:24 INFO - no previously-included directories found matching 'build' 00:18:24 INFO - no previously-included directories found matching 'dist' 00:18:24 INFO - no previously-included directories found matching '.git*' 00:18:24 INFO - Installing collected packages: jsonschema, functools32 00:18:24 INFO - Running setup.py install for functools32 00:18:24 INFO - warning: no files found matching '*.txt' 00:18:24 INFO - no previously-included directories found matching 'build' 00:18:24 INFO - no previously-included directories found matching 'dist' 00:18:24 INFO - no previously-included directories found matching '.git*' 00:18:24 INFO - Successfully installed jsonschema functools32 00:18:24 INFO - Cleaning up... 00:18:24 INFO - Return code: 0 00:18:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:18:24 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')]} 00:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:24 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')]} 00:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:24 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:24 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 00:18:24 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 00:18:24 INFO - Using env: {'DISPLAY': ':0', 00:18:24 INFO - 'HOME': '/home/cltbld', 00:18:24 INFO - 'LANG': 'en_US.UTF-8', 00:18:24 INFO - 'LANGUAGE': 'en_US:en', 00:18:24 INFO - 'LOGNAME': 'cltbld', 00:18:24 INFO - 'MAIL': '/var/mail/cltbld', 00:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:24 INFO - 'MOZ_NO_REMOTE': '1', 00:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:24 INFO - 'NO_EM_RESTART': '1', 00:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:24 INFO - 'PWD': '/builds/slave/test', 00:18:24 INFO - 'SHELL': '/bin/bash', 00:18:24 INFO - 'SHLVL': '1', 00:18:24 INFO - 'TERM': 'linux', 00:18:24 INFO - 'TMOUT': '86400', 00:18:24 INFO - 'USER': 'cltbld', 00:18:24 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:24 INFO - '_': '/tools/buildbot/bin/python'} 00:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:18:25 INFO - Cleaning up... 00:18:25 INFO - Return code: 0 00:18:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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', '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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:18: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 blobuploader==1.2.4 00:18:25 INFO - Using env: {'DISPLAY': ':0', 00:18:25 INFO - 'HOME': '/home/cltbld', 00:18:25 INFO - 'LANG': 'en_US.UTF-8', 00:18:25 INFO - 'LANGUAGE': 'en_US:en', 00:18:25 INFO - 'LOGNAME': 'cltbld', 00:18:25 INFO - 'MAIL': '/var/mail/cltbld', 00:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:25 INFO - 'MOZ_NO_REMOTE': '1', 00:18:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:25 INFO - 'NO_EM_RESTART': '1', 00:18:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:25 INFO - 'PWD': '/builds/slave/test', 00:18:25 INFO - 'SHELL': '/bin/bash', 00:18:25 INFO - 'SHLVL': '1', 00:18:25 INFO - 'TERM': 'linux', 00:18:25 INFO - 'TMOUT': '86400', 00:18:25 INFO - 'USER': 'cltbld', 00:18:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:25 INFO - '_': '/tools/buildbot/bin/python'} 00:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:25 INFO - Downloading/unpacking blobuploader==1.2.4 00:18: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 00:18: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 00:18: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 00:18: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 00:18: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 00:18: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 00:18:27 INFO - Downloading blobuploader-1.2.4.tar.gz 00:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:18:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:18:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:27 INFO - Downloading docopt-0.6.1.tar.gz 00:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 00:18:27 INFO - Running setup.py install for blobuploader 00:18:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:18:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:18:28 INFO - Running setup.py install for requests 00:18:28 INFO - Running setup.py install for docopt 00:18:28 INFO - Successfully installed blobuploader requests docopt 00:18:28 INFO - Cleaning up... 00:18:28 INFO - Return code: 0 00:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:28 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:28 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 00:18:28 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 00:18:28 INFO - Using env: {'DISPLAY': ':0', 00:18:28 INFO - 'HOME': '/home/cltbld', 00:18:28 INFO - 'LANG': 'en_US.UTF-8', 00:18:28 INFO - 'LANGUAGE': 'en_US:en', 00:18:28 INFO - 'LOGNAME': 'cltbld', 00:18:28 INFO - 'MAIL': '/var/mail/cltbld', 00:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:28 INFO - 'MOZ_NO_REMOTE': '1', 00:18:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:28 INFO - 'NO_EM_RESTART': '1', 00:18:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:28 INFO - 'PWD': '/builds/slave/test', 00:18:28 INFO - 'SHELL': '/bin/bash', 00:18:28 INFO - 'SHLVL': '1', 00:18:28 INFO - 'TERM': 'linux', 00:18:28 INFO - 'TMOUT': '86400', 00:18:28 INFO - 'USER': 'cltbld', 00:18:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:28 INFO - '_': '/tools/buildbot/bin/python'} 00:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:18:28 INFO - Running setup.py (path:/tmp/pip-Jsw_Dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:18:28 INFO - Running setup.py (path:/tmp/pip-FI75uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:18:28 INFO - Running setup.py (path:/tmp/pip-fkxqhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:18:28 INFO - Running setup.py (path:/tmp/pip-ETG_zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:18:29 INFO - Running setup.py (path:/tmp/pip-0ie_xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:18:29 INFO - Running setup.py (path:/tmp/pip-aRFz0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:18:29 INFO - Running setup.py (path:/tmp/pip-odpzBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:18:29 INFO - Running setup.py (path:/tmp/pip-CoO7t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:18:29 INFO - Running setup.py (path:/tmp/pip-1GjPae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:18:29 INFO - Running setup.py (path:/tmp/pip-8l9GcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:18:29 INFO - Running setup.py (path:/tmp/pip-H_Hw6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:18:29 INFO - Running setup.py (path:/tmp/pip-bTbf1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:18:29 INFO - Running setup.py (path:/tmp/pip-TV304X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:18:29 INFO - Running setup.py (path:/tmp/pip-cccBHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:18:29 INFO - Running setup.py (path:/tmp/pip-Q_M7aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:18:29 INFO - Running setup.py (path:/tmp/pip-iOCPGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:18:29 INFO - Running setup.py (path:/tmp/pip-dKGgwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:18:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:18:29 INFO - Running setup.py install for manifestparser 00:18:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for mozcrash 00:18:30 INFO - Running setup.py install for mozdebug 00:18:30 INFO - Running setup.py install for mozdevice 00:18:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for mozfile 00:18:30 INFO - Running setup.py install for mozhttpd 00:18:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for mozinfo 00:18:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for mozInstall 00:18:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for mozleak 00:18:30 INFO - Running setup.py install for mozlog 00:18:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:18:30 INFO - Running setup.py install for moznetwork 00:18:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Running setup.py install for mozprocess 00:18:31 INFO - Running setup.py install for mozprofile 00:18:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Running setup.py install for mozrunner 00:18:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Running setup.py install for mozscreenshot 00:18:31 INFO - Running setup.py install for moztest 00:18:31 INFO - Running setup.py install for mozversion 00:18:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:18:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:18:31 INFO - Cleaning up... 00:18:31 INFO - Return code: 0 00:18:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:31 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:31 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 00:18:31 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 00:18:31 INFO - Using env: {'DISPLAY': ':0', 00:18:31 INFO - 'HOME': '/home/cltbld', 00:18:31 INFO - 'LANG': 'en_US.UTF-8', 00:18:31 INFO - 'LANGUAGE': 'en_US:en', 00:18:31 INFO - 'LOGNAME': 'cltbld', 00:18:31 INFO - 'MAIL': '/var/mail/cltbld', 00:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:31 INFO - 'MOZ_NO_REMOTE': '1', 00:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:31 INFO - 'NO_EM_RESTART': '1', 00:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:31 INFO - 'PWD': '/builds/slave/test', 00:18:31 INFO - 'SHELL': '/bin/bash', 00:18:31 INFO - 'SHLVL': '1', 00:18:31 INFO - 'TERM': 'linux', 00:18:31 INFO - 'TMOUT': '86400', 00:18:31 INFO - 'USER': 'cltbld', 00:18:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:31 INFO - '_': '/tools/buildbot/bin/python'} 00:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:18:31 INFO - Running setup.py (path:/tmp/pip-0rD1St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:18:32 INFO - Running setup.py (path:/tmp/pip-cqHgWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:18:32 INFO - Running setup.py (path:/tmp/pip-cGfH2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:18:32 INFO - Running setup.py (path:/tmp/pip-2fDEM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:18:32 INFO - Running setup.py (path:/tmp/pip-_qW47K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:18:32 INFO - Running setup.py (path:/tmp/pip-zjjO_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:18:32 INFO - Running setup.py (path:/tmp/pip-D0VRSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:18:32 INFO - Running setup.py (path:/tmp/pip-ByiJiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:18:32 INFO - Running setup.py (path:/tmp/pip-bqYe0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:18:32 INFO - Running setup.py (path:/tmp/pip-nTuh8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:18:32 INFO - Running setup.py (path:/tmp/pip-JwD9ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:18:32 INFO - Running setup.py (path:/tmp/pip-49SE_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:18:32 INFO - Running setup.py (path:/tmp/pip-cPuXZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:18:32 INFO - Running setup.py (path:/tmp/pip-UUDF4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:18:32 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)) 00:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:18:32 INFO - Running setup.py (path:/tmp/pip-rciRda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:18:33 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)) 00:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:18:33 INFO - Running setup.py (path:/tmp/pip-nHbB1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:18:33 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)) 00:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:18:33 INFO - Running setup.py (path:/tmp/pip-ZvG3Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:18:33 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)) 00:18:33 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)) 00:18:33 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)) 00:18:33 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)) 00:18:33 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)) 00:18:33 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)) 00:18:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:18:35 INFO - Downloading blessings-1.6.tar.gz 00:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:18:35 INFO - Installing collected packages: blessings 00:18:35 INFO - Running setup.py install for blessings 00:18:35 INFO - Successfully installed blessings 00:18:35 INFO - Cleaning up... 00:18:35 INFO - Return code: 0 00:18:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:18:35 INFO - Reading from file tmpfile_stdout 00:18:35 INFO - Current package versions: 00:18:35 INFO - argparse == 1.2.1 00:18:35 INFO - blessings == 1.6 00:18:35 INFO - blobuploader == 1.2.4 00:18:35 INFO - docopt == 0.6.1 00:18:35 INFO - functools32 == 3.2.3-2 00:18:35 INFO - jsonschema == 2.5.1 00:18:35 INFO - manifestparser == 1.1 00:18:35 INFO - mozInstall == 1.12 00:18:35 INFO - mozcrash == 0.17 00:18:35 INFO - mozdebug == 0.1 00:18:35 INFO - mozdevice == 0.48 00:18:35 INFO - mozfile == 1.2 00:18:35 INFO - mozhttpd == 0.7 00:18:35 INFO - mozinfo == 0.9 00:18:35 INFO - mozleak == 0.1 00:18:35 INFO - mozlog == 3.3 00:18:35 INFO - moznetwork == 0.27 00:18:35 INFO - mozprocess == 0.23 00:18:35 INFO - mozprofile == 0.28 00:18:35 INFO - mozrunner == 6.12 00:18:35 INFO - mozscreenshot == 0.1 00:18:35 INFO - mozsystemmonitor == 0.3 00:18:35 INFO - moztest == 0.7 00:18:35 INFO - mozversion == 1.4 00:18:35 INFO - psutil == 3.1.1 00:18:35 INFO - requests == 1.2.3 00:18:35 INFO - wsgiref == 0.1.2 00:18:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:35 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 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:35 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 00:18:35 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 00:18:35 INFO - Using env: {'DISPLAY': ':0', 00:18:35 INFO - 'HOME': '/home/cltbld', 00:18:35 INFO - 'LANG': 'en_US.UTF-8', 00:18:35 INFO - 'LANGUAGE': 'en_US:en', 00:18:35 INFO - 'LOGNAME': 'cltbld', 00:18:35 INFO - 'MAIL': '/var/mail/cltbld', 00:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:35 INFO - 'MOZ_NO_REMOTE': '1', 00:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:35 INFO - 'NO_EM_RESTART': '1', 00:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:35 INFO - 'PWD': '/builds/slave/test', 00:18:35 INFO - 'SHELL': '/bin/bash', 00:18:35 INFO - 'SHLVL': '1', 00:18:35 INFO - 'TERM': 'linux', 00:18:35 INFO - 'TMOUT': '86400', 00:18:35 INFO - 'USER': 'cltbld', 00:18:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:35 INFO - '_': '/tools/buildbot/bin/python'} 00:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 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)) 00:18:35 INFO - Cleaning up... 00:18:35 INFO - Return code: 0 00:18:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:18: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')]} 00:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18: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')]} 00:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c69030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', '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 00:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:18:35 INFO - Using env: {'DISPLAY': ':0', 00:18:35 INFO - 'HOME': '/home/cltbld', 00:18:35 INFO - 'LANG': 'en_US.UTF-8', 00:18:35 INFO - 'LANGUAGE': 'en_US:en', 00:18:35 INFO - 'LOGNAME': 'cltbld', 00:18:35 INFO - 'MAIL': '/var/mail/cltbld', 00:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:35 INFO - 'MOZ_NO_REMOTE': '1', 00:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:35 INFO - 'NO_EM_RESTART': '1', 00:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:35 INFO - 'PWD': '/builds/slave/test', 00:18:35 INFO - 'SHELL': '/bin/bash', 00:18:35 INFO - 'SHLVL': '1', 00:18:35 INFO - 'TERM': 'linux', 00:18:35 INFO - 'TMOUT': '86400', 00:18:35 INFO - 'USER': 'cltbld', 00:18:35 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:35 INFO - '_': '/tools/buildbot/bin/python'} 00:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:18:35 INFO - Cleaning up... 00:18:36 INFO - Return code: 0 00:18:36 INFO - Running post-action listener: _resource_record_post_action 00:18:36 INFO - Running post-action listener: _start_resource_monitoring 00:18:36 INFO - Starting resource monitoring. 00:18:36 INFO - [mozharness: 2016-09-08 07:18:36.027945Z] Finished create-virtualenv step (success) 00:18:36 INFO - [mozharness: 2016-09-08 07:18:36.028412Z] Running install step. 00:18:36 INFO - Running pre-action listener: _resource_record_pre_action 00:18:36 INFO - Running main action method: install 00:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:18:36 INFO - Reading from file tmpfile_stdout 00:18:36 INFO - Detecting whether we're running mozinstall >=1.0... 00:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:18:36 INFO - Reading from file tmpfile_stdout 00:18:36 INFO - Output received: 00:18:36 INFO - Usage: mozinstall [options] installer 00:18:36 INFO - Options: 00:18:36 INFO - -h, --help show this help message and exit 00:18:36 INFO - -d DEST, --destination=DEST 00:18:36 INFO - Directory to install application into. [default: 00:18:36 INFO - "/builds/slave/test"] 00:18:36 INFO - --app=APP Application being installed. [default: firefox] 00:18:36 INFO - mkdir: /builds/slave/test/build/application 00:18:36 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'] 00:18:36 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 00:18:53 INFO - Reading from file tmpfile_stdout 00:18:53 INFO - Output received: 00:18:53 INFO - /builds/slave/test/build/application/firefox/firefox 00:18:53 INFO - Running post-action listener: _resource_record_post_action 00:18:53 INFO - [mozharness: 2016-09-08 07:18:53.138811Z] Finished install step (success) 00:18:53 INFO - [mozharness: 2016-09-08 07:18:53.139090Z] Running run-tests step. 00:18:53 INFO - Running pre-action listener: _resource_record_pre_action 00:18:53 INFO - Running main action method: run_tests 00:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:18:53 INFO - Python 2.7.3 00:18:53 INFO - Return code: 0 00:18:53 INFO - grabbing minidump binary from tooltool 00:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:18:53 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 0x1f283b0>, '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 0x1f292b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f29740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:18:53 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 00:18:53 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 00:18:53 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 00:18:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:18:53 INFO - Return code: 0 00:18:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:18:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:18:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:18:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:18:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:18:53 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/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 00:18:53 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/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 00:18:53 INFO - Using env: {'DISPLAY': ':0', 00:18:53 INFO - 'HOME': '/home/cltbld', 00:18:53 INFO - 'LANG': 'en_US.UTF-8', 00:18:53 INFO - 'LANGUAGE': 'en_US:en', 00:18:53 INFO - 'LOGNAME': 'cltbld', 00:18:53 INFO - 'MAIL': '/var/mail/cltbld', 00:18:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:18:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:18:53 INFO - 'MOZ_NO_REMOTE': '1', 00:18:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:18:53 INFO - 'NO_EM_RESTART': '1', 00:18:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:18:53 INFO - 'PWD': '/builds/slave/test', 00:18:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:18:53 INFO - 'SHELL': '/bin/bash', 00:18:53 INFO - 'SHLVL': '1', 00:18:53 INFO - 'TERM': 'linux', 00:18:53 INFO - 'TMOUT': '86400', 00:18:53 INFO - 'USER': 'cltbld', 00:18:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294', 00:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:18:53 INFO - '_': '/tools/buildbot/bin/python'} 00:18:53 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/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 00:18:53 INFO - mozversion application_buildid: 20160907212216 00:18:53 INFO - mozversion application_changeset: 7d3d4f307002cc8475800df191196b94999ffd0a 00:18:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:18:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:18:53 INFO - mozversion application_name: Firefox 00:18:53 INFO - mozversion application_remotingname: firefox-dev 00:18:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:18:53 INFO - mozversion application_vendor: Mozilla 00:18:53 INFO - mozversion application_version: 50.0a2 00:18:53 INFO - mozversion platform_buildid: 20160907212216 00:18:53 INFO - mozversion platform_changeset: 7d3d4f307002cc8475800df191196b94999ffd0a 00:18:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:18:53 INFO - mozversion platform_version: 50.0a2 00:18:53 INFO - using testdate: 1473319133 00:18:53 INFO - actual date: 1473319133 00:18:53 INFO - starting webserver on 'localhost:54948' 00:18:53 INFO - SUITE-START | Running 2 tests 00:18:53 INFO - TEST-START | dromaeo_css 00:18:53 INFO - operating with platform_type : linux_ 00:18:53 INFO - Initialising browser for dromaeo_css test... 00:18:53 INFO - TEST-INFO | started process 3502 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyg9h98/profile http://localhost:54948/getInfo.html) 00:18:57 INFO - PROCESS | 3502 | __metrics Screen width/height:1600/1200 00:18:57 INFO - PROCESS | 3502 | colorDepth:24 00:18:57 INFO - PROCESS | 3502 | Browser inner width/height: 1024/697 00:18:57 INFO - PROCESS | 3502 | __metrics 00:18:58 INFO - PROCESS | 3502 | 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" 00:18:58 INFO - PROCESS | 3502 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:18:58 INFO - PROCESS | 3502 | console.error: PushService: 00:18:58 INFO - PROCESS | 3502 | stateChangeProcessEnqueue: Error transitioning state 00:18:58 INFO - PROCESS | 3502 | UnknownError 00:18:59 INFO - TEST-INFO | 3502: exit 0 00:18:59 INFO - Browser initialized. 00:18:59 INFO - Running cycle 1/1 for dromaeo_css test... 00:18:59 INFO - TEST-INFO | started process 3591 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyg9h98/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:19:10 INFO - PROCESS | 3591 | RSS: Main: 145035264 00:19:10 INFO - PROCESS | 3591 | 00:20:01 INFO - PROCESS | 3591 | 1473319201789 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] 00:22:11 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54948/tests/dromaeo/cssquery-ext.html) 00:22:12 INFO - PROCESS | 3591 | RSS: Main: 274440192 00:22:12 INFO - PROCESS | 3591 | 00:25:14 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-ext.html (next: http://localhost:54948/tests/dromaeo/cssquery-jquery.html) 00:25:15 INFO - PROCESS | 3591 | RSS: Main: 235008000 00:25:15 INFO - PROCESS | 3591 | 00:28:17 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54948/tests/dromaeo/cssquery-mootools.html) 00:28:17 INFO - PROCESS | 3591 | RSS: Main: 225243136 00:28:17 INFO - PROCESS | 3591 | 00:31:19 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54948/tests/dromaeo/cssquery-prototype.html) 00:31:20 INFO - PROCESS | 3591 | RSS: Main: 174428160 00:31:20 INFO - PROCESS | 3591 | 00:34:22 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54948/tests/dromaeo/cssquery-yui.html) 00:34:23 INFO - PROCESS | 3591 | RSS: Main: 176619520 00:34:23 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | Cycle 1(1): loaded http://localhost:54948/tests/dromaeo/cssquery-yui.html (next: http://localhost:54948/tests/dromaeo/cssquery-dojo.html) 00:37:26 INFO - PROCESS | 3591 | RSS: Main: 257105920 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | __start_tp_report 00:37:26 INFO - PROCESS | 3591 | _x_x_mozilla_page_load 00:37:26 INFO - PROCESS | 3591 | _x_x_mozilla_page_load_details 00:37:26 INFO - PROCESS | 3591 | |i|pagename|runs| 00:37:26 INFO - PROCESS | 3591 | |0;dojo.html;3225.161458020915;3237.9352412951744;3245.918852028699;3264.8041117532935;3285.7535684823642;5574.136008918619;5656.434356564347;5678.205051292823;5693.886122277562;5710.7430165642545;5649.915251271234;5735.139729040646;5739.311282646088;5748.5975981681295;5751.200662985628;4867.68360056597;4979.45226025137;5173.974130129353;5212.374515058198;5267;5029.647924645276;5030.320906677588;5068.974655126719;5083.110455670261;5090.083784918713;5091.949080509203;5129.717865517395;5150.948490515084;5169.896602067956;5178.922316165242;5212.6611770235195;5222.973885130569;5225.686458812445;5229.712365819878;5260.079486089919;4898.314236006961;5053;5122.129238029544;5126.384833819928;5139.691618502902;5490.972545137289;5508.669479831223;5540.335159780811;5547.362053363874;5566.638168519034;6030.7889223876955;6060.606060606046;6077.209962704865;6087.573869829113;6094.878102437927;5732.684702341369;5989.520838332923;6007.459328660441;6055.6063855849225;6101.786437474711;5682.715864206773;5708.343540492897;5709.486146246774;5722.628029178131;5770.653760774367;6221;6273.529485288622;6282.089097080898;6285.779997700059;6289.654069026156;5661.830145095653;5761.366249712528;5855.59010869235;5899.587028907935;5980.282366116007;6045.939540604626;6065.763022232795;6094.725737341831;6095;6101.023836186278;6007.939920600738;6017.247844019497;6018.398160183947;6019.367966363468;6023.578349515492;5734.426557344233;5777.0467872801355;5903.498202652737;5966.433188847052;5972.163897054415;4605.470370907322;4639.628829693616;4641.6518761092375;4667.6265898728025;4686.906261874743;4143.4199212110325;4162.375643653416;4166.375043743463;4169.999200191993;4187.476565429321;5507.669539827623;5716.628419152742;5777.104548794943;5782.10377391505;5809.564282678732;6084.178635884099;6086.360932102154;6089.634621922699;6090.3605121461605;6097.878042439215;5805.941940580625;5806.1581070744505;5823.5923485355615;5827.184194212814;5829.94170058294;4939.925901111486;5090.389153301628;5130.153524668388;5133.4609865964285;5166.896662066738;6305.936940630627;6311.147995020705;6319.842003949846;6325.240971083499;6325.493960483058;6001.279846418458;6070.332263451105;6071.908921366095;6072.514198863992;6078.45293923549;5904.61619994699;6214.223222097238;6286.214223222098;6287.371262873859;6294.559380843297;6009.579329446897;6027.336992930862;6028.517718582415;6032.4570788629235;6061.333253342041;6109.84725381869;6114.663693496723;6120.693965301628;6120.908186377297;6121.938780612137;5707.571932105026;5877.735501902339;5913.556483263858;5929.940700592939;5982.700864956648;5935.109733540004;5992.340842507408;5994.220751302303;5995.9700201500455;6012.669303188367;5934.3175534814045;5939.970300148471;5969.492593129633;5972.5520585955355;5980.43185897339;5706.885862282648;5851.64890106595;5908.05471124618;5950.940490595039;5989.1914591529585;5876.382979787232;5882.823515294548;5883.323417806836;5890.7054647266605;5917.082852157922 00:37:26 INFO - PROCESS | 3591 | |1;ext.html;590.616099535302;626.4737620398864;643.4209211709458;646.7154452041103;673.942714869236;34666.480002799944;45738.31392529115;47176.52823471764;47183.292250616185;47256.76371618141;48822.755886220606;49041.75479122599;49273.753631231884;49287.014259714786;49319;8571.314294856396;9291.953540232289;9437.669681561147;9463;11100.111991040738;10935.343879367263;11404.828927566094;11447.542098316057;11448.255863368897;11454;11370.0903927686;11422.771544569103;11476.254043487188;11483.196176267624;11488.483018264156;8370.455920365157;11024.173187011007;11356.261842980262;11369.829452558177;11435.371054592035;11025.062869656089;11113.499892504857;11126.221164518487;11126.499307531181;11126.944365278121;45688.543114569096;46002.53997460016;46085.76957115227;46166.76916615429;46226.53773462289;3704.870329538453;3942.8619998299923;4042.6563742081958;4334.263175260214;4404.757738324391;35851.4622280666;35921.64078359235;35964;36013.45979810305;36076;4788.688735232199;4848.636352273558;4849.660523763338;4853.417589889201;4939.110960027194;386607;387212;387795.9999999972;388264;388378;64889.35110648833;65082.34917650858;65242.347576524575;65291.34708652948;65529.344706553275;38661.613383866366;39730.80134599309;67120.3287967114;67333;67339;17554.824451755576;17869.374571889923;17879.195436205402;17920.462386128438;17952.281908723504;12535.185212961129;13284.601461955963;13649.522266720616;13649.727005459836;13663.931680341733;19962.70055949162;20323.898380508002;20368.18527258893;20531.897340513202;20603.381898543066;7322.231165727629;7419.85160296791;7424.740134095388;7641.197674244236;7713.4214933880185;1069.6042464288264;1088.820344643125;1112.7273817914502;1132.3885102044803;1136.8749437561862;35450.82274588611;35516.46725299123;35756;35763.4635480468;35795.28409431797;1162.2096974057554;1166.049669519339;1188.7206506470973;1219.408586835384;1225.6078055022308;17336.04651744141;17481.388151414638;17488.562785930455;17497.300107995794;17578.384756533458;616214;623594;623640;624036;625566;63560.68219658872;63894.68052659707;64019.6799016002;64143.67928160423;64213.35786642074;1349.2646507653353;1427.193635595885;1437.417845772443;1474.1376294867407;1499.4002399040253;33869;35931.82034089813;36221.456678150375;36235.818820905726;36463.81768091142;54187;54836.45163548473;54868.45131548633;54916;54974;470099;472828;473836;474047;474554;30651;31989;32079.518807217442;33457.83271083726;33498.832505837316;53477;53529.464705352446;53684;53951.19073213827;54027;44275;48849.26726099182;49946;49948;50157.439515324106;46969.765151174026;46993;47009.29486057643;47344.28983565318;47824.063530413434;46350.76824615855;47089;47585.76207118942;47668.52331476641;47708;38839.61160388473;40554.36396634209;41100.383494248206;41228.79385603053;41354;30573.388532228786;30706.846465768424;30845.537316939815;30928;31331.216719582193 00:37:26 INFO - PROCESS | 3591 | |2;jquery.html;361.01803095580016;378.2869291385738;385.8186652273434;387.09741619938006;413.51618606230727;18971.905140474297;20494.487637809045;20622.17511299546;20625.484362890915;20962.790372096275;18173.727394089066;18286.268549258046;18558.536036599056;18622.25510979559;18760.43718688438;5615.213870058195;5762.222100016486;5852.590318677694;5972.910406343908;5977.31260904995;5858.062709966406;5881.705914704269;5901.291844978608;5905.409459054082;5905.616134951214;5692.146178073302;5811.099279611667;5864;5864.560157988147;5900.970495147519;5824.854378640525;5854.97072514639;5855.151003104547;5861.062230043215;5861.85345366365;5187.584993200573;5225.947740522623;5255.553277971377;5265.736713164365;5270.367555893317;24699.135530256535;24737.87631061851;24890.875545622337;25057;25072.749272507408;2304.631258998569;2446.902123915041;2616.699079605851;2727.972720272792;2779.4024284778666;10167.244747438506;10651.63783033658;10887.945560272226;10888.564457421693;10924.453777311182;2031.8476114291486;2107.831373490117;2117.2907076129513;2122.299641118427;2147.6134295826746;61903.33803211328;75679.24320756776;80035.5998220011;84965.15034849635;88271.55864220638;9956.701298961043;10126.645567405252;10464.686059418229;11571.884281157081;11700.648980530597;15663.608409789618;16416.343346266254;16440.6711865762;16452;16469.670606587802;6585.374389432996;6619.702113404908;7962.601869906597;8306.255686305823;8559.443636163754;8069.233422824822;8102.513849169069;8107.3108785753875;8319.168083191753;8323.042850072197;4738.981119059419;4812.820858889594;4897.14299997499;4929.260610908393;5015.27278544609;1982.2566537548419;2000.109951071759;2028.320512628286;2081.760597531303;2091.1008266445574;935.5649622925372;975.317277905469;991.2367477042637;1003.1924300937815;1073.7637719701654;20332.390028299174;20927.26754563583;21061.578768424853;21174.258900938392;21358.893205534183;983.8917719050899;1026.4200726589454;1034.4207243943415;1047.2878442659064;1095.2333366643381;10899.945500272448;11778.057755379548;11793.941030294794;11799.469023893946;11937.224080434744;525015;529204;532399;563301;564268;92511.53744231371;93603;94420;94544.52727736456;95627;972.3388096094724;974.8635191073255;980.4656462228103;991.9900800992042;1029.3155051890708;18171.909140454212;18215.726764098814;18612.813871861108;18844.246230150642;18921.716174257122;42792;44523;44978.55021449875;45053.32420013637;45295.547044529136;58043.4195658038;59349.10976335272;59422;61324;61669;8297.691180547477;8597.742067737978;8764.517951512727;8813.559322034002;9148.954255228682;12026.05895714271;12805.167664101804;12833.358332083546;13196.20822750638;13318.800217996544;12440.253584784941;12601.243925364508;12616.936915315364;12763.361831908182;12889.935550322189;12110.697232569257;12186.2078964867;12297.631071067883;12487.750244995232;12548.24710517372;12803.10378273512;12859.742805143658;12941.223526588436;12961.02792290563;12984.93507532494;8892.199702026848;8924.241439477717;8984;9009.189172974464;9248.445093294424;9415.482148481626;10936.56253749841;11758.412079395825;12513.374331283581;13097.934510327388 00:37:26 INFO - PROCESS | 3591 | |3;mootools.html;1335.1188215777588;1486.5243122200893;1532.3410933298683;1687.594977205471;1877.859160562958;8021.157778433268;9087.227585655219;9392;9428.38715483493;9806.21550275978;9630.662926797564;9662.082102200297;9742.902570974305;9745.0742179493;9771.364861284012;2336.369180321308;2348.9177878774244;2355.7997570206467;2361.787439130478;2363.7399886012527;2288.1533832482005;2336;2340.8946597403155;2343.0979073056824;2345.413646588353;2322.883855807211;2326.3718795925092;2330.8601483910934;2377.3581133094053;2379.7858192762646;2262.2534563593977;2317.2353123469297;2328.0338659456306;2329.6505524171425;2336.707911511061;2288.725352957639;2291.3355127013147;2292.56441276157;2292.977070229293;2294.6672732453867;9460.290478214161;9670.854937175878;9716.465594392306;9720.368176068532;9728.659496917644;5144.794208231666;5365.812196573102;5377.0321342158395;5382.542483888874;5384.7846086156505;3502.4045912194733;3504.8072356020407;3555.7510974231814;3562.5190599269285;3574.428091505373;1144.4621028116771;1144.6680462665818;1152.4353066997194;1161.976760464786;1184.4373922386796;13269.402876870565;13273.137246078973;13350.26573538455;13405.195688258638;13438.932805335911;4405.493368262691;4556.476005259437;4649.5815376616265;4696.436427628706;4709.340692303081;10634.255602107778;10635;10650.786984260427;10655.094316982988;10680.519376627915;5452.672839629635;5455;5459.781608735686;5466.371367292732;5467.17992301158;4392.758398288125;4877.6585639004925;5028.245763135559;5031.622628302892;5082.186850103965;3956.940645890314;3969.7816620085596;4020.577839326887;4046.0491784430637;4076.7553946762696;1124.6344937895217;1140.1107136433595;1142.731458107344;1146.9942650286696;1151.2746969409386;958.8226178157063;1006.5369929832212;1017.6336518853207;1029.0275689473376;1042.2652030318607;6204.937950620526;6351.951927931839;6495.512836537184;6529.281779004401;6603.438707709898;431.46282877817316;433.5404471260474;447.29327662293497;455.74933786418;456.44541881614;4487.6634252431195;4516.593506584423;4549.340345649928;4567.81728730847;4691.882702932454;11892.464839082262;12185.268883866996;12190.939045304716;12280.938595306967;12328.938355308166;11845.940770296093;11876.10929180315;11878.227915185485;11906.702332441759;11907.106966977339;8328.750137495885;8657.783555411164;8668.956655216683;8849.20357167858;8864.20222179994;6067.514598832171;6234.937650623436;6338.366163383625;6506.642134682638;6710.498070519621;10925.726856828644;11242.381669008282;11252.099832013582;11381.943090284496;11406.657800266004;10958;11697.883021169679;11702.765944681229;11731.00286475659;11741.354225517336;7328.450366222662;7541.509801862861;8268.669253229804;8577.871331930151;8735.65057397697;9135.543222838964;9556.95221523888;9611.711648650551;9613.990530994144;9620.518974051129;8339.916600833914;8484;8542.78643033929;8555.529445880537;8780.824383512167;8215;8285.461445006054;8299.460535065202;8388.664453421794;8531.36014798905;7395.482316237812;7615.809604759926;7699.960505331708;7704.345130663733;7724.7682569523;5166.4316925137455;5384.892302153857;5443.673379597237;5477.096279113902;5536.2249285099315;5263.34208223972;5265.552428043506;5401.837944861661;5425.077736784677;5429.077056900415 00:37:26 INFO - PROCESS | 3591 | |4;prototype.html;324.66883778545883;328.5548082348418;332.13313252411217;332.6523782647134;337.2883216413369;5532.806351777684;5589.664620122796;5596.748146333414;5614.354349249832;5620.803271885485;5524.58565607579;5527.419620939793;5528.44715528447;5553.972230138854;5573.916391254124;4143.523494798092;4209.53695093541;4328.177646247219;4387.648988080962;4400.251957167273;4289.056407590325;4328.242557552431;4339.717918335298;4360.146937551528;4369.956300437003;4298.034356294147;4320.308750599905;4326.675499337546;4328.653707703392;4331.743794299665;4079.8980025499304;4141.509056739586;4316.287812510964;4353.28170851809;4356.411884395598;3807.7524960877454;3817.828197731109;3823.50294461721;3823.9617603823885;3826.2921359548573;5396.271503347037;5406.486383793534;5452.318460192476;5489.4236105209175;5507.091329930557;4836.419629644465;4945.703257804544;4952.4057113146655;4965.180745177042;5015.3480047593575;5733.140028995643;5738.684372359518;5743.885122297531;5745.310562732457;5749.425057494217;4143.751374917515;4148.170365926797;4194.2869712149;4204.138151678899;4242.957570424287;5805.954928112938;5921.73352199152;5987.622846745273;6003.279606447254;6016.157737916739;4265.914681706348;4273.807678654468;4275.187714334266;4291.9785401072795;4292.463442069741;4326.65386769064;4346.434963454793;4364.454443194601;4385.934210986838;4387.934180987288;4859.635527335464;4896.2410826321975;4907.484714104968;4926.531979461945;4961.7271050092595;5711.257536520226;5732.570057245724;5739.512141467939;5741.13413140731;5745.482906538432;1467.288365142905;1485.3687182947203;1496.2294418374545;1498.5204734485078;1503.488813803312;937.6062053937226;956.3257903178381;958.2382006305004;977.4819345746623;980.7401038724744;5518.33779946409;5646.350669672958;5711.514521265739;5714.685692286881;5714.885702285931;5996.730147143389;6004.519638428916;6005.189299444519;6019.939800602026;6026.227935149793;5398.245570189625;5719.8570035749435;5723.627964182315;5726.141078838207;5726.167626359608;3134.843257837099;3173.508106243536;3191.505316675919;3192.345569158317;3204.6154461464776;6109.205803245639;6176.320604733476;6266.561340706106;6296.464800491918;6299.811005669837;6088.786892458743;6090.6041107328765;6098.695065246809;6101.145839582462;6112.969435152796;6241.625502469867;6264.060390941395;6265.937340626627;6267.809116267895;6283.465905398001;5859.677717725566;6003.609765365237;6013.128096425906;6037.275526936795;6042.8791424170395;5931.6441013539325;5931.961906666403;5936.554758393051;5965.940340596539;5969.7313620888035;6069.696515174135;6194.5354098443695;6216.968915155395;6231.2522497300615;6234.937650623436;3813.6164896939413;3821.980890095532;3834.6740527055513;3840.5612436102715;3845.4618152738008;5229.869253268699;5250.291210686509;5273.472652734696;5285.814996475105;5289;4878.56092951636;5095.706931880594;5130.281760553451;5139.8715032123;5152.330197074356;5095.414027386898;5159.638825282344;5176.792928282826;5232.947670523246;5237.240600113038;5157.510036546522;5159.045576568347;5195.610329225247;5230.581553475637;5546.722663866871;5330.866728331823;5731;5795.942040579709;5827.708614569339;5837.737301821343;5674.2126230237345;5678.119891416837;5694.715264236689;5703.62926409782;5706.2011318414625 00:37:26 INFO - PROCESS | 3591 | |5;yui.html;800.0719165767711;816.6080281464897;832.3091833777964;837.7161526814886;922.9215516681081;13237;14666.266686665664;14672.046316989388;14705.779413308808;14723.263836808144;13561.52534661287;13689.65775855604;13692;13700;13718.314084295771;713.678844519968;843.2452954605609;852.3436953545767;853.3216093205885;853.4751128056232;818.0428898189103;844.990236667183;847.2882778466087;852.910444403338;852.9189726975927;890.973270801877;892.7366426904047;892.7991201979568;892.8125093730324;892.8482158033117;742.2392048150634;884.6815146547237;886.1492966751925;886.2245535156706;886.3086792302015;635.7361694896613;651.1047309948821;651.4234902111618;656.1273506236741;656.2485953583168;13818.170909745348;13942.23317717525;13952.860471395257;14113.506027288995;14175.716485670331;14313.141211527342;14657.853421465756;15214.46749363778;15222.923885380502;15223.923880380638;13375.33123343829;13751.793723094062;13789.241591712453;13790.379432925442;14099.50651727195;3907.7069219808627;4018.7588744675413;4090.3046482098116;4140.296149654566;4258.808353624095;87848.56075719581;88922.5553872233;89283.55358223167;89351;90482.54758726164;10209.540570674337;10484;10492.632757853438;10493.265471416928;10646.307989980627;20372.69440958387;20390.592188156155;20399;20402.489937751674;20409.183632654825;8163;8178.345732341399;8239.588020599065;8391.286740626992;8478.321734261244;4659.184642687584;4811.9518804811505;4941.827036053721;5069.036882992294;5104.846854594294;3729.1236559408517;3880.5731369549326;3882.864099756495;3884.3590807516443;3884.7863367515056;653.1085068880943;659.0608383054224;664.1565212180503;673.2829536543663;674.0664180110484;662.7812821768805;769.6805825582433;770.3913908012607;788.491423032141;792.1167897794033;10010.749731256778;10218.795624087625;10396;10729.678109656723;10770.246082774287;727.7671145233475;762.938964882808;805.1867613710195;812.6244890052262;818.5211651184005;4636.397268355092;4869.975650121798;4887.315775791392;5005.374328208974;5021.49785021495;136236;138791;139002;140045;140786;97361;116892;118387;121834.39082804706;122458;102882;103440;104301;104860;105082.47458762657;9411.200047995997;9671.371360861522;9732.756681082747;9784.168345690412;9936.900630993598;45603.315950261436;61584.38415615966;61639.69180154071;62001.37998619956;62820.68589657023;65858.67070664615;80743.59628201822;80872;82473.17526824895;83626;13872.237026963614;14008.299585020912;14244.430222790972;14465.710685786436;14523.128612283297;15301.693966120838;15605.609859753597;15733.763993539877;15793.526194214191;16252.431164909645;14267.286635667968;14321.283935803376;14489.78265326;14674.55976320712;15162.31769570394;14222.217778022308;14228;14361.569152925429;14413.85586144125;14567.708645827237;13866.722665546835;14428.685577307064;14758.48345307909;15063.54809355721;15727.764083538985;11267.718307042389;11279;11586.304821710724;11864.711164322198;12188.634340969786;11904.464299106388;12239.388030598257;12350.876491234972;12364.814527781911;12540.623781286577 00:37:26 INFO - PROCESS | 3591 | __end_tp_report 00:37:26 INFO - PROCESS | 3591 | __start_cc_report 00:37:26 INFO - PROCESS | 3591 | _x_x_mozilla_cycle_collect,1168 00:37:26 INFO - PROCESS | 3591 | __end_cc_report 00:37:26 INFO - PROCESS | 3591 | __startTimestamp1473320246312__endTimestamp 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | ------- Summary: start ------- 00:37:26 INFO - PROCESS | 3591 | Number of tests: 6 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#0] dojo.html Cycles:165 Average:5620.18 Median:5851.65 stddev:648.01 (11.1%) stddev-sans-first:622.16 00:37:26 INFO - PROCESS | 3591 | Values: 3225.2 3237.9 3245.9 3264.8 3285.8 5574.1 5656.4 5678.2 5693.9 5710.7 5649.9 5735.1 5739.3 5748.6 5751.2 4867.7 4979.5 5174.0 5212.4 5267.0 5029.6 5030.3 5069.0 5083.1 5090.1 5091.9 5129.7 5150.9 5169.9 5178.9 5212.7 5223.0 5225.7 5229.7 5260.1 4898.3 5053.0 5122.1 5126.4 5139.7 5491.0 5508.7 5540.3 5547.4 5566.6 6030.8 6060.6 6077.2 6087.6 6094.9 5732.7 5989.5 6007.5 6055.6 6101.8 5682.7 5708.3 5709.5 5722.6 5770.7 6221.0 6273.5 6282.1 6285.8 6289.7 5661.8 5761.4 5855.6 5899.6 5980.3 6045.9 6065.8 6094.7 6095.0 6101.0 6007.9 6017.2 6018.4 6019.4 6023.6 5734.4 5777.0 5903.5 5966.4 5972.2 4605.5 4639.6 4641.7 4667.6 4686.9 4143.4 4162.4 4166.4 4170.0 4187.5 5507.7 5716.6 5777.1 5782.1 5809.6 6084.2 6086.4 6089.6 6090.4 6097.9 5805.9 5806.2 5823.6 5827.2 5829.9 4939.9 5090.4 5130.2 5133.5 5166.9 6305.9 6311.1 6319.8 6325.2 6325.5 6001.3 6070.3 6071.9 6072.5 6078.5 5904.6 6214.2 6286.2 6287.4 6294.6 6009.6 6027.3 6028.5 6032.5 6061.3 6109.8 6114.7 6120.7 6120.9 6121.9 5707.6 5877.7 5913.6 5929.9 5982.7 5935.1 5992.3 5994.2 5996.0 6012.7 5934.3 5940.0 5969.5 5972.6 5980.4 5706.9 5851.6 5908.1 5950.9 5989.2 5876.4 5882.8 5883.3 5890.7 5917.1 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#1] ext.html Cycles:180 Average:67082.76 Median:21886.73 stddev:133710.98 (610.9%) stddev-sans-first:133992.88 00:37:26 INFO - PROCESS | 3591 | Values: 590.6 626.5 643.4 646.7 673.9 34666.5 45738.3 47176.5 47183.3 47256.8 48822.8 49041.8 49273.8 49287.0 49319.0 8571.3 9292.0 9437.7 9463.0 11100.1 10935.3 11404.8 11447.5 11448.3 11454.0 11370.1 11422.8 11476.3 11483.2 11488.5 8370.5 11024.2 11356.3 11369.8 11435.4 11025.1 11113.5 11126.2 11126.5 11126.9 45688.5 46002.5 46085.8 46166.8 46226.5 3704.9 3942.9 4042.7 4334.3 4404.8 35851.5 35921.6 35964.0 36013.5 36076.0 4788.7 4848.6 4849.7 4853.4 4939.1 386607.0 387212.0 387796.0 388264.0 388378.0 64889.4 65082.3 65242.3 65291.3 65529.3 38661.6 39730.8 67120.3 67333.0 67339.0 17554.8 17869.4 17879.2 17920.5 17952.3 12535.2 13284.6 13649.5 13649.7 13663.9 19962.7 20323.9 20368.2 20531.9 20603.4 7322.2 7419.9 7424.7 7641.2 7713.4 1069.6 1088.8 1112.7 1132.4 1136.9 35450.8 35516.5 35756.0 35763.5 35795.3 1162.2 1166.0 1188.7 1219.4 1225.6 17336.0 17481.4 17488.6 17497.3 17578.4 616214.0 623594.0 623640.0 624036.0 625566.0 63560.7 63894.7 64019.7 64143.7 64213.4 1349.3 1427.2 1437.4 1474.1 1499.4 33869.0 35931.8 36221.5 36235.8 36463.8 54187.0 54836.5 54868.5 54916.0 54974.0 470099.0 472828.0 473836.0 474047.0 474554.0 30651.0 31989.0 32079.5 33457.8 33498.8 53477.0 53529.5 53684.0 53951.2 54027.0 44275.0 48849.3 49946.0 49948.0 50157.4 46969.8 46993.0 47009.3 47344.3 47824.1 46350.8 47089.0 47585.8 47668.5 47708.0 38839.6 40554.4 41100.4 41228.8 41354.0 30573.4 30706.8 30845.5 30928.0 31331.2 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#2] jquery.html Cycles:180 Average:30881.24 Median:369.65 stddev:89276.87 (24151.6%) stddev-sans-first:89497.91 00:37:26 INFO - PROCESS | 3591 | Values: 361.0 378.3 385.8 387.1 413.5 18971.9 20494.5 20622.2 20625.5 20962.8 18173.7 18286.3 18558.5 18622.3 18760.4 5615.2 5762.2 5852.6 5972.9 5977.3 5858.1 5881.7 5901.3 5905.4 5905.6 5692.1 5811.1 5864.0 5864.6 5901.0 5824.9 5855.0 5855.2 5861.1 5861.9 5187.6 5225.9 5255.6 5265.7 5270.4 24699.1 24737.9 24890.9 25057.0 25072.7 2304.6 2446.9 2616.7 2728.0 2779.4 10167.2 10651.6 10887.9 10888.6 10924.5 2031.8 2107.8 2117.3 2122.3 2147.6 61903.3 75679.2 80035.6 84965.2 88271.6 9956.7 10126.6 10464.7 11571.9 11700.6 15663.6 16416.3 16440.7 16452.0 16469.7 6585.4 6619.7 7962.6 8306.3 8559.4 8069.2 8102.5 8107.3 8319.2 8323.0 4739.0 4812.8 4897.1 4929.3 5015.3 1982.3 2000.1 2028.3 2081.8 2091.1 935.6 975.3 991.2 1003.2 1073.8 20332.4 20927.3 21061.6 21174.3 21358.9 983.9 1026.4 1034.4 1047.3 1095.2 10899.9 11778.1 11793.9 11799.5 11937.2 525015.0 529204.0 532399.0 563301.0 564268.0 92511.5 93603.0 94420.0 94544.5 95627.0 972.3 974.9 980.5 992.0 1029.3 18171.9 18215.7 18612.8 18844.2 18921.7 42792.0 44523.0 44978.6 45053.3 45295.5 58043.4 59349.1 59422.0 61324.0 61669.0 8297.7 8597.7 8764.5 8813.6 9149.0 12026.1 12805.2 12833.4 13196.2 13318.8 12440.3 12601.2 12616.9 12763.4 12889.9 12110.7 12186.2 12297.6 12487.8 12548.2 12803.1 12859.7 12941.2 12961.0 12984.9 8892.2 8924.2 8984.0 9009.2 9248.4 9415.5 10936.6 11758.4 12513.4 13097.9 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#3] mootools.html Cycles:180 Average:6197.34 Median:4532.97 stddev:3695.60 (81.5%) stddev-sans-first:3687.90 00:37:26 INFO - PROCESS | 3591 | Values: 1335.1 1486.5 1532.3 1687.6 1877.9 8021.2 9087.2 9392.0 9428.4 9806.2 9630.7 9662.1 9742.9 9745.1 9771.4 2336.4 2348.9 2355.8 2361.8 2363.7 2288.2 2336.0 2340.9 2343.1 2345.4 2322.9 2326.4 2330.9 2377.4 2379.8 2262.3 2317.2 2328.0 2329.7 2336.7 2288.7 2291.3 2292.6 2293.0 2294.7 9460.3 9670.9 9716.5 9720.4 9728.7 5144.8 5365.8 5377.0 5382.5 5384.8 3502.4 3504.8 3555.8 3562.5 3574.4 1144.5 1144.7 1152.4 1162.0 1184.4 13269.4 13273.1 13350.3 13405.2 13438.9 4405.5 4556.5 4649.6 4696.4 4709.3 10634.3 10635.0 10650.8 10655.1 10680.5 5452.7 5455.0 5459.8 5466.4 5467.2 4392.8 4877.7 5028.2 5031.6 5082.2 3956.9 3969.8 4020.6 4046.0 4076.8 1124.6 1140.1 1142.7 1147.0 1151.3 958.8 1006.5 1017.6 1029.0 1042.3 6204.9 6352.0 6495.5 6529.3 6603.4 431.5 433.5 447.3 455.7 456.4 4487.7 4516.6 4549.3 4567.8 4691.9 11892.5 12185.3 12190.9 12280.9 12328.9 11845.9 11876.1 11878.2 11906.7 11907.1 8328.8 8657.8 8669.0 8849.2 8864.2 6067.5 6234.9 6338.4 6506.6 6710.5 10925.7 11242.4 11252.1 11381.9 11406.7 10958.0 11697.9 11702.8 11731.0 11741.4 7328.5 7541.5 8268.7 8577.9 8735.7 9135.5 9557.0 9611.7 9614.0 9620.5 8339.9 8484.0 8542.8 8555.5 8780.8 8215.0 8285.5 8299.5 8388.7 8531.4 7395.5 7615.8 7700.0 7704.3 7724.8 5166.4 5384.9 5443.7 5477.1 5536.2 5263.3 5265.6 5401.8 5425.1 5429.1 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#4] prototype.html Cycles:180 Average:4825.95 Median:5397.26 stddev:1424.09 (26.4%) stddev-sans-first:1387.43 00:37:26 INFO - PROCESS | 3591 | Values: 324.7 328.6 332.1 332.7 337.3 5532.8 5589.7 5596.7 5614.4 5620.8 5524.6 5527.4 5528.4 5554.0 5573.9 4143.5 4209.5 4328.2 4387.6 4400.3 4289.1 4328.2 4339.7 4360.1 4370.0 4298.0 4320.3 4326.7 4328.7 4331.7 4079.9 4141.5 4316.3 4353.3 4356.4 3807.8 3817.8 3823.5 3824.0 3826.3 5396.3 5406.5 5452.3 5489.4 5507.1 4836.4 4945.7 4952.4 4965.2 5015.3 5733.1 5738.7 5743.9 5745.3 5749.4 4143.8 4148.2 4194.3 4204.1 4243.0 5806.0 5921.7 5987.6 6003.3 6016.2 4265.9 4273.8 4275.2 4292.0 4292.5 4326.7 4346.4 4364.5 4385.9 4387.9 4859.6 4896.2 4907.5 4926.5 4961.7 5711.3 5732.6 5739.5 5741.1 5745.5 1467.3 1485.4 1496.2 1498.5 1503.5 937.6 956.3 958.2 977.5 980.7 5518.3 5646.4 5711.5 5714.7 5714.9 5996.7 6004.5 6005.2 6019.9 6026.2 5398.2 5719.9 5723.6 5726.1 5726.2 3134.8 3173.5 3191.5 3192.3 3204.6 6109.2 6176.3 6266.6 6296.5 6299.8 6088.8 6090.6 6098.7 6101.1 6113.0 6241.6 6264.1 6265.9 6267.8 6283.5 5859.7 6003.6 6013.1 6037.3 6042.9 5931.6 5932.0 5936.6 5965.9 5969.7 6069.7 6194.5 6217.0 6231.3 6234.9 3813.6 3822.0 3834.7 3840.6 3845.5 5229.9 5250.3 5273.5 5285.8 5289.0 4878.6 5095.7 5130.3 5139.9 5152.3 5095.4 5159.6 5176.8 5232.9 5237.2 5157.5 5159.0 5195.6 5230.6 5546.7 5330.9 5731.0 5795.9 5827.7 5837.7 5674.2 5678.1 5694.7 5703.6 5706.2 00:37:26 INFO - PROCESS | 3591 | 00:37:26 INFO - PROCESS | 3591 | [#5] yui.html Cycles:180 Average:23266.05 Median:3881.72 stddev:35458.77 (913.5%) stddev-sans-first:35518.12 00:37:26 INFO - PROCESS | 3591 | Values: 800.1 816.6 832.3 837.7 922.9 13237.0 14666.3 14672.0 14705.8 14723.3 13561.5 13689.7 13692.0 13700.0 13718.3 713.7 843.2 852.3 853.3 853.5 818.0 845.0 847.3 852.9 852.9 891.0 892.7 892.8 892.8 892.8 742.2 884.7 886.1 886.2 886.3 635.7 651.1 651.4 656.1 656.2 13818.2 13942.2 13952.9 14113.5 14175.7 14313.1 14657.9 15214.5 15222.9 15223.9 13375.3 13751.8 13789.2 13790.4 14099.5 3907.7 4018.8 4090.3 4140.3 4258.8 87848.6 88922.6 89283.6 89351.0 90482.5 10209.5 10484.0 10492.6 10493.3 10646.3 20372.7 20390.6 20399.0 20402.5 20409.2 8163.0 8178.3 8239.6 8391.3 8478.3 4659.2 4812.0 4941.8 5069.0 5104.8 3729.1 3880.6 3882.9 3884.4 3884.8 653.1 659.1 664.2 673.3 674.1 662.8 769.7 770.4 788.5 792.1 10010.7 10218.8 10396.0 10729.7 10770.2 727.8 762.9 805.2 812.6 818.5 4636.4 4870.0 4887.3 5005.4 5021.5 136236.0 138791.0 139002.0 140045.0 140786.0 97361.0 116892.0 118387.0 121834.4 122458.0 102882.0 103440.0 104301.0 104860.0 105082.5 9411.2 9671.4 9732.8 9784.2 9936.9 45603.3 61584.4 61639.7 62001.4 62820.7 65858.7 80743.6 80872.0 82473.2 83626.0 13872.2 14008.3 14244.4 14465.7 14523.1 15301.7 15605.6 15733.8 15793.5 16252.4 14267.3 14321.3 14489.8 14674.6 15162.3 14222.2 14228.0 14361.6 14413.9 14567.7 13866.7 14428.7 14758.5 15063.5 15727.8 11267.7 11279.0 11586.3 11864.7 12188.6 11904.5 12239.4 12350.9 12364.8 12540.6 00:37:26 INFO - PROCESS | 3591 | -------- Summary: end -------- 00:37:26 INFO - PROCESS | 3591 | 00:37:27 INFO - TEST-INFO | 3591: exit 0 00:37:27 INFO - TEST-OK | dromaeo_css | took 1113715ms 00:37:27 INFO - TEST-START | kraken 00:37:27 INFO - operating with platform_type : linux_ 00:37:27 INFO - Initialising browser for kraken test... 00:37:27 INFO - TEST-INFO | started process 3787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVcwtIj/profile http://localhost:54948/getInfo.html) 00:37:31 INFO - PROCESS | 3787 | __metrics Screen width/height:1600/1200 00:37:31 INFO - PROCESS | 3787 | colorDepth:24 00:37:31 INFO - PROCESS | 3787 | Browser inner width/height: 1024/697 00:37:31 INFO - PROCESS | 3787 | __metrics 00:37:31 INFO - PROCESS | 3787 | 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" 00:37:31 INFO - PROCESS | 3787 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:37:32 INFO - TEST-INFO | 3787: exit 0 00:37:32 INFO - Browser initialized. 00:37:32 INFO - Running cycle 1/1 for kraken test... 00:37:32 INFO - TEST-INFO | started process 3878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVcwtIj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:37:44 INFO - PROCESS | 3878 | RSS: Main: 145002496 00:37:44 INFO - PROCESS | 3878 | 00:37:47 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54948/tests/kraken/driver.html?testName=audio-beat-detection) 00:37:47 INFO - PROCESS | 3878 | RSS: Main: 191057920 00:37:47 INFO - PROCESS | 3878 | 00:37:50 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54948/tests/kraken/driver.html?testName=audio-dft) 00:37:51 INFO - PROCESS | 3878 | RSS: Main: 182054912 00:37:51 INFO - PROCESS | 3878 | 00:37:53 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54948/tests/kraken/driver.html?testName=audio-fft) 00:37:54 INFO - PROCESS | 3878 | RSS: Main: 270987264 00:37:54 INFO - PROCESS | 3878 | 00:37:56 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54948/tests/kraken/driver.html?testName=audio-oscillator) 00:37:57 INFO - PROCESS | 3878 | RSS: Main: 175521792 00:37:57 INFO - PROCESS | 3878 | 00:37:59 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:37:59 INFO - PROCESS | 3878 | RSS: Main: 177160192 00:37:59 INFO - PROCESS | 3878 | 00:38:02 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54948/tests/kraken/driver.html?testName=imaging-darkroom) 00:38:03 INFO - PROCESS | 3878 | RSS: Main: 213577728 00:38:03 INFO - PROCESS | 3878 | 00:38:06 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54948/tests/kraken/driver.html?testName=imaging-desaturate) 00:38:07 INFO - PROCESS | 3878 | RSS: Main: 212205568 00:38:07 INFO - PROCESS | 3878 | 00:38:10 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54948/tests/kraken/driver.html?testName=json-parse-financial) 00:38:10 INFO - PROCESS | 3878 | RSS: Main: 211386368 00:38:10 INFO - PROCESS | 3878 | 00:38:12 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:38:12 INFO - PROCESS | 3878 | RSS: Main: 176275456 00:38:12 INFO - PROCESS | 3878 | 00:38:14 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:38:15 INFO - PROCESS | 3878 | RSS: Main: 182640640 00:38:15 INFO - PROCESS | 3878 | 00:38:17 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:38:17 INFO - PROCESS | 3878 | RSS: Main: 186556416 00:38:17 INFO - PROCESS | 3878 | 00:38:20 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:38:20 INFO - PROCESS | 3878 | RSS: Main: 180297728 00:38:20 INFO - PROCESS | 3878 | 00:38:23 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:38:24 INFO - PROCESS | 3878 | RSS: Main: 184307712 00:38:24 INFO - PROCESS | 3878 | 00:38:25 INFO - PROCESS | 3878 | Cycle 1(1): loaded http://localhost:54948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54948/tests/kraken/driver.html?testName=ai-astar) 00:38:26 INFO - PROCESS | 3878 | RSS: Main: 236445696 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | __start_tp_report 00:38:26 INFO - PROCESS | 3878 | _x_x_mozilla_page_load 00:38:26 INFO - PROCESS | 3878 | _x_x_mozilla_page_load_details 00:38:26 INFO - PROCESS | 3878 | |i|pagename|runs| 00:38:26 INFO - PROCESS | 3878 | |0;ai-astar;99;110;111;110;110;110;109;109;108;109 00:38:26 INFO - PROCESS | 3878 | |1;audio-beat-detection;141;121;122;170;116;119;144;116;148;117 00:38:26 INFO - PROCESS | 3878 | |2;audio-dft;164;161;161;160;160;160;160;160;161;159 00:38:26 INFO - PROCESS | 3878 | |3;audio-fft;75;74;74;112;77;75;74;76;104;74 00:38:26 INFO - PROCESS | 3878 | |4;audio-oscillator;83;82;124;83;83;83;122;83;84;126 00:38:26 INFO - PROCESS | 3878 | |5;imaging-gaussian-blur;116;117;116;116;117;116;118;117;116;117 00:38:26 INFO - PROCESS | 3878 | |6;imaging-darkroom;146;146;146;145;147;146;146;146;147;146 00:38:26 INFO - PROCESS | 3878 | |7;imaging-desaturate;94;95;94;94;95;94;95;94;94;95 00:38:26 INFO - PROCESS | 3878 | |8;json-parse-financial;50;49;50;49;49;53;56;50;49;51 00:38:26 INFO - PROCESS | 3878 | |9;json-stringify-tinderbox;60;62;60;59;60;60;60;60;60;60 00:38:26 INFO - PROCESS | 3878 | |10;stanford-crypto-aes;78;77;73;73;73;73;77;81;76;72 00:38:26 INFO - PROCESS | 3878 | |11;stanford-crypto-ccm;131;118;126;126;115;117;125;116;161;126 00:38:26 INFO - PROCESS | 3878 | |12;stanford-crypto-pbkdf2;150;156;150;154;155;153;151;151;151;148 00:38:26 INFO - PROCESS | 3878 | |13;stanford-crypto-sha256-iterative;65;72;62;60;69;62;62;60;59;60 00:38:26 INFO - PROCESS | 3878 | __end_tp_report 00:38:26 INFO - PROCESS | 3878 | __start_cc_report 00:38:26 INFO - PROCESS | 3878 | _x_x_mozilla_cycle_collect,1635 00:38:26 INFO - PROCESS | 3878 | __end_cc_report 00:38:26 INFO - PROCESS | 3878 | __startTimestamp1473320306082__endTimestamp 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | ------- Summary: start ------- 00:38:26 INFO - PROCESS | 3878 | Number of tests: 14 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.44 (3.1%) stddev-sans-first:0.88 00:38:26 INFO - PROCESS | 3878 | Values: 99.0 110.0 111.0 110.0 110.0 110.0 109.0 109.0 108.0 109.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:131.50 stddev:18.40 (14.0%) stddev-sans-first:19.19 00:38:26 INFO - PROCESS | 3878 | Values: 141.0 121.0 122.0 170.0 116.0 119.0 144.0 116.0 148.0 117.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.35 (0.8%) stddev-sans-first:0.67 00:38:26 INFO - PROCESS | 3878 | Values: 164.0 161.0 161.0 160.0 160.0 160.0 160.0 160.0 161.0 159.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#3] audio-fft Cycles:10 Average:81.50 Median:74.50 stddev:14.13 (19.0%) stddev-sans-first:14.79 00:38:26 INFO - PROCESS | 3878 | Values: 75.0 74.0 74.0 112.0 77.0 75.0 74.0 76.0 104.0 74.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:83.00 stddev:19.83 (23.9%) stddev-sans-first:20.53 00:38:26 INFO - PROCESS | 3878 | Values: 83.0 82.0 124.0 83.0 83.0 83.0 122.0 83.0 84.0 126.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 00:38:26 INFO - PROCESS | 3878 | Values: 116.0 117.0 116.0 116.0 117.0 116.0 118.0 117.0 116.0 117.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 00:38:26 INFO - PROCESS | 3878 | Values: 146.0 146.0 146.0 145.0 147.0 146.0 146.0 146.0 147.0 146.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 00:38:26 INFO - PROCESS | 3878 | Values: 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.00 stddev:2.27 (4.5%) stddev-sans-first:2.40 00:38:26 INFO - PROCESS | 3878 | Values: 50.0 49.0 50.0 49.0 49.0 53.0 56.0 50.0 49.0 51.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 00:38:26 INFO - PROCESS | 3878 | Values: 60.0 62.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:76.50 stddev:2.95 (3.9%) stddev-sans-first:2.96 00:38:26 INFO - PROCESS | 3878 | Values: 78.0 77.0 73.0 73.0 73.0 73.0 77.0 81.0 76.0 72.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:126.00 stddev:13.40 (10.6%) stddev-sans-first:14.10 00:38:26 INFO - PROCESS | 3878 | Values: 131.0 118.0 126.0 126.0 115.0 117.0 125.0 116.0 161.0 126.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.00 stddev:2.51 (1.7%) stddev-sans-first:2.57 00:38:26 INFO - PROCESS | 3878 | Values: 150.0 156.0 150.0 154.0 155.0 153.0 151.0 151.0 151.0 148.0 00:38:26 INFO - PROCESS | 3878 | 00:38:26 INFO - PROCESS | 3878 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.00 stddev:4.31 (6.9%) stddev-sans-first:4.51 00:38:26 INFO - PROCESS | 3878 | Values: 65.0 72.0 62.0 60.0 69.0 62.0 62.0 60.0 59.0 60.0 00:38:26 INFO - PROCESS | 3878 | -------- Summary: end -------- 00:38:26 INFO - PROCESS | 3878 | 00:38:27 INFO - TEST-INFO | 3878: exit 0 00:38:27 INFO - TEST-OK | kraken | took 59915ms 00:38:27 INFO - SUITE-END | took 1173s 00:38:27 INFO - Completed test suite (00:19:33) 00:38:27 INFO - javascript benchmark 00:38:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3225.161458020915, 3237.9352412951744, 3245.918852028699, 3264.8041117532935, 3285.7535684823642, 5574.136008918619, 5656.434356564347, 5678.205051292823, 5693.886122277562, 5710.7430165642545, 5649.915251271234, 5735.139729040646, 5739.311282646088, 5748.5975981681295, 5751.200662985628, 4867.68360056597, 4979.45226025137, 5173.974130129353, 5212.374515058198, 5267.0, 5029.647924645276, 5030.320906677588, 5068.974655126719, 5083.110455670261, 5090.083784918713, 5091.949080509203, 5129.717865517395, 5150.948490515084, 5169.896602067956, 5178.922316165242, 5212.6611770235195, 5222.973885130569, 5225.686458812445, 5229.712365819878, 5260.079486089919, 4898.314236006961, 5053.0, 5122.129238029544, 5126.384833819928, 5139.691618502902, 5490.972545137289, 5508.669479831223, 5540.335159780811, 5547.362053363874, 5566.638168519034, 6030.7889223876955, 6060.606060606046, 6077.209962704865, 6087.573869829113, 6094.878102437927, 5732.684702341369, 5989.520838332923, 6007.459328660441, 6055.6063855849225, 6101.786437474711, 5682.715864206773, 5708.343540492897, 5709.486146246774, 5722.628029178131, 5770.653760774367, 6221.0, 6273.529485288622, 6282.089097080898, 6285.779997700059, 6289.654069026156, 5661.830145095653, 5761.366249712528, 5855.59010869235, 5899.587028907935, 5980.282366116007, 6045.939540604626, 6065.763022232795, 6094.725737341831, 6095.0, 6101.023836186278, 6007.939920600738, 6017.247844019497, 6018.398160183947, 6019.367966363468, 6023.578349515492, 5734.426557344233, 5777.0467872801355, 5903.498202652737, 5966.433188847052, 5972.163897054415, 4605.470370907322, 4639.628829693616, 4641.6518761092375, 4667.6265898728025, 4686.906261874743, 4143.4199212110325, 4162.375643653416, 4166.375043743463, 4169.999200191993, 4187.476565429321, 5507.669539827623, 5716.628419152742, 5777.104548794943, 5782.10377391505, 5809.564282678732, 6084.178635884099, 6086.360932102154, 6089.634621922699, 6090.3605121461605, 6097.878042439215, 5805.941940580625, 5806.1581070744505, 5823.5923485355615, 5827.184194212814, 5829.94170058294, 4939.925901111486, 5090.389153301628, 5130.153524668388, 5133.4609865964285, 5166.896662066738, 6305.936940630627, 6311.147995020705, 6319.842003949846, 6325.240971083499, 6325.493960483058, 6001.279846418458, 6070.332263451105, 6071.908921366095, 6072.514198863992, 6078.45293923549, 5904.61619994699, 6214.223222097238, 6286.214223222098, 6287.371262873859, 6294.559380843297, 6009.579329446897, 6027.336992930862, 6028.517718582415, 6032.4570788629235, 6061.333253342041, 6109.84725381869, 6114.663693496723, 6120.693965301628, 6120.908186377297, 6121.938780612137, 5707.571932105026, 5877.735501902339, 5913.556483263858, 5929.940700592939, 5982.700864956648, 5935.109733540004, 5992.340842507408, 5994.220751302303, 5995.9700201500455, 6012.669303188367, 5934.3175534814045, 5939.970300148471, 5969.492593129633, 5972.5520585955355, 5980.43185897339, 5706.885862282648, 5851.64890106595, 5908.05471124618, 5950.940490595039, 5989.1914591529585, 5876.382979787232, 5882.823515294548, 5883.323417806836, 5890.7054647266605, 5917.082852157922], "value": 5575.9772555425025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.616099535302, 626.4737620398864, 643.4209211709458, 646.7154452041103, 673.942714869236, 34666.480002799944, 45738.31392529115, 47176.52823471764, 47183.292250616185, 47256.76371618141, 48822.755886220606, 49041.75479122599, 49273.753631231884, 49287.014259714786, 49319.0, 8571.314294856396, 9291.953540232289, 9437.669681561147, 9463.0, 11100.111991040738, 10935.343879367263, 11404.828927566094, 11447.542098316057, 11448.255863368897, 11454.0, 11370.0903927686, 11422.771544569103, 11476.254043487188, 11483.196176267624, 11488.483018264156, 8370.455920365157, 11024.173187011007, 11356.261842980262, 11369.829452558177, 11435.371054592035, 11025.062869656089, 11113.499892504857, 11126.221164518487, 11126.499307531181, 11126.944365278121, 45688.543114569096, 46002.53997460016, 46085.76957115227, 46166.76916615429, 46226.53773462289, 3704.870329538453, 3942.8619998299923, 4042.6563742081958, 4334.263175260214, 4404.757738324391, 35851.4622280666, 35921.64078359235, 35964.0, 36013.45979810305, 36076.0, 4788.688735232199, 4848.636352273558, 4849.660523763338, 4853.417589889201, 4939.110960027194, 386607.0, 387212.0, 387795.9999999972, 388264.0, 388378.0, 64889.35110648833, 65082.34917650858, 65242.347576524575, 65291.34708652948, 65529.344706553275, 38661.613383866366, 39730.80134599309, 67120.3287967114, 67333.0, 67339.0, 17554.824451755576, 17869.374571889923, 17879.195436205402, 17920.462386128438, 17952.281908723504, 12535.185212961129, 13284.601461955963, 13649.522266720616, 13649.727005459836, 13663.931680341733, 19962.70055949162, 20323.898380508002, 20368.18527258893, 20531.897340513202, 20603.381898543066, 7322.231165727629, 7419.85160296791, 7424.740134095388, 7641.197674244236, 7713.4214933880185, 1069.6042464288264, 1088.820344643125, 1112.7273817914502, 1132.3885102044803, 1136.8749437561862, 35450.82274588611, 35516.46725299123, 35756.0, 35763.4635480468, 35795.28409431797, 1162.2096974057554, 1166.049669519339, 1188.7206506470973, 1219.408586835384, 1225.6078055022308, 17336.04651744141, 17481.388151414638, 17488.562785930455, 17497.300107995794, 17578.384756533458, 616214.0, 623594.0, 623640.0, 624036.0, 625566.0, 63560.68219658872, 63894.68052659707, 64019.6799016002, 64143.67928160423, 64213.35786642074, 1349.2646507653353, 1427.193635595885, 1437.417845772443, 1474.1376294867407, 1499.4002399040253, 33869.0, 35931.82034089813, 36221.456678150375, 36235.818820905726, 36463.81768091142, 54187.0, 54836.45163548473, 54868.45131548633, 54916.0, 54974.0, 470099.0, 472828.0, 473836.0, 474047.0, 474554.0, 30651.0, 31989.0, 32079.518807217442, 33457.83271083726, 33498.832505837316, 53477.0, 53529.464705352446, 53684.0, 53951.19073213827, 54027.0, 44275.0, 48849.26726099182, 49946.0, 49948.0, 50157.439515324106, 46969.765151174026, 46993.0, 47009.29486057643, 47344.28983565318, 47824.063530413434, 46350.76824615855, 47089.0, 47585.76207118942, 47668.52331476641, 47708.0, 38839.61160388473, 40554.36396634209, 41100.383494248206, 41228.79385603053, 41354.0, 30573.388532228786, 30706.846465768424, 30845.537316939815, 30928.0, 31331.216719582193], "value": 22325.281444790908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.01803095580016, 378.2869291385738, 385.8186652273434, 387.09741619938006, 413.51618606230727, 18971.905140474297, 20494.487637809045, 20622.17511299546, 20625.484362890915, 20962.790372096275, 18173.727394089066, 18286.268549258046, 18558.536036599056, 18622.25510979559, 18760.43718688438, 5615.213870058195, 5762.222100016486, 5852.590318677694, 5972.910406343908, 5977.31260904995, 5858.062709966406, 5881.705914704269, 5901.291844978608, 5905.409459054082, 5905.616134951214, 5692.146178073302, 5811.099279611667, 5864.0, 5864.560157988147, 5900.970495147519, 5824.854378640525, 5854.97072514639, 5855.151003104547, 5861.062230043215, 5861.85345366365, 5187.584993200573, 5225.947740522623, 5255.553277971377, 5265.736713164365, 5270.367555893317, 24699.135530256535, 24737.87631061851, 24890.875545622337, 25057.0, 25072.749272507408, 2304.631258998569, 2446.902123915041, 2616.699079605851, 2727.972720272792, 2779.4024284778666, 10167.244747438506, 10651.63783033658, 10887.945560272226, 10888.564457421693, 10924.453777311182, 2031.8476114291486, 2107.831373490117, 2117.2907076129513, 2122.299641118427, 2147.6134295826746, 61903.33803211328, 75679.24320756776, 80035.5998220011, 84965.15034849635, 88271.55864220638, 9956.701298961043, 10126.645567405252, 10464.686059418229, 11571.884281157081, 11700.648980530597, 15663.608409789618, 16416.343346266254, 16440.6711865762, 16452.0, 16469.670606587802, 6585.374389432996, 6619.702113404908, 7962.601869906597, 8306.255686305823, 8559.443636163754, 8069.233422824822, 8102.513849169069, 8107.3108785753875, 8319.168083191753, 8323.042850072197, 4738.981119059419, 4812.820858889594, 4897.14299997499, 4929.260610908393, 5015.27278544609, 1982.2566537548419, 2000.109951071759, 2028.320512628286, 2081.760597531303, 2091.1008266445574, 935.5649622925372, 975.317277905469, 991.2367477042637, 1003.1924300937815, 1073.7637719701654, 20332.390028299174, 20927.26754563583, 21061.578768424853, 21174.258900938392, 21358.893205534183, 983.8917719050899, 1026.4200726589454, 1034.4207243943415, 1047.2878442659064, 1095.2333366643381, 10899.945500272448, 11778.057755379548, 11793.941030294794, 11799.469023893946, 11937.224080434744, 525015.0, 529204.0, 532399.0, 563301.0, 564268.0, 92511.53744231371, 93603.0, 94420.0, 94544.52727736456, 95627.0, 972.3388096094724, 974.8635191073255, 980.4656462228103, 991.9900800992042, 1029.3155051890708, 18171.909140454212, 18215.726764098814, 18612.813871861108, 18844.246230150642, 18921.716174257122, 42792.0, 44523.0, 44978.55021449875, 45053.32420013637, 45295.547044529136, 58043.4195658038, 59349.10976335272, 59422.0, 61324.0, 61669.0, 8297.691180547477, 8597.742067737978, 8764.517951512727, 8813.559322034002, 9148.954255228682, 12026.05895714271, 12805.167664101804, 12833.358332083546, 13196.20822750638, 13318.800217996544, 12440.253584784941, 12601.243925364508, 12616.936915315364, 12763.361831908182, 12889.935550322189, 12110.697232569257, 12186.2078964867, 12297.631071067883, 12487.750244995232, 12548.24710517372, 12803.10378273512, 12859.742805143658, 12941.223526588436, 12961.02792290563, 12984.93507532494, 8892.199702026848, 8924.241439477717, 8984.0, 9009.189172974464, 9248.445093294424, 9415.482148481626, 10936.56253749841, 11758.412079395825, 12513.374331283581, 13097.934510327388], "value": 9507.746942868178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.1188215777588, 1486.5243122200893, 1532.3410933298683, 1687.594977205471, 1877.859160562958, 8021.157778433268, 9087.227585655219, 9392.0, 9428.38715483493, 9806.21550275978, 9630.662926797564, 9662.082102200297, 9742.902570974305, 9745.0742179493, 9771.364861284012, 2336.369180321308, 2348.9177878774244, 2355.7997570206467, 2361.787439130478, 2363.7399886012527, 2288.1533832482005, 2336.0, 2340.8946597403155, 2343.0979073056824, 2345.413646588353, 2322.883855807211, 2326.3718795925092, 2330.8601483910934, 2377.3581133094053, 2379.7858192762646, 2262.2534563593977, 2317.2353123469297, 2328.0338659456306, 2329.6505524171425, 2336.707911511061, 2288.725352957639, 2291.3355127013147, 2292.56441276157, 2292.977070229293, 2294.6672732453867, 9460.290478214161, 9670.854937175878, 9716.465594392306, 9720.368176068532, 9728.659496917644, 5144.794208231666, 5365.812196573102, 5377.0321342158395, 5382.542483888874, 5384.7846086156505, 3502.4045912194733, 3504.8072356020407, 3555.7510974231814, 3562.5190599269285, 3574.428091505373, 1144.4621028116771, 1144.6680462665818, 1152.4353066997194, 1161.976760464786, 1184.4373922386796, 13269.402876870565, 13273.137246078973, 13350.26573538455, 13405.195688258638, 13438.932805335911, 4405.493368262691, 4556.476005259437, 4649.5815376616265, 4696.436427628706, 4709.340692303081, 10634.255602107778, 10635.0, 10650.786984260427, 10655.094316982988, 10680.519376627915, 5452.672839629635, 5455.0, 5459.781608735686, 5466.371367292732, 5467.17992301158, 4392.758398288125, 4877.6585639004925, 5028.245763135559, 5031.622628302892, 5082.186850103965, 3956.940645890314, 3969.7816620085596, 4020.577839326887, 4046.0491784430637, 4076.7553946762696, 1124.6344937895217, 1140.1107136433595, 1142.731458107344, 1146.9942650286696, 1151.2746969409386, 958.8226178157063, 1006.5369929832212, 1017.6336518853207, 1029.0275689473376, 1042.2652030318607, 6204.937950620526, 6351.951927931839, 6495.512836537184, 6529.281779004401, 6603.438707709898, 431.46282877817316, 433.5404471260474, 447.29327662293497, 455.74933786418, 456.44541881614, 4487.6634252431195, 4516.593506584423, 4549.340345649928, 4567.81728730847, 4691.882702932454, 11892.464839082262, 12185.268883866996, 12190.939045304716, 12280.938595306967, 12328.938355308166, 11845.940770296093, 11876.10929180315, 11878.227915185485, 11906.702332441759, 11907.106966977339, 8328.750137495885, 8657.783555411164, 8668.956655216683, 8849.20357167858, 8864.20222179994, 6067.514598832171, 6234.937650623436, 6338.366163383625, 6506.642134682638, 6710.498070519621, 10925.726856828644, 11242.381669008282, 11252.099832013582, 11381.943090284496, 11406.657800266004, 10958.0, 11697.883021169679, 11702.765944681229, 11731.00286475659, 11741.354225517336, 7328.450366222662, 7541.509801862861, 8268.669253229804, 8577.871331930151, 8735.65057397697, 9135.543222838964, 9556.95221523888, 9611.711648650551, 9613.990530994144, 9620.518974051129, 8339.916600833914, 8484.0, 8542.78643033929, 8555.529445880537, 8780.824383512167, 8215.0, 8285.461445006054, 8299.460535065202, 8388.664453421794, 8531.36014798905, 7395.482316237812, 7615.809604759926, 7699.960505331708, 7704.345130663733, 7724.7682569523, 5166.4316925137455, 5384.892302153857, 5443.673379597237, 5477.096279113902, 5536.2249285099315, 5263.34208223972, 5265.552428043506, 5401.837944861661, 5425.077736784677, 5429.077056900415], "value": 4771.614558013436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.66883778545883, 328.5548082348418, 332.13313252411217, 332.6523782647134, 337.2883216413369, 5532.806351777684, 5589.664620122796, 5596.748146333414, 5614.354349249832, 5620.803271885485, 5524.58565607579, 5527.419620939793, 5528.44715528447, 5553.972230138854, 5573.916391254124, 4143.523494798092, 4209.53695093541, 4328.177646247219, 4387.648988080962, 4400.251957167273, 4289.056407590325, 4328.242557552431, 4339.717918335298, 4360.146937551528, 4369.956300437003, 4298.034356294147, 4320.308750599905, 4326.675499337546, 4328.653707703392, 4331.743794299665, 4079.8980025499304, 4141.509056739586, 4316.287812510964, 4353.28170851809, 4356.411884395598, 3807.7524960877454, 3817.828197731109, 3823.50294461721, 3823.9617603823885, 3826.2921359548573, 5396.271503347037, 5406.486383793534, 5452.318460192476, 5489.4236105209175, 5507.091329930557, 4836.419629644465, 4945.703257804544, 4952.4057113146655, 4965.180745177042, 5015.3480047593575, 5733.140028995643, 5738.684372359518, 5743.885122297531, 5745.310562732457, 5749.425057494217, 4143.751374917515, 4148.170365926797, 4194.2869712149, 4204.138151678899, 4242.957570424287, 5805.954928112938, 5921.73352199152, 5987.622846745273, 6003.279606447254, 6016.157737916739, 4265.914681706348, 4273.807678654468, 4275.187714334266, 4291.9785401072795, 4292.463442069741, 4326.65386769064, 4346.434963454793, 4364.454443194601, 4385.934210986838, 4387.934180987288, 4859.635527335464, 4896.2410826321975, 4907.484714104968, 4926.531979461945, 4961.7271050092595, 5711.257536520226, 5732.570057245724, 5739.512141467939, 5741.13413140731, 5745.482906538432, 1467.288365142905, 1485.3687182947203, 1496.2294418374545, 1498.5204734485078, 1503.488813803312, 937.6062053937226, 956.3257903178381, 958.2382006305004, 977.4819345746623, 980.7401038724744, 5518.33779946409, 5646.350669672958, 5711.514521265739, 5714.685692286881, 5714.885702285931, 5996.730147143389, 6004.519638428916, 6005.189299444519, 6019.939800602026, 6026.227935149793, 5398.245570189625, 5719.8570035749435, 5723.627964182315, 5726.141078838207, 5726.167626359608, 3134.843257837099, 3173.508106243536, 3191.505316675919, 3192.345569158317, 3204.6154461464776, 6109.205803245639, 6176.320604733476, 6266.561340706106, 6296.464800491918, 6299.811005669837, 6088.786892458743, 6090.6041107328765, 6098.695065246809, 6101.145839582462, 6112.969435152796, 6241.625502469867, 6264.060390941395, 6265.937340626627, 6267.809116267895, 6283.465905398001, 5859.677717725566, 6003.609765365237, 6013.128096425906, 6037.275526936795, 6042.8791424170395, 5931.6441013539325, 5931.961906666403, 5936.554758393051, 5965.940340596539, 5969.7313620888035, 6069.696515174135, 6194.5354098443695, 6216.968915155395, 6231.2522497300615, 6234.937650623436, 3813.6164896939413, 3821.980890095532, 3834.6740527055513, 3840.5612436102715, 3845.4618152738008, 5229.869253268699, 5250.291210686509, 5273.472652734696, 5285.814996475105, 5289.0, 4878.56092951636, 5095.706931880594, 5130.281760553451, 5139.8715032123, 5152.330197074356, 5095.414027386898, 5159.638825282344, 5176.792928282826, 5232.947670523246, 5237.240600113038, 5157.510036546522, 5159.045576568347, 5195.610329225247, 5230.581553475637, 5546.722663866871, 5330.866728331823, 5731.0, 5795.942040579709, 5827.708614569339, 5837.737301821343, 5674.2126230237345, 5678.119891416837, 5694.715264236689, 5703.62926409782, 5706.2011318414625], "value": 4369.454074105238, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.0719165767711, 816.6080281464897, 832.3091833777964, 837.7161526814886, 922.9215516681081, 13237.0, 14666.266686665664, 14672.046316989388, 14705.779413308808, 14723.263836808144, 13561.52534661287, 13689.65775855604, 13692.0, 13700.0, 13718.314084295771, 713.678844519968, 843.2452954605609, 852.3436953545767, 853.3216093205885, 853.4751128056232, 818.0428898189103, 844.990236667183, 847.2882778466087, 852.910444403338, 852.9189726975927, 890.973270801877, 892.7366426904047, 892.7991201979568, 892.8125093730324, 892.8482158033117, 742.2392048150634, 884.6815146547237, 886.1492966751925, 886.2245535156706, 886.3086792302015, 635.7361694896613, 651.1047309948821, 651.4234902111618, 656.1273506236741, 656.2485953583168, 13818.170909745348, 13942.23317717525, 13952.860471395257, 14113.506027288995, 14175.716485670331, 14313.141211527342, 14657.853421465756, 15214.46749363778, 15222.923885380502, 15223.923880380638, 13375.33123343829, 13751.793723094062, 13789.241591712453, 13790.379432925442, 14099.50651727195, 3907.7069219808627, 4018.7588744675413, 4090.3046482098116, 4140.296149654566, 4258.808353624095, 87848.56075719581, 88922.5553872233, 89283.55358223167, 89351.0, 90482.54758726164, 10209.540570674337, 10484.0, 10492.632757853438, 10493.265471416928, 10646.307989980627, 20372.69440958387, 20390.592188156155, 20399.0, 20402.489937751674, 20409.183632654825, 8163.0, 8178.345732341399, 8239.588020599065, 8391.286740626992, 8478.321734261244, 4659.184642687584, 4811.9518804811505, 4941.827036053721, 5069.036882992294, 5104.846854594294, 3729.1236559408517, 3880.5731369549326, 3882.864099756495, 3884.3590807516443, 3884.7863367515056, 653.1085068880943, 659.0608383054224, 664.1565212180503, 673.2829536543663, 674.0664180110484, 662.7812821768805, 769.6805825582433, 770.3913908012607, 788.491423032141, 792.1167897794033, 10010.749731256778, 10218.795624087625, 10396.0, 10729.678109656723, 10770.246082774287, 727.7671145233475, 762.938964882808, 805.1867613710195, 812.6244890052262, 818.5211651184005, 4636.397268355092, 4869.975650121798, 4887.315775791392, 5005.374328208974, 5021.49785021495, 136236.0, 138791.0, 139002.0, 140045.0, 140786.0, 97361.0, 116892.0, 118387.0, 121834.39082804706, 122458.0, 102882.0, 103440.0, 104301.0, 104860.0, 105082.47458762657, 9411.200047995997, 9671.371360861522, 9732.756681082747, 9784.168345690412, 9936.900630993598, 45603.315950261436, 61584.38415615966, 61639.69180154071, 62001.37998619956, 62820.68589657023, 65858.67070664615, 80743.59628201822, 80872.0, 82473.17526824895, 83626.0, 13872.237026963614, 14008.299585020912, 14244.430222790972, 14465.710685786436, 14523.128612283297, 15301.693966120838, 15605.609859753597, 15733.763993539877, 15793.526194214191, 16252.431164909645, 14267.286635667968, 14321.283935803376, 14489.78265326, 14674.55976320712, 15162.31769570394, 14222.217778022308, 14228.0, 14361.569152925429, 14413.85586144125, 14567.708645827237, 13866.722665546835, 14428.685577307064, 14758.48345307909, 15063.54809355721, 15727.764083538985, 11267.718307042389, 11279.0, 11586.304821710724, 11864.711164322198, 12188.634340969786, 11904.464299106388, 12239.388030598257, 12350.876491234972, 12364.814527781911, 12540.623781286577], "value": 7984.196858184439, "unit": "score"}], "name": "dromaeo_css", "value": 7628.296932514967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 111.0, 110.0, 110.0, 110.0, 109.0, 109.0, 108.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 121.0, 122.0, 170.0, 116.0, 119.0, 144.0, 116.0, 148.0, 117.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 112.0, 77.0, 75.0, 74.0, 76.0, 104.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 124.0, 83.0, 83.0, 83.0, 122.0, 83.0, 84.0, 126.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 118.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, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 49.0, 49.0, 53.0, 56.0, 50.0, 49.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 73.0, 73.0, 73.0, 73.0, 77.0, 81.0, 76.0, 72.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 118.0, 126.0, 126.0, 115.0, 117.0, 125.0, 116.0, 161.0, 126.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 156.0, 150.0, 154.0, 155.0, 153.0, 151.0, 151.0, 151.0, 148.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 62.0, 60.0, 69.0, 62.0, 62.0, 60.0, 59.0, 60.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1461.4999999999998, "alertThreshold": 2.0}]} 00:38:27 INFO - Return code: 0 00:38:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:38:27 INFO - # TBPL SUCCESS # 00:38:27 INFO - Running post-action listener: _resource_record_post_action 00:38:27 INFO - [mozharness: 2016-09-08 07:38:27.388114Z] Finished run-tests step (success) 00:38:27 INFO - Running post-run listener: _resource_record_post_run 00:38:27 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328519680; Read time: 32; Write time: 290884 00:38:27 INFO - TinderboxPrint: CPU usage
13.0% 00:38:27 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 32 00:38:27 INFO - TinderboxPrint: I/O write bytes / time
328,519,680 / 290,884 00:38:27 INFO - TinderboxPrint: CPU idle
8,273.6 (87.0%) 00:38:27 INFO - TinderboxPrint: CPU user
1,186.1 (12.5%) 00:38:27 INFO - TinderboxPrint: Swap in / out
0 / 0 00:38:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78671872; Read time: 0; Write time: 56576 00:38:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248786944; Read time: 20; Write time: 234156 00:38:28 INFO - Running post-run listener: _upload_blobber_files 00:38:28 INFO - Blob upload gear active. 00:38:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:38:28 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 00:38:28 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'] 00:38:28 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 00:38:28 INFO - (blobuploader) - INFO - Open directory for files ... 00:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:38:29 INFO - (blobuploader) - INFO - Done attempting. 00:38:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:38:29 INFO - (blobuploader) - INFO - Done attempting. 00:38:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:38:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:38:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:38:33 INFO - (blobuploader) - INFO - Done attempting. 00:38:33 INFO - (blobuploader) - INFO - Iteration through files over. 00:38:33 INFO - Return code: 0 00:38:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:38:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:38:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d79d7cdce3cea775a34275cb7692917979ec2ffa124b44ebedf2b157ade9052bf850a0af641dc4e68798d87e5d7daadb120b8bdbba68e0a00e86517ad29fcbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a4fdf430a02291f5347b48647e0df00a48f7c8b4d60edcaa084914f92cda988bb5432471b74d90836655690af128b7d752593e383b9cd6ca5e826de91aeee9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7fcd55ca22f0825456e3ce6968a1712d1cdcb30470270363edeb7920a57d1409c79932d6a65e0116bd1318777ef33c9d9c6f390a21b521bdb9fa43c0937fc04"} 00:38:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:38:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:38:33 INFO - Contents: 00:38:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1d79d7cdce3cea775a34275cb7692917979ec2ffa124b44ebedf2b157ade9052bf850a0af641dc4e68798d87e5d7daadb120b8bdbba68e0a00e86517ad29fcbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a4fdf430a02291f5347b48647e0df00a48f7c8b4d60edcaa084914f92cda988bb5432471b74d90836655690af128b7d752593e383b9cd6ca5e826de91aeee9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7fcd55ca22f0825456e3ce6968a1712d1cdcb30470270363edeb7920a57d1409c79932d6a65e0116bd1318777ef33c9d9c6f390a21b521bdb9fa43c0937fc04"} 00:38:33 INFO - Running post-run listener: copy_logs_to_upload_dir 00:38:33 INFO - Copying logs to upload dir... 00:38:33 INFO - mkdir: /builds/slave/test/build/upload/logs 00:38:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.165127 ========= master_lag: 0.62 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-08 00:38:34.310375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 00:38:34.315438) ========= 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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 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/1d79d7cdce3cea775a34275cb7692917979ec2ffa124b44ebedf2b157ade9052bf850a0af641dc4e68798d87e5d7daadb120b8bdbba68e0a00e86517ad29fcbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a4fdf430a02291f5347b48647e0df00a48f7c8b4d60edcaa084914f92cda988bb5432471b74d90836655690af128b7d752593e383b9cd6ca5e826de91aeee9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7fcd55ca22f0825456e3ce6968a1712d1cdcb30470270363edeb7920a57d1409c79932d6a65e0116bd1318777ef33c9d9c6f390a21b521bdb9fa43c0937fc04"} build_url:https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/Cime8-S0TOSnUvh7hU5NvA/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/1d79d7cdce3cea775a34275cb7692917979ec2ffa124b44ebedf2b157ade9052bf850a0af641dc4e68798d87e5d7daadb120b8bdbba68e0a00e86517ad29fcbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a4fdf430a02291f5347b48647e0df00a48f7c8b4d60edcaa084914f92cda988bb5432471b74d90836655690af128b7d752593e383b9cd6ca5e826de91aeee9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7fcd55ca22f0825456e3ce6968a1712d1cdcb30470270363edeb7920a57d1409c79932d6a65e0116bd1318777ef33c9d9c6f390a21b521bdb9fa43c0937fc04"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 00:38:34.348565) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:38:34.348968) ========= 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=c6927cf182745ad25b1d38b9000001fe-1473316401.872340-2145660294 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 00:38:34.399289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 00:38:34.399660) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 00:38:34.403024) ========= ========= Total master_lag: 0.75 =========