builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1473815060.58 results: success (0) buildid: 20160913150444 builduid: df7e1675a96a4a0f8caa9361a8789a95 revision: 648df5f64606195b069754bccfaba43a58e175c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.579019) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.579449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.579747) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.609301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.609550) ========= 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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-13 18:04:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2016-09-13 18:04:21 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081638 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.709610) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.713045) ========= 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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019152 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.766621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:20.767016) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 648df5f64606195b069754bccfaba43a58e175c4 --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 648df5f64606195b069754bccfaba43a58e175c4 --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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-13 18:04:21,603 truncating revision to first 12 chars 2016-09-13 18:04:21,603 Setting DEBUG logging. 2016-09-13 18:04:21,603 attempt 1/10 2016-09-13 18:04:21,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/648df5f64606?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 18:04:21,910 unpacking tar archive at: mozilla-aurora-648df5f64606/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627068 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:21.407566) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:21.407872) ========= script_repo_revision: 648df5f64606195b069754bccfaba43a58e175c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:21.408256) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:21.408518) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 18:04:21.416489) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-13 18:04:21.417049) ========= /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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:04:22 INFO - MultiFileLogger online at 20160913 18:04:22 in /builds/slave/test 18:04:22 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 18:04:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:22 INFO - {'append_to_log': False, 18:04:22 INFO - 'base_work_dir': '/builds/slave/test', 18:04:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:04:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:22 INFO - 'branch': 'Mozilla-Aurora', 18:04:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:22 INFO - 'config_files': ('talos/linux_config.py',), 18:04:22 INFO - 'default_actions': ('clobber', 18:04:22 INFO - 'read-buildbot-config', 18:04:22 INFO - 'download-and-extract', 18:04:22 INFO - 'populate-webroot', 18:04:22 INFO - 'create-virtualenv', 18:04:22 INFO - 'install', 18:04:22 INFO - 'run-tests'), 18:04:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:22 INFO - 'download_minidump_stackwalk': True, 18:04:22 INFO - 'download_symbols': 'ondemand', 18:04:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:22 INFO - 'installer_path': 'installer.exe', 18:04:22 INFO - 'log_level': 'info', 18:04:22 INFO - 'log_name': 'talos', 18:04:22 INFO - 'log_to_console': True, 18:04:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:04:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:04:22 INFO - 'opt_config_files': (), 18:04:22 INFO - 'pip_index': False, 18:04:22 INFO - 'sps_profile': False, 18:04:22 INFO - 'sps_profile_interval': 0, 18:04:22 INFO - 'suite': 'dromaeojs', 18:04:22 INFO - 'system_bits': '32', 18:04:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:22 INFO - 'title': 'talos-linux64-ix-027', 18:04:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:22 INFO - 'use_talos_json': True, 18:04:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:22 INFO - 'work_dir': 'build'} 18:04:22 INFO - [mozharness: 2016-09-14 01:04:22.347677Z] Running clobber step. 18:04:22 INFO - Running pre-action listener: _resource_record_pre_action 18:04:22 INFO - Running main action method: clobber 18:04:22 INFO - rmtree: /builds/slave/test/build 18:04:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:22 INFO - Running post-action listener: _resource_record_post_action 18:04:22 INFO - [mozharness: 2016-09-14 01:04:22.805522Z] Finished clobber step (success) 18:04:22 INFO - [mozharness: 2016-09-14 01:04:22.805623Z] Running read-buildbot-config step. 18:04:22 INFO - Running pre-action listener: _resource_record_pre_action 18:04:22 INFO - Running main action method: read_buildbot_config 18:04:22 INFO - Using buildbot properties: 18:04:22 INFO - { 18:04:22 INFO - "project": "", 18:04:22 INFO - "product": "firefox", 18:04:22 INFO - "script_repo_revision": "production", 18:04:22 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 18:04:22 INFO - "repository": "", 18:04:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 18:04:22 INFO - "buildid": "20160913150444", 18:04:22 INFO - "pgo_build": "True", 18:04:22 INFO - "basedir": "/builds/slave/test", 18:04:22 INFO - "buildnumber": 515, 18:04:22 INFO - "slavename": "talos-linux64-ix-027", 18:04:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:04:22 INFO - "platform": "ubuntu64_hw", 18:04:22 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:04:22 INFO - "branch": "mozilla-aurora", 18:04:22 INFO - "repo_path": "releases/mozilla-aurora", 18:04:22 INFO - "revision": "648df5f64606195b069754bccfaba43a58e175c4", 18:04:22 INFO - "stage_platform": "linux64-pgo", 18:04:22 INFO - "builduid": "df7e1675a96a4a0f8caa9361a8789a95", 18:04:22 INFO - "slavebuilddir": "test" 18:04:22 INFO - } 18:04:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 18:04:22 INFO - Running post-action listener: _resource_record_post_action 18:04:22 INFO - [mozharness: 2016-09-14 01:04:22.808380Z] Finished read-buildbot-config step (success) 18:04:22 INFO - [mozharness: 2016-09-14 01:04:22.808470Z] Running download-and-extract step. 18:04:22 INFO - Running pre-action listener: _resource_record_pre_action 18:04:22 INFO - Running main action method: download_and_extract 18:04:22 INFO - mkdir: /builds/slave/test/build/tests 18:04: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')]} 18:04:22 INFO - https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:04:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:04:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:04:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:04:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:04:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:04:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:04:22 INFO - retry: Failed, sleeping 30 seconds before retrying 18:04:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:04:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:04:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:04:52 INFO - retry: Failed, sleeping 60 seconds before retrying 18:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:05:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:05:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:05:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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! 18:05:52 INFO - Caught exception: HTTP Error 404: Not Found 18:05:52 INFO - Caught exception: HTTP Error 404: Not Found 18:05:52 INFO - Caught exception: HTTP Error 404: Not Found 18:05:52 INFO - trying https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:05:52 INFO - Downloading https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:05:53 INFO - Downloaded 1361 bytes. 18:05:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 18:05:53 INFO - Using the following test package requirements: 18:05:53 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 18:05:53 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:05:53 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:05:53 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'jsshell-linux-x86_64.zip'], 18:05:53 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:05:53 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 18:05:53 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:05:53 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:05:53 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:05:53 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 18:05:53 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:05:53 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:05: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')]} 18:05:53 INFO - https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:05:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:05:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:05:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:05:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:05:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:05:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:05:53 INFO - retry: Failed, sleeping 30 seconds before retrying 18:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:06:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:06:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:06:23 INFO - retry: Failed, sleeping 60 seconds before retrying 18:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:07:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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! 18:07:23 INFO - Caught exception: HTTP Error 404: Not Found 18:07:23 INFO - Caught exception: HTTP Error 404: Not Found 18:07:23 INFO - Caught exception: HTTP Error 404: Not Found 18:07:23 INFO - trying https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:23 INFO - Downloading https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:24 INFO - Downloaded 21769896 bytes. 18:07:24 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'] 18:07:24 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 18:07:25 INFO - Return code: 0 18:07:25 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 18:07: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')]} 18:07:25 INFO - https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:07:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:07:25 INFO - retry: Failed, sleeping 30 seconds before retrying 18:07:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:07:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:07:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:07:55 INFO - retry: Failed, sleeping 60 seconds before retrying 18:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:08:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:08:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:08:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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! 18:08:55 INFO - Caught exception: HTTP Error 404: Not Found 18:08:55 INFO - Caught exception: HTTP Error 404: Not Found 18:08:55 INFO - Caught exception: HTTP Error 404: Not Found 18:08:55 INFO - trying https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 18:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:08:56 INFO - Downloaded 21769896 bytes. 18:08:56 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'] 18:08:56 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 18:08:57 INFO - Return code: 0 18:08:57 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')]} 18:08:57 INFO - https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:08:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:08:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:08:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:08:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:08:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:08:57 INFO - retry: Failed, sleeping 30 seconds before retrying 18:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:09:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:09:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:09:27 INFO - retry: Failed, sleeping 60 seconds before retrying 18:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:10:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:10:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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! 18:10:28 INFO - Caught exception: HTTP Error 404: Not Found 18:10:28 INFO - Caught exception: HTTP Error 404: Not Found 18:10:28 INFO - Caught exception: HTTP Error 404: Not Found 18:10:28 INFO - trying https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 18:10:28 INFO - Downloading https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:28 INFO - Downloaded 14413740 bytes. 18:10:28 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'] 18:10:28 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 18:10:28 INFO - Return code: 0 18:10: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')]} 18:10:28 INFO - https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:10:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:10:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:10:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:10:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:10:28 INFO - retry: Failed, sleeping 30 seconds before retrying 18:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:10:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:10:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:10:59 INFO - retry: Failed, sleeping 60 seconds before retrying 18:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:11:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:11:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:11:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LeKrfQVqSwm5eTHUn_gVug/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! 18:11:59 INFO - Caught exception: HTTP Error 404: Not Found 18:11:59 INFO - Caught exception: HTTP Error 404: Not Found 18:11:59 INFO - Caught exception: HTTP Error 404: Not Found 18:11:59 INFO - trying https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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 18:12:00 INFO - Downloaded 63896547 bytes. 18:12:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:12:00 INFO - mkdir: /builds/slave/test/properties 18:12:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:00 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:00 INFO - Contents: 18:12:00 INFO - build_url:https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 18:12:01 INFO - Running post-action listener: _resource_record_post_action 18:12:01 INFO - Running post-action listener: set_extra_try_arguments 18:12:01 INFO - [mozharness: 2016-09-14 01:12:01.014714Z] Finished download-and-extract step (success) 18:12:01 INFO - [mozharness: 2016-09-14 01:12:01.014948Z] Running populate-webroot step. 18:12:01 INFO - Running pre-action listener: _resource_record_pre_action 18:12:01 INFO - Running main action method: populate_webroot 18:12:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:12:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:12:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:12:01 INFO - '--tppagecycles', 18:12:01 INFO - '7'], 18:12:01 INFO - 'tests': ['tsvgm']}}, 18:12:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:12:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:12:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:12:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:12:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['damp', 'tps']}, 18:12:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'tests': ['damp', 'tps']}, 18:12:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['dromaeo_dom']}, 18:12:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['basic_compositor_video']}, 18:12:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:12:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['a11yr', 18:12:01 INFO - 'ts_paint', 18:12:01 INFO - 'tpaint', 18:12:01 INFO - 'sessionrestore', 18:12:01 INFO - 'sessionrestore_no_auto_restore', 18:12:01 INFO - 'tabpaint']}, 18:12:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['tsvgx', 18:12:01 INFO - 'tsvgr_opacity', 18:12:01 INFO - 'tart', 18:12:01 INFO - 'tscrollx', 18:12:01 INFO - 'cart']}, 18:12:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:12:01 INFO - 'tsvgr_opacity', 18:12:01 INFO - 'tart', 18:12:01 INFO - 'tscrollx', 18:12:01 INFO - 'cart']}, 18:12:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'talos_options': ['--disable-e10s'], 18:12:01 INFO - 'tests': ['tp5o']}, 18:12:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'tests': ['tp5o']}, 18:12:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'talos_options': ['--disable-e10s', 18:12:01 INFO - '--xperf_path', 18:12:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:01 INFO - 'tests': ['tp5n']}, 18:12:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:01 INFO - 'talos_options': ['--xperf_path', 18:12:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:01 INFO - 'tests': ['tp5n']}}, 18:12:01 INFO - 'talos.zip': {'path': '', 18:12:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:12:01 INFO - Running post-action listener: _resource_record_post_action 18:12:01 INFO - [mozharness: 2016-09-14 01:12:01.032968Z] Finished populate-webroot step (success) 18:12:01 INFO - [mozharness: 2016-09-14 01:12:01.033057Z] Running create-virtualenv step. 18:12:01 INFO - Running pre-action listener: _resource_record_pre_action 18:12:01 INFO - Running main action method: create_virtualenv 18:12:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:01 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 18:12:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:12:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:01 INFO - Using real prefix '/usr' 18:12:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:03 INFO - Installing pip.................done. 18:12:03 INFO - Return code: 0 18:12:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:12:03 INFO - Using env: {'DISPLAY': ':0', 18:12:03 INFO - 'HOME': '/home/cltbld', 18:12:03 INFO - 'LANG': 'en_US.UTF-8', 18:12:03 INFO - 'LANGUAGE': 'en_US:en', 18:12:03 INFO - 'LOGNAME': 'cltbld', 18:12:03 INFO - 'MAIL': '/var/mail/cltbld', 18:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:03 INFO - 'MOZ_NO_REMOTE': '1', 18:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:03 INFO - 'NO_EM_RESTART': '1', 18:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:03 INFO - 'PWD': '/builds/slave/test', 18:12:03 INFO - 'SHELL': '/bin/bash', 18:12:03 INFO - 'SHLVL': '1', 18:12:03 INFO - 'TERM': 'linux', 18:12:03 INFO - 'TMOUT': '86400', 18:12:03 INFO - 'USER': 'cltbld', 18:12:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:03 INFO - '_': '/tools/buildbot/bin/python'} 18:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:03 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 18:12:03 INFO - Cleaning up... 18:12:03 INFO - Return code: 0 18:12:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:12:03 INFO - Using env: {'DISPLAY': ':0', 18:12:03 INFO - 'HOME': '/home/cltbld', 18:12:03 INFO - 'LANG': 'en_US.UTF-8', 18:12:03 INFO - 'LANGUAGE': 'en_US:en', 18:12:03 INFO - 'LOGNAME': 'cltbld', 18:12:03 INFO - 'MAIL': '/var/mail/cltbld', 18:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:03 INFO - 'MOZ_NO_REMOTE': '1', 18:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:03 INFO - 'NO_EM_RESTART': '1', 18:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:03 INFO - 'PWD': '/builds/slave/test', 18:12:03 INFO - 'SHELL': '/bin/bash', 18:12:03 INFO - 'SHLVL': '1', 18:12:03 INFO - 'TERM': 'linux', 18:12:03 INFO - 'TMOUT': '86400', 18:12:03 INFO - 'USER': 'cltbld', 18:12:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:03 INFO - '_': '/tools/buildbot/bin/python'} 18:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:04 INFO - Downloading/unpacking psutil>=3.1.1 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:04 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 18:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:06 INFO - Installing collected packages: psutil 18:12:06 INFO - Running setup.py install for psutil 18:12:06 INFO - building 'psutil._psutil_linux' extension 18:12:06 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 18:12:06 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 18:12:06 INFO - building 'psutil._psutil_posix' extension 18:12:06 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 18:12:06 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 18:12:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:06 INFO - Successfully installed psutil 18:12:06 INFO - Cleaning up... 18:12:06 INFO - Return code: 0 18:12:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:06 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')]} 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:06 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')]} 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:06 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:06 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 18:12:06 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 18:12:06 INFO - Using env: {'DISPLAY': ':0', 18:12:06 INFO - 'HOME': '/home/cltbld', 18:12:06 INFO - 'LANG': 'en_US.UTF-8', 18:12:06 INFO - 'LANGUAGE': 'en_US:en', 18:12:06 INFO - 'LOGNAME': 'cltbld', 18:12:06 INFO - 'MAIL': '/var/mail/cltbld', 18:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:06 INFO - 'MOZ_NO_REMOTE': '1', 18:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:06 INFO - 'NO_EM_RESTART': '1', 18:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:06 INFO - 'PWD': '/builds/slave/test', 18:12:06 INFO - 'SHELL': '/bin/bash', 18:12:06 INFO - 'SHLVL': '1', 18:12:06 INFO - 'TERM': 'linux', 18:12:06 INFO - 'TMOUT': '86400', 18:12:06 INFO - 'USER': 'cltbld', 18:12:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:06 INFO - '_': '/tools/buildbot/bin/python'} 18:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:08 INFO - Installing collected packages: mozsystemmonitor 18:12:08 INFO - Running setup.py install for mozsystemmonitor 18:12:08 INFO - Successfully installed mozsystemmonitor 18:12:08 INFO - Cleaning up... 18:12:08 INFO - Return code: 0 18:12:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:08 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:08 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 18:12:08 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 18:12:08 INFO - Using env: {'DISPLAY': ':0', 18:12:08 INFO - 'HOME': '/home/cltbld', 18:12:08 INFO - 'LANG': 'en_US.UTF-8', 18:12:08 INFO - 'LANGUAGE': 'en_US:en', 18:12:08 INFO - 'LOGNAME': 'cltbld', 18:12:08 INFO - 'MAIL': '/var/mail/cltbld', 18:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:08 INFO - 'MOZ_NO_REMOTE': '1', 18:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:08 INFO - 'NO_EM_RESTART': '1', 18:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:08 INFO - 'PWD': '/builds/slave/test', 18:12:08 INFO - 'SHELL': '/bin/bash', 18:12:08 INFO - 'SHLVL': '1', 18:12:08 INFO - 'TERM': 'linux', 18:12:08 INFO - 'TMOUT': '86400', 18:12:08 INFO - 'USER': 'cltbld', 18:12:08 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:08 INFO - '_': '/tools/buildbot/bin/python'} 18:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:09 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 18:12:09 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 18:12:09 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 18:12:09 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 18:12:09 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 18:12:09 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 18:12:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:10 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 18:12:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:11 INFO - warning: no files found matching '*.txt' 18:12:11 INFO - no previously-included directories found matching 'build' 18:12:11 INFO - no previously-included directories found matching 'dist' 18:12:11 INFO - no previously-included directories found matching '.git*' 18:12:11 INFO - Installing collected packages: jsonschema, functools32 18:12:11 INFO - Running setup.py install for functools32 18:12:11 INFO - warning: no files found matching '*.txt' 18:12:11 INFO - no previously-included directories found matching 'build' 18:12:11 INFO - no previously-included directories found matching 'dist' 18:12:11 INFO - no previously-included directories found matching '.git*' 18:12:11 INFO - Successfully installed jsonschema functools32 18:12:11 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:11 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 18:12:11 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 18:12:11 INFO - Using env: {'DISPLAY': ':0', 18:12:11 INFO - 'HOME': '/home/cltbld', 18:12:11 INFO - 'LANG': 'en_US.UTF-8', 18:12:11 INFO - 'LANGUAGE': 'en_US:en', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MAIL': '/var/mail/cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SHLVL': '1', 18:12:11 INFO - 'TERM': 'linux', 18:12:11 INFO - 'TMOUT': '86400', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - '_': '/tools/buildbot/bin/python'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:11 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:11 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 18:12:11 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 18:12:11 INFO - Using env: {'DISPLAY': ':0', 18:12:11 INFO - 'HOME': '/home/cltbld', 18:12:11 INFO - 'LANG': 'en_US.UTF-8', 18:12:11 INFO - 'LANGUAGE': 'en_US:en', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MAIL': '/var/mail/cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SHLVL': '1', 18:12:11 INFO - 'TERM': 'linux', 18:12:11 INFO - 'TMOUT': '86400', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - '_': '/tools/buildbot/bin/python'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:13 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:13 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 18:12:13 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 18:12:13 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 18:12:13 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 18:12:13 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 18:12:13 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 18:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 INFO - Downloading docopt-0.6.1.tar.gz 18:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:14 INFO - Running setup.py install for blobuploader 18:12:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:14 INFO - Running setup.py install for requests 18:12:14 INFO - Running setup.py install for docopt 18:12:15 INFO - Successfully installed blobuploader requests docopt 18:12:15 INFO - Cleaning up... 18:12:15 INFO - Return code: 0 18:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:15 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')]} 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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')]} 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:15 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 18:12:15 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 18:12:15 INFO - Using env: {'DISPLAY': ':0', 18:12:15 INFO - 'HOME': '/home/cltbld', 18:12:15 INFO - 'LANG': 'en_US.UTF-8', 18:12:15 INFO - 'LANGUAGE': 'en_US:en', 18:12:15 INFO - 'LOGNAME': 'cltbld', 18:12:15 INFO - 'MAIL': '/var/mail/cltbld', 18:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:15 INFO - 'MOZ_NO_REMOTE': '1', 18:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:15 INFO - 'NO_EM_RESTART': '1', 18:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:15 INFO - 'PWD': '/builds/slave/test', 18:12:15 INFO - 'SHELL': '/bin/bash', 18:12:15 INFO - 'SHLVL': '1', 18:12:15 INFO - 'TERM': 'linux', 18:12:15 INFO - 'TMOUT': '86400', 18:12:15 INFO - 'USER': 'cltbld', 18:12:15 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:15 INFO - '_': '/tools/buildbot/bin/python'} 18:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:15 INFO - Running setup.py (path:/tmp/pip-YZtRvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:15 INFO - Running setup.py (path:/tmp/pip-Is6OVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:15 INFO - Running setup.py (path:/tmp/pip-6dMO5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:15 INFO - Running setup.py (path:/tmp/pip-Go42js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:15 INFO - Running setup.py (path:/tmp/pip-UOZqJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:15 INFO - Running setup.py (path:/tmp/pip-WF5GHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:15 INFO - Running setup.py (path:/tmp/pip-VCzWWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:15 INFO - Running setup.py (path:/tmp/pip-Agm6vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:15 INFO - Running setup.py (path:/tmp/pip-Ot_T9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:15 INFO - Running setup.py (path:/tmp/pip-kicSKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:16 INFO - Running setup.py (path:/tmp/pip-rns99Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:16 INFO - Running setup.py (path:/tmp/pip-kcwuSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:16 INFO - Running setup.py (path:/tmp/pip-1hCs97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:16 INFO - Running setup.py (path:/tmp/pip-ZaNqDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:16 INFO - Running setup.py (path:/tmp/pip-wDm5QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:16 INFO - Running setup.py (path:/tmp/pip-LCSLdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:16 INFO - Running setup.py (path:/tmp/pip-CFQUdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:16 INFO - Running setup.py install for manifestparser 18:12:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for mozcrash 18:12:16 INFO - Running setup.py install for mozdebug 18:12:16 INFO - Running setup.py install for mozdevice 18:12:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:16 INFO - Running setup.py install for mozfile 18:12:17 INFO - Running setup.py install for mozhttpd 18:12:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for mozinfo 18:12:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for mozInstall 18:12:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for mozleak 18:12:17 INFO - Running setup.py install for mozlog 18:12:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for moznetwork 18:12:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for mozprocess 18:12:17 INFO - Running setup.py install for mozprofile 18:12:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:17 INFO - Running setup.py install for mozrunner 18:12:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Running setup.py install for mozscreenshot 18:12:18 INFO - Running setup.py install for moztest 18:12:18 INFO - Running setup.py install for mozversion 18:12:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:18 INFO - Cleaning up... 18:12:18 INFO - Return code: 0 18:12:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:18 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')]} 18:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:18 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')]} 18:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:18 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:18 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 18:12:18 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 18:12:18 INFO - Using env: {'DISPLAY': ':0', 18:12:18 INFO - 'HOME': '/home/cltbld', 18:12:18 INFO - 'LANG': 'en_US.UTF-8', 18:12:18 INFO - 'LANGUAGE': 'en_US:en', 18:12:18 INFO - 'LOGNAME': 'cltbld', 18:12:18 INFO - 'MAIL': '/var/mail/cltbld', 18:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:18 INFO - 'MOZ_NO_REMOTE': '1', 18:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:18 INFO - 'NO_EM_RESTART': '1', 18:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:18 INFO - 'PWD': '/builds/slave/test', 18:12:18 INFO - 'SHELL': '/bin/bash', 18:12:18 INFO - 'SHLVL': '1', 18:12:18 INFO - 'TERM': 'linux', 18:12:18 INFO - 'TMOUT': '86400', 18:12:18 INFO - 'USER': 'cltbld', 18:12:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:18 INFO - '_': '/tools/buildbot/bin/python'} 18:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:18 INFO - Running setup.py (path:/tmp/pip-4I0Zqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:18 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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:18 INFO - Running setup.py (path:/tmp/pip-yKaoDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:18 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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:18 INFO - Running setup.py (path:/tmp/pip-bTShBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:18 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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:18 INFO - Running setup.py (path:/tmp/pip-lEJhDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:18 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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:18 INFO - Running setup.py (path:/tmp/pip-CD1fXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:18 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)) 18:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:18 INFO - Running setup.py (path:/tmp/pip-DdWmxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:19 INFO - Running setup.py (path:/tmp/pip-sTIdTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:19 INFO - Running setup.py (path:/tmp/pip-ormZ8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:19 INFO - Running setup.py (path:/tmp/pip-kCClDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:19 INFO - Running setup.py (path:/tmp/pip-tzHwYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:19 INFO - Running setup.py (path:/tmp/pip-f8houM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:19 INFO - Running setup.py (path:/tmp/pip-7HVR4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:19 INFO - Running setup.py (path:/tmp/pip-fr8d86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:19 INFO - Running setup.py (path:/tmp/pip-DsYL6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:19 INFO - Running setup.py (path:/tmp/pip-bXn2wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:19 INFO - Running setup.py (path:/tmp/pip-wZCyU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:19 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)) 18:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:19 INFO - Running setup.py (path:/tmp/pip-YWFAfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:19 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)) 18:12:19 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)) 18:12:19 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)) 18:12:19 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)) 18:12:19 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)) 18:12:19 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)) 18:12:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:21 INFO - Downloading blessings-1.6.tar.gz 18:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:21 INFO - Installing collected packages: blessings 18:12:21 INFO - Running setup.py install for blessings 18:12:21 INFO - Successfully installed blessings 18:12:21 INFO - Cleaning up... 18:12:21 INFO - Return code: 0 18:12:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:22 INFO - Reading from file tmpfile_stdout 18:12:22 INFO - Current package versions: 18:12:22 INFO - argparse == 1.2.1 18:12:22 INFO - blessings == 1.6 18:12:22 INFO - blobuploader == 1.2.4 18:12:22 INFO - docopt == 0.6.1 18:12:22 INFO - functools32 == 3.2.3-2 18:12:22 INFO - jsonschema == 2.5.1 18:12:22 INFO - manifestparser == 1.1 18:12:22 INFO - mozInstall == 1.12 18:12:22 INFO - mozcrash == 0.17 18:12:22 INFO - mozdebug == 0.1 18:12:22 INFO - mozdevice == 0.48 18:12:22 INFO - mozfile == 1.2 18:12:22 INFO - mozhttpd == 0.7 18:12:22 INFO - mozinfo == 0.9 18:12:22 INFO - mozleak == 0.1 18:12:22 INFO - mozlog == 3.3 18:12:22 INFO - moznetwork == 0.27 18:12:22 INFO - mozprocess == 0.23 18:12:22 INFO - mozprofile == 0.28 18:12:22 INFO - mozrunner == 6.12 18:12:22 INFO - mozscreenshot == 0.1 18:12:22 INFO - mozsystemmonitor == 0.3 18:12:22 INFO - moztest == 0.7 18:12:22 INFO - mozversion == 1.4 18:12:22 INFO - psutil == 3.1.1 18:12:22 INFO - requests == 1.2.3 18:12:22 INFO - wsgiref == 0.1.2 18:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:22 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 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12:22 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 18:12:22 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 18:12:22 INFO - Using env: {'DISPLAY': ':0', 18:12:22 INFO - 'HOME': '/home/cltbld', 18:12:22 INFO - 'LANG': 'en_US.UTF-8', 18:12:22 INFO - 'LANGUAGE': 'en_US:en', 18:12:22 INFO - 'LOGNAME': 'cltbld', 18:12:22 INFO - 'MAIL': '/var/mail/cltbld', 18:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:22 INFO - 'MOZ_NO_REMOTE': '1', 18:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:22 INFO - 'NO_EM_RESTART': '1', 18:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:22 INFO - 'PWD': '/builds/slave/test', 18:12:22 INFO - 'SHELL': '/bin/bash', 18:12:22 INFO - 'SHLVL': '1', 18:12:22 INFO - 'TERM': 'linux', 18:12:22 INFO - 'TMOUT': '86400', 18:12:22 INFO - 'USER': 'cltbld', 18:12:22 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:22 INFO - '_': '/tools/buildbot/bin/python'} 18:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 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)) 18:12:22 INFO - Cleaning up... 18:12:22 INFO - Return code: 0 18:12:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x153b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x169e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, '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': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', '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 18:12: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'] in /builds/slave/test/build 18:12: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 18:12:22 INFO - Using env: {'DISPLAY': ':0', 18:12:22 INFO - 'HOME': '/home/cltbld', 18:12:22 INFO - 'LANG': 'en_US.UTF-8', 18:12:22 INFO - 'LANGUAGE': 'en_US:en', 18:12:22 INFO - 'LOGNAME': 'cltbld', 18:12:22 INFO - 'MAIL': '/var/mail/cltbld', 18:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:22 INFO - 'MOZ_NO_REMOTE': '1', 18:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:22 INFO - 'NO_EM_RESTART': '1', 18:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:22 INFO - 'PWD': '/builds/slave/test', 18:12:22 INFO - 'SHELL': '/bin/bash', 18:12:22 INFO - 'SHLVL': '1', 18:12:22 INFO - 'TERM': 'linux', 18:12:22 INFO - 'TMOUT': '86400', 18:12:22 INFO - 'USER': 'cltbld', 18:12:22 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:22 INFO - '_': '/tools/buildbot/bin/python'} 18:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:22 INFO - Cleaning up... 18:12:22 INFO - Return code: 0 18:12:22 INFO - Running post-action listener: _resource_record_post_action 18:12:22 INFO - Running post-action listener: _start_resource_monitoring 18:12:22 INFO - Starting resource monitoring. 18:12:22 INFO - [mozharness: 2016-09-14 01:12:22.622925Z] Finished create-virtualenv step (success) 18:12:22 INFO - [mozharness: 2016-09-14 01:12:22.623356Z] Running install step. 18:12:22 INFO - Running pre-action listener: _resource_record_pre_action 18:12:22 INFO - Running main action method: install 18:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:22 INFO - Reading from file tmpfile_stdout 18:12:22 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:22 INFO - Reading from file tmpfile_stdout 18:12:22 INFO - Output received: 18:12:22 INFO - Usage: mozinstall [options] installer 18:12:22 INFO - Options: 18:12:22 INFO - -h, --help show this help message and exit 18:12:22 INFO - -d DEST, --destination=DEST 18:12:22 INFO - Directory to install application into. [default: 18:12:22 INFO - "/builds/slave/test"] 18:12:22 INFO - --app=APP Application being installed. [default: firefox] 18:12:22 INFO - mkdir: /builds/slave/test/build/application 18:12:22 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'] 18:12:22 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 18:12:39 INFO - Reading from file tmpfile_stdout 18:12:39 INFO - Output received: 18:12:39 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:39 INFO - Running post-action listener: _resource_record_post_action 18:12:39 INFO - [mozharness: 2016-09-14 01:12:39.781110Z] Finished install step (success) 18:12:39 INFO - [mozharness: 2016-09-14 01:12:39.781387Z] Running run-tests step. 18:12:39 INFO - Running pre-action listener: _resource_record_pre_action 18:12:39 INFO - Running main action method: run_tests 18:12:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:39 INFO - Python 2.7.3 18:12:39 INFO - Return code: 0 18:12:39 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12:39 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 0x17fa3b0>, '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 0x17fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17fb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:39 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 18:12:39 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 18:12:39 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 18:12:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:39 INFO - Return code: 0 18:12:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:39 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/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 18:12:39 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/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 18:12:39 INFO - Using env: {'DISPLAY': ':0', 18:12:39 INFO - 'HOME': '/home/cltbld', 18:12:39 INFO - 'LANG': 'en_US.UTF-8', 18:12:39 INFO - 'LANGUAGE': 'en_US:en', 18:12:39 INFO - 'LOGNAME': 'cltbld', 18:12:39 INFO - 'MAIL': '/var/mail/cltbld', 18:12:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:39 INFO - 'MOZ_NO_REMOTE': '1', 18:12:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:39 INFO - 'NO_EM_RESTART': '1', 18:12:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:39 INFO - 'PWD': '/builds/slave/test', 18:12:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:39 INFO - 'SHELL': '/bin/bash', 18:12:39 INFO - 'SHLVL': '1', 18:12:39 INFO - 'TERM': 'linux', 18:12:39 INFO - 'TMOUT': '86400', 18:12:39 INFO - 'USER': 'cltbld', 18:12:39 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525', 18:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:39 INFO - '_': '/tools/buildbot/bin/python'} 18:12:39 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/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 18:12:40 INFO - mozversion application_buildid: 20160913150444 18:12:40 INFO - mozversion application_changeset: 648df5f64606195b069754bccfaba43a58e175c4 18:12:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:12:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:40 INFO - mozversion application_name: Firefox 18:12:40 INFO - mozversion application_remotingname: firefox-dev 18:12:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:40 INFO - mozversion application_vendor: Mozilla 18:12:40 INFO - mozversion application_version: 50.0a2 18:12:40 INFO - mozversion platform_buildid: 20160913150444 18:12:40 INFO - mozversion platform_changeset: 648df5f64606195b069754bccfaba43a58e175c4 18:12:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:40 INFO - mozversion platform_version: 50.0a2 18:12:40 INFO - using testdate: 1473815560 18:12:40 INFO - actual date: 1473815560 18:12:40 INFO - starting webserver on 'localhost:56086' 18:12:40 INFO - SUITE-START | Running 2 tests 18:12:40 INFO - TEST-START | dromaeo_css 18:12:40 INFO - operating with platform_type : linux_ 18:12:40 INFO - Initialising browser for dromaeo_css test... 18:12:40 INFO - TEST-INFO | started process 18617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwQqYV6/profile http://localhost:56086/getInfo.html) 18:12:43 INFO - PROCESS | 18617 | __metrics Screen width/height:1600/1200 18:12:43 INFO - PROCESS | 18617 | colorDepth:24 18:12:43 INFO - PROCESS | 18617 | Browser inner width/height: 1024/697 18:12:43 INFO - PROCESS | 18617 | __metrics 18:12:44 INFO - PROCESS | 18617 | 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" 18:12:44 INFO - PROCESS | 18617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:12:45 INFO - TEST-INFO | 18617: exit 0 18:12:45 INFO - Browser initialized. 18:12:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:45 INFO - TEST-INFO | started process 18705 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwQqYV6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:57 INFO - PROCESS | 18705 | RSS: Main: 145674240 18:12:57 INFO - PROCESS | 18705 | 18:13:48 INFO - PROCESS | 18705 | 1473815628266 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] 18:15:57 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56086/tests/dromaeo/cssquery-ext.html) 18:15:58 INFO - PROCESS | 18705 | RSS: Main: 225505280 18:15:58 INFO - PROCESS | 18705 | 18:19:00 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-ext.html (next: http://localhost:56086/tests/dromaeo/cssquery-jquery.html) 18:19:01 INFO - PROCESS | 18705 | RSS: Main: 237154304 18:19:01 INFO - PROCESS | 18705 | 18:22:02 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56086/tests/dromaeo/cssquery-mootools.html) 18:22:03 INFO - PROCESS | 18705 | RSS: Main: 203972608 18:22:03 INFO - PROCESS | 18705 | 18:25:05 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56086/tests/dromaeo/cssquery-prototype.html) 18:25:06 INFO - PROCESS | 18705 | RSS: Main: 176287744 18:25:06 INFO - PROCESS | 18705 | 18:28:08 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56086/tests/dromaeo/cssquery-yui.html) 18:28:09 INFO - PROCESS | 18705 | RSS: Main: 176570368 18:28:09 INFO - PROCESS | 18705 | 18:31:11 INFO - PROCESS | 18705 | Cycle 1(1): loaded http://localhost:56086/tests/dromaeo/cssquery-yui.html (next: http://localhost:56086/tests/dromaeo/cssquery-dojo.html) 18:31:12 INFO - PROCESS | 18705 | RSS: Main: 268505088 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | __start_tp_report 18:31:12 INFO - PROCESS | 18705 | _x_x_mozilla_page_load 18:31:12 INFO - PROCESS | 18705 | _x_x_mozilla_page_load_details 18:31:12 INFO - PROCESS | 18705 | |i|pagename|runs| 18:31:12 INFO - PROCESS | 18705 | |0;dojo.html;3201.4237437261295;3212.59842519685;3225.629052658945;3276.7870088444265;3282.524034015068;5487.890242195159;5563.109902415619;5563.638363506379;5570.86072848179;5584.441555844413;5443;5511.3937466878615;5521.751521181546;5528.143137813645;5545.694986775726;4905.533974272456;4985.40175178979;4985.725785081828;5012;5064.24036394542;4989.550940415362;5091.109055915219;5096.210087436453;5099.566536844372;5099.6685215460875;4991.900161996758;5035.194368900977;5054.342935418391;5061.443241243479;5093.668911520739;5119.56483698886;5135.35808023997;5141.485851414866;5141.845744627667;5172.40517340505;4843.975780121112;4919.950800491985;4963.329950456714;5017.64876458648;5033.622478314104;5353.732313384354;5358.33020872391;5385.003774301727;5388.488093631099;5391.730413479349;5847.649141051551;5875.941240587582;5883.14694369314;5884.235049443588;5885.411458854123;5653.208550802891;5889.087191485327;5906.704664766745;5906.8818623627285;5948.910766338508;5588.050031494656;5598.104303311532;5602.38373778884;5604.8038318659055;5625.718714064321;6076.179715738407;6083.6045657032155;6084.908726369108;6091.329953705089;6096.146539484475;5616.1014237721765;5765.3369862465515;5796.101604251347;5811.27359080115;5894.498967587803;5897.439743224472;5905.586608937333;5911.527077833763;5911.793087242012;5941.405859414024;5910.438508341701;5918.585699001114;5923.963306421359;5924.496417804448;5926.644401335933;5650.434956504317;5822.097574875901;5854.2974843018255;5866.266716660418;5946.762129514771;4539.251023581072;4552.84065057728;4553.5218802025975;4559.931601025987;4594.104149690778;4049.5545489996075;4073.5926407359025;4076.2662720709964;4102.548719640837;4109.876703698834;5453.727313634303;5526.889462210734;5563.193336966117;5580.525655319343;5584.21820945068;5849.766009359663;5863.061910094364;5870.853228669232;5880.323762767251;5881.3530511643685;5423.783048678088;5600;5601.691906945157;5612;5615.354234263031;5099.923501147486;5123.795048198031;5140.563052140536;5161.690298582097;5186.481351864859;6179.3202747697715;6209.223847019122;6212.81361559154;6213.565050446426;6215.223097112861;5644.745986430703;5977.581569290108;5990.940090599038;5992.460678538952;5998.370171131968;6078.240219972504;6104;6116.35778243278;6170.043643235126;6181.165542651865;5837.299524056971;5868.530517558671;5871.089981052944;5872.442117998783;5901.822945311647;5732.197492350991;5881.382454842351;5921.230240068763;5931.614445061057;5938.257717785277;5714.657120572779;5754.884902302014;5760.222369980167;5801.129830525286;5840.970795145997;5785.4214578541805;5792.681402522901;5795.536357091507;5799.913001305068;5801.071828507418;5539.667619942817;5780.45085716873;5782.392848750989;5794.826155215182;5796.478316951495;5733.3406658234835;5741.368449470639;5750.827475175752;5859.531237500904;5863.530917526673;5696.946064977992;5702.857428564319;5710.685912274865;5711.143328500758;5714.428557144252 18:31:12 INFO - PROCESS | 18705 | |1;ext.html;604.6704546022418;618.4712071179141;662.2648859765659;679.4869873245699;682.0383259603959;34852.65147348529;46258.76870615651;47272.52727472728;47302.76348618252;47303;49498.25752613714;49958.25062624064;49995.750021249936;50014.49985500144;50037.24944125841;8492.278156356686;9254.583543740531;9409.388389754644;9415.576299066559;10893.564257429693;10902.454877256105;11289.435528223597;11328.490217940212;11329.206955513117;11333.659990200265;11361.829572556418;11396;11410.486528106256;11410.600628977987;11419.31484110956;11254.831177532344;11295;11297.322160670386;11313.377764222965;11315.32108073514;11057.557697692082;11130.944345278222;11142.610008649739;11147.442627868655;11153.553857845676;45003.549964500264;45434.77282613599;45524.089518209454;45596.772016140036;45652.08695826098;3759.830807613637;3897.629725176132;4089.325261331877;4295.2912769393015;4621.699589526704;34933.30133397343;35023;35438.64561354405;35669.82165089158;36508.63491365079;4767.189577771787;4857.781399837016;4858.1012512685265;4901.975490122562;4980.302757613937;383773;384767.0761646202;384851;384925;385165;37499.25001500009;65541;66222.66888665622;66321;66347.66826165839;37913.05217369532;40589.79705101455;40709.1858162841;40756.59243407528;40789;15169.772453413208;15197.240137993056;15228.086314821147;15335;15338.769918451233;12211.877881221075;12260.386980650932;12293.56972505958;12379.381030948416;12856.29290389019;9173.678921237724;9187.310951678517;9201.309901757395;9242.214411775074;9261.398009129385;3491.127218195451;3572.8034958077556;3601.4597810328137;3614.3855544557487;3618.8973547298183;1080.6379862746069;1093.7375029992904;1108.5842808946645;1167.8014737494645;1174.0196935558733;34692.47961280583;34928.84864165609;35184.64815351865;35360.46959295613;35845.64154358423;1125.9662210133708;1156.4333476596576;1185.7095011722183;1243.8072098824696;1245.1532957588927;18271.177796999167;18491.075446227635;18623.255069797055;18691.971941543346;18712.625747484974;705959;706849;715079;715389;716041;63914;64388.35611643917;64398;64534.35465645284;64645.67677161584;1326.6683329167709;1348.1237195822598;1396.6089494941637;1440.0927415728024;1442.4086124689038;31053.534196986613;35812.28375432529;36082.4587631191;36230.09424764402;36318;54661.72669136629;55006.72496637651;55123.448765513436;55236.44763552313;55382;467138;467438;468334;469610;470966;30580.694193057785;31272.687273126976;31499.370012600077;31586.368272633958;33143.83428082844;47777.761111195614;48152.27771583499;48170;48907.26639100487;49041;40673.38989915213;42470.362944555236;42871.3569296467;43040.354394683476;43434;41610.79194604007;42341;42518.78740606277;43109.353359699;43395.56604433915;41945;42705.78647106745;42920;43167;43268;34191;36803.934230788764;37050.44424333691;37859.81070094632;37906;28333.858330708215;28713;28874;28897.42205155927;29040 18:31:12 INFO - PROCESS | 18705 | |2;jquery.html;402.9435878976942;408.3670311017921;416.98123584438696;423.3543845635406;443.81138016343044;15669.921650391747;20126.49683757903;20606.793932060675;20758.169673213073;21329.146834126655;19540.31608893689;19582.11880465379;19609.705854412194;19630.41108766736;19734.91457969811;5766.855828604276;5818.418158184179;5986.281646202462;6024.427679370453;6031.758729650809;5891.793787217448;5964.970175149118;5967.731452084645;5970.134330522072;5970.373110823366;5987.670678112702;6237.095621134933;6278.6232826030355;6362.650054247015;6369.777057802978;5904.3505214426805;6134.110553969649;6304.905426418607;6339.239291285052;6339.492840572766;6042.456178943872;6070.150178974947;6081.847953801146;6082.361352058015;6089.421504957022;15435.614109647237;16865.662686746196;21413.036413361435;25160.245192644248;25458.745412546006;2406.4344878953434;2423.6185374336637;2432.2946345559767;2633.2231991562535;3102.495831985459;10604.575816967312;10862;11025.558977640885;11268;11680.766384672344;1924.7112933060014;1942.9319973800991;1984.9503762405911;1999.800019998003;2021.7270668459716;78508;81459.59270203611;84680.57659711663;87816.560917195;89024;11393.601223957121;11507.71230719222;11696.766064678659;11783.587574434854;11870.821937670942;17027.063511506985;17038.48884533466;17044.659106817795;17048.57378565521;17054.74417883733;8125.756227313072;8252.298554622925;8257.81167990483;8262.256396924306;8506.405546308435;7887.566183859828;7909.841803163905;8044.356451483867;8075.717349892726;8119.959400203079;5020.3724534433195;5054.393472783216;5067.45442639991;5121.795128194831;5180.274761533388;2113.3237364043357;2125.691774692692;2164.231697747277;2168.495387827062;2218.3566765638107;987.4519641598981;993.562832353762;1036.9740756481149;1038.2783965144304;1065.5524679634573;19541.41375758701;20736.688949665768;20811.68782468264;20868.373948781562;20869.895650521954;1020.4284014280106;1021.7139201023576;1085.9619913303154;1112.293693504614;1131.6095946898306;10696.679099627023;11634.6509604712;11695.473703683354;11697.824532632018;11708.297502149899;505315;514771;517004;519560;521408;66823.33176668268;90018.09981900097;90505;92733.5363323179;93001;985.8915519292872;1010.8736407949007;1032.0505135275564;1037.6731329631143;1050.1808589300358;17516.649667006845;17708.645827083128;18356.26574937041;18383.724244136618;18501.167447464628;23926.88036559806;41101.588984110975;41587.7920610395;41877.581224187365;43434.56565434305;57222.7138864303;57889;57937.71031144817;58319.70840145772;59361.703191485496;8985.505797181218;8998.775030624287;9021.639134434548;9076.863847042168;9100.362974591715;11994.280343179436;13640.79538806899;13740.175589464665;13769.724605508034;13778.311084445939;10707.060690202363;11662.12534059966;11959.043276537683;11978.64064078079;12608.49566017349;7545.6981720730555;11513.597024104116;12117.212381195193;12414.193077449936;12703.618891433272;12617.306048167438;12655.240685558896;12711.872881271069;12726.87273127257;13097.214167150001;9320.95339523298;9445.716628501155;9503.477308748084;9507.714768556954;9701.951490242503;9765.462899540593;12046.337451440255;12243.326617035793;12288.938555307166;12983.35083245815 18:31:12 INFO - PROCESS | 18705 | |3;mootools.html;1385.6674398144448;1554.572492564545;1568.7333153363927;1706.1128213329068;1865.5429419792154;8571.742847714562;8891;8936;9180.311476639245;9182.86225706615;8927.241184499326;9014.414063085895;9019.72940811775;9021.233195178402;9034.954825225865;2445.229752627921;2470.72821989581;2471.1968610201757;2472.3571871313497;2476.05909754294;2426.2721183644926;2489.5892177790643;2490.651308816767;2494.102123235634;2495.4011037350997;2360.4688944987324;2368.5144545368157;2467.5311690778785;2468.1854987854053;2473.1096805150223;2374.548835721216;2380.8928598213124;2388.4148383645943;2391;2413.5172965406814;2333.334999525144;2342.8125749939963;2343.5430091132234;2346.085026839534;2352.8235382346393;8904.243139333164;9007.504587247699;9067.365284430092;9068.365214434993;9078.319126065571;5180.922286165711;5373;5391.218273350342;5391.487808658171;5392.487713667196;3541.203229273419;3543.344481270973;3614.5843228028853;3626.528551288342;3658.140337020798;1129.24904938216;1163.2090178678495;1166.5042356998326;1167.3287859480747;1190.8749581294012;12640;12684.23894566329;12692.428840702098;12735.554255601102;12749.808752868621;4608.700434471749;4662.184117779375;4828.468868424541;4855.101806165871;4901.534354236342;9942.900570994341;9953.353032052893;9956.950215248877;9960.850587241197;9970.202383809277;5467.289252397163;5469.972650136803;5486.533644640171;5488.588355873326;5488.670679759227;4178.143480586473;4500.167469018176;4606.516315786861;4918.90162196754;5077.720725360066;3979.801009949491;3994.940075898864;4087.6729861611;4089.2434899543678;4097.97951010243;1245.0662003497378;1267.847858257015;1269.8222248885163;1271.167385362589;1280.5966120671958;1022.1669339488442;1042.6663467690266;1073.2380010192694;1077.4289626497969;1080.5839751695653;6283.246010478681;6437.871242575122;6482.157319548429;6495.1231583736535;6554.344565543408;475.500724239554;479.2882569384531;482.4933819489522;490.57320131485835;491.26065271766475;4339.8698039058245;4507.909841803211;4543.386642803245;4608.5621865922685;4609.792559334838;10779.838302425469;10834.566617335218;10883.346999180074;10891.782164356668;10894.128469722404;10692.09117225029;10831.133509319236;11125.944370278097;11141.053010494037;11396.94301528487;8372.330213582898;8546.401751877309;8575.914240857637;8586.65653373858;8596.054434012254;6367.9044814328745;6459.095726598374;6493.448056915215;6593.868122637617;6650.135482387259;9914.30599858003;10357.067863892285;10358.274920755475;10416.843747343644;10505.054545090978;9409.811803763749;10574.682759517227;10724.195685323475;10814;10851.294665846695;7880.921190788019;8059.43583949118;8094.352451803724;8305.211004954519;8315.00219973607;8656.350773692124;8807.603657835542;8877.24543413817;8933.419327743675;8965.686200982935;7900.920990790249;8031;8034.83930321402;8044.557549334842;8232.835343292982;7437.107547094383;7707.383409327128;7760.456768026184;7836.177201394;7867.488613240121;7316.341529262392;7388.556686598821;7457.738979135921;7458.440616953859;7585.582792946441;5526.861828454321;5547.528460080938;5708.600397972268;5722.313322401338;5902.6458412495385;5512.4211957743855;5611.396557664306;5739.426057394394;5804.6807425592;5813.94186058134 18:31:12 INFO - PROCESS | 18705 | |4;prototype.html;354.0776277796341;356.7698834251906;357.57270062275575;362.45269642839304;367.60666087286614;5482;5510.173473978905;5513.972430137844;5517.503424691777;5530.391656917746;5425.9728701356535;5430.56555475562;5456.61803673743;5466.535344495713;5475.72621368932;3930.528336599612;4012.0972781124306;4274.487061552618;4300;4329.307310830268;4321.481422229337;4325.199838029975;4329.523752387235;4339.544347843478;4345.196138714332;4293.65650747941;4299.742015479082;4300.935485967713;4307.784610769465;4312.331588603755;4076.4496792932873;4105;4249.851255206053;4277.8288868445225;4310.784460776948;3825.1775868188474;3827.081500439874;3827.7129215308964;3829.463875057494;3839.063116715715;5225.581953443715;5273.551748101416;5291.497307755757;5318.680879147263;5323.574114070865;4810.663253572251;4892.9235568174945;4916.222334381063;4922.778474968635;4923.10460472717;5614.747336369833;5630.465105814941;5650.785081207519;5652.004437648053;5661.915071273934;4152.937705934413;4187.790610469464;4206.831726730927;4212.588782757749;4215;5624.803131890364;5787.50806181475;5820.534357251452;5841.094630332305;5893.734781934781;4184.4141820145205;4244.405783190356;4250.149970006011;4259.531451540328;4289.506706728766;4332.588404101605;4341.934870976938;4343.457067866517;4350.347447882843;4353.499347575006;4636.4899861015265;4887.780049897763;4891.706497610155;4911.336969509142;4923.803047878045;5760.36635970043;5772.567057470623;5776.826695199151;5779.566532510079;5793.536517078624;1473.4253641079988;1490.6422458609857;1496.5585152380074;1496.6108811709034;1502.706972140444;927.6753136402206;934.9158575728217;958.6980101268078;963.4075043847984;971.13568923658;5469.808556700496;5553.972230138824;5562.360328562268;5577.135543990688;5587.804426845041;5862.882742345129;5882.499987501024;5891.293044834647;5898.970505147447;5905.966455870292;5399.757010934517;5579.2746942897165;5620.409856965041;5623.353314368818;5626.2685850839125;3065.9540106898417;3088.984555077255;3090.2119959410206;3098.7830851840604;3109.5646609474693;5825.427134673614;6062.484688801501;6067.544934129958;6067.905368262218;6069.302030266487;5900.1739756434135;5916.757480929053;5945.613535120204;5950.619752099108;5968.224130863046;5971.671558064349;6058.1215723719815;6067.33148384189;6069.33237343883;6110.297315808739;5675.744591493475;5762.2221000164445;5784.94215057844;5814.970925145516;5901.822945311647;5777.306723193244;5779.739911704068;5800.796972105956;5818.563607729353;5837.956715149664;5794.5364370851075;6067.757289708362;6079.422454866781;6086.360932102066;6087.634741915499;4310.676699247631;4384.276594361894;4390.253656878274;4393.582609652078;4402.141582391531;5860.736266867916;5866.94133058681;5888.735006924784;5924.170616113662;5927.940720592739;5554.611177217556;5729.455701708331;5742.483176513967;5775.48020678141;5782.421757824184;5701.6864072474755;5703.686297253691;5707.714614269187;5708.315002199763;5732.34078081026;5481.342238931354;5687.118496633028;5696.863676121455;5718.313802343745;5730.484256416942;5327.706976116196;5736.598438109292;5767.884642307046;5774.2782152230975;5803.7968671096305;5609.382967873612;5611.915821262603;5615.943840561542;5618;5621.9156712650165 18:31:12 INFO - PROCESS | 18705 | |5;yui.html;804.1556365815892;857.2413414128495;886.2865393358347;891.1088911088913;905.5312035106753;16996.490105296845;18150.63698726028;18161;18172.18225179867;18182.999935003587;15635.983661062022;15676.529704108867;15799.447019354326;16151.757723634126;16463.588410289747;728.1589763822793;743.1602289412956;744.7356188553067;750.2759836757527;858.7552547523957;871.6600525794945;874.711345256064;879.5865943006809;880.5245167609484;881.6781874615783;944.2681921510849;945.6595625574788;946.4747065378712;946.5551190940247;946.5551190940247;944.8724422203052;945.6359301668873;945.6690158444524;945.6784693204274;946.74911148545;560.7448610882079;656.7799787071336;674.3189378727471;679.2663922963188;691.0705101638289;16075.356985720675;16123.274452649659;16148.354065837353;16166.191690415431;16171.676566468605;14776.778348324784;15693.529194124081;15808.367665293374;15863.682726345409;16314.265858036417;14353.210573418457;14364.936855785132;14520.564383068524;14630.268486575735;14707;4515.61617262533;4528.25283828168;4551.203539380628;4600.309953506967;4623.7225766453785;84724.57637711834;87209.56395218047;88344.11655883488;88409.55795220983;89688.5515572418;11518.712032199095;11560.479778409992;11612.012978896719;12472.688182795502;12721.173123746927;21986.560268794536;22049.559008819735;22055.11779528801;22060.779392206194;22092;8983.820323593622;9053.456792592466;9091.863622045543;9142.542872856331;9449.055094490634;4923.138450771101;5051.368578927634;5063.367079115111;5088.414832294259;5237.188235823453;3704.1665625234104;3819.6371344722206;3856.0359910022494;3859.9807000964815;3863.130795570974;647.4626060369882;653.7679123911038;659.8416380068845;664.6709878610022;672.5225090185925;714.8856183010694;725.5537844225763;741.2143128284036;747.0960138232799;781.6365390093633;9989.400635961865;10251.38491690501;10391.272610917315;10420.531076101593;10443.84334234987;618.5391883047068;629.9716512756937;667.6995352091578;699.6152116335995;708.3553965891117;4628.166929952574;4871.48849370811;4895.681780684244;4916.360873086476;4956.207006878882;77064.61467692626;83346;133942;134250;135238;108564.4571777136;111377;112586.43706781414;112605;116312;100990.4950475243;101327;101503;102596;102766.48616756868;8678.479291242546;9109.407888487227;9186.586603602987;9359.204467620328;9368.81262374735;54571.454285458225;55183.72408137934;55615.16577251425;57137.42862571321;58285.417145827996;81536;88191.55904220438;88211.55894220488;88855;90977;12092.697682558006;12354.196977196454;12525.248485090924;12615.116941814353;12688.365581721062;13800.792988104986;13914.09558378702;13915.512957046454;13940.721185576029;14008.579742607739;12741.55404560836;12980.091393602357;13068.411921463367;13194.472221111431;13368.933155334551;12149.635510934333;12392.442340094538;12842.165259258118;12983;13108.868911311147;12498;12579.119461637598;12673.112882098165;13200.14199077057;13291;10061;10604.469776511298;10825.837612435977;10878.910321959469;11058.889411105785;10330.535125919501;11042.282251653616;11244.66266012021;11301.26541774782;11348.319100853974 18:31:12 INFO - PROCESS | 18705 | __end_tp_report 18:31:12 INFO - PROCESS | 18705 | __start_cc_report 18:31:12 INFO - PROCESS | 18705 | _x_x_mozilla_cycle_collect,1079 18:31:12 INFO - PROCESS | 18705 | __end_cc_report 18:31:12 INFO - PROCESS | 18705 | __startTimestamp1473816672032__endTimestamp 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | ------- Summary: start ------- 18:31:12 INFO - PROCESS | 18705 | Number of tests: 6 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#0] dojo.html Cycles:165 Average:5494.99 Median:5710.69 stddev:612.74 (10.7%) stddev-sans-first:587.60 18:31:12 INFO - PROCESS | 18705 | Values: 3201.4 3212.6 3225.6 3276.8 3282.5 5487.9 5563.1 5563.6 5570.9 5584.4 5443.0 5511.4 5521.8 5528.1 5545.7 4905.5 4985.4 4985.7 5012.0 5064.2 4989.6 5091.1 5096.2 5099.6 5099.7 4991.9 5035.2 5054.3 5061.4 5093.7 5119.6 5135.4 5141.5 5141.8 5172.4 4844.0 4920.0 4963.3 5017.6 5033.6 5353.7 5358.3 5385.0 5388.5 5391.7 5847.6 5875.9 5883.1 5884.2 5885.4 5653.2 5889.1 5906.7 5906.9 5948.9 5588.1 5598.1 5602.4 5604.8 5625.7 6076.2 6083.6 6084.9 6091.3 6096.1 5616.1 5765.3 5796.1 5811.3 5894.5 5897.4 5905.6 5911.5 5911.8 5941.4 5910.4 5918.6 5924.0 5924.5 5926.6 5650.4 5822.1 5854.3 5866.3 5946.8 4539.3 4552.8 4553.5 4559.9 4594.1 4049.6 4073.6 4076.3 4102.5 4109.9 5453.7 5526.9 5563.2 5580.5 5584.2 5849.8 5863.1 5870.9 5880.3 5881.4 5423.8 5600.0 5601.7 5612.0 5615.4 5099.9 5123.8 5140.6 5161.7 5186.5 6179.3 6209.2 6212.8 6213.6 6215.2 5644.7 5977.6 5990.9 5992.5 5998.4 6078.2 6104.0 6116.4 6170.0 6181.2 5837.3 5868.5 5871.1 5872.4 5901.8 5732.2 5881.4 5921.2 5931.6 5938.3 5714.7 5754.9 5760.2 5801.1 5841.0 5785.4 5792.7 5795.5 5799.9 5801.1 5539.7 5780.5 5782.4 5794.8 5796.5 5733.3 5741.4 5750.8 5859.5 5863.5 5696.9 5702.9 5710.7 5711.1 5714.4 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#1] ext.html Cycles:180 Average:67538.41 Median:384270.04 stddev:144310.62 (37.6%) stddev-sans-first:144627.95 18:31:12 INFO - PROCESS | 18705 | Values: 604.7 618.5 662.3 679.5 682.0 34852.7 46258.8 47272.5 47302.8 47303.0 49498.3 49958.3 49995.8 50014.5 50037.2 8492.3 9254.6 9409.4 9415.6 10893.6 10902.5 11289.4 11328.5 11329.2 11333.7 11361.8 11396.0 11410.5 11410.6 11419.3 11254.8 11295.0 11297.3 11313.4 11315.3 11057.6 11130.9 11142.6 11147.4 11153.6 45003.5 45434.8 45524.1 45596.8 45652.1 3759.8 3897.6 4089.3 4295.3 4621.7 34933.3 35023.0 35438.6 35669.8 36508.6 4767.2 4857.8 4858.1 4902.0 4980.3 383773.0 384767.1 384851.0 384925.0 385165.0 37499.3 65541.0 66222.7 66321.0 66347.7 37913.1 40589.8 40709.2 40756.6 40789.0 15169.8 15197.2 15228.1 15335.0 15338.8 12211.9 12260.4 12293.6 12379.4 12856.3 9173.7 9187.3 9201.3 9242.2 9261.4 3491.1 3572.8 3601.5 3614.4 3618.9 1080.6 1093.7 1108.6 1167.8 1174.0 34692.5 34928.8 35184.6 35360.5 35845.6 1126.0 1156.4 1185.7 1243.8 1245.2 18271.2 18491.1 18623.3 18692.0 18712.6 705959.0 706849.0 715079.0 715389.0 716041.0 63914.0 64388.4 64398.0 64534.4 64645.7 1326.7 1348.1 1396.6 1440.1 1442.4 31053.5 35812.3 36082.5 36230.1 36318.0 54661.7 55006.7 55123.4 55236.4 55382.0 467138.0 467438.0 468334.0 469610.0 470966.0 30580.7 31272.7 31499.4 31586.4 33143.8 47777.8 48152.3 48170.0 48907.3 49041.0 40673.4 42470.4 42871.4 43040.4 43434.0 41610.8 42341.0 42518.8 43109.4 43395.6 41945.0 42705.8 42920.0 43167.0 43268.0 34191.0 36803.9 37050.4 37859.8 37906.0 28333.9 28713.0 28874.0 28897.4 29040.0 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#2] jquery.html Cycles:180 Average:29855.81 Median:2427.96 stddev:84790.02 (3492.2%) stddev-sans-first:84999.04 18:31:12 INFO - PROCESS | 18705 | Values: 402.9 408.4 417.0 423.4 443.8 15669.9 20126.5 20606.8 20758.2 21329.1 19540.3 19582.1 19609.7 19630.4 19734.9 5766.9 5818.4 5986.3 6024.4 6031.8 5891.8 5965.0 5967.7 5970.1 5970.4 5987.7 6237.1 6278.6 6362.7 6369.8 5904.4 6134.1 6304.9 6339.2 6339.5 6042.5 6070.2 6081.8 6082.4 6089.4 15435.6 16865.7 21413.0 25160.2 25458.7 2406.4 2423.6 2432.3 2633.2 3102.5 10604.6 10862.0 11025.6 11268.0 11680.8 1924.7 1942.9 1985.0 1999.8 2021.7 78508.0 81459.6 84680.6 87816.6 89024.0 11393.6 11507.7 11696.8 11783.6 11870.8 17027.1 17038.5 17044.7 17048.6 17054.7 8125.8 8252.3 8257.8 8262.3 8506.4 7887.6 7909.8 8044.4 8075.7 8120.0 5020.4 5054.4 5067.5 5121.8 5180.3 2113.3 2125.7 2164.2 2168.5 2218.4 987.5 993.6 1037.0 1038.3 1065.6 19541.4 20736.7 20811.7 20868.4 20869.9 1020.4 1021.7 1086.0 1112.3 1131.6 10696.7 11634.7 11695.5 11697.8 11708.3 505315.0 514771.0 517004.0 519560.0 521408.0 66823.3 90018.1 90505.0 92733.5 93001.0 985.9 1010.9 1032.1 1037.7 1050.2 17516.6 17708.6 18356.3 18383.7 18501.2 23926.9 41101.6 41587.8 41877.6 43434.6 57222.7 57889.0 57937.7 58319.7 59361.7 8985.5 8998.8 9021.6 9076.9 9100.4 11994.3 13640.8 13740.2 13769.7 13778.3 10707.1 11662.1 11959.0 11978.6 12608.5 7545.7 11513.6 12117.2 12414.2 12703.6 12617.3 12655.2 12711.9 12726.9 13097.2 9321.0 9445.7 9503.5 9507.7 9702.0 9765.5 12046.3 12243.3 12288.9 12983.4 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#3] mootools.html Cycles:180 Average:5986.23 Median:2655.48 stddev:3361.02 (126.6%) stddev-sans-first:3352.67 18:31:12 INFO - PROCESS | 18705 | Values: 1385.7 1554.6 1568.7 1706.1 1865.5 8571.7 8891.0 8936.0 9180.3 9182.9 8927.2 9014.4 9019.7 9021.2 9035.0 2445.2 2470.7 2471.2 2472.4 2476.1 2426.3 2489.6 2490.7 2494.1 2495.4 2360.5 2368.5 2467.5 2468.2 2473.1 2374.5 2380.9 2388.4 2391.0 2413.5 2333.3 2342.8 2343.5 2346.1 2352.8 8904.2 9007.5 9067.4 9068.4 9078.3 5180.9 5373.0 5391.2 5391.5 5392.5 3541.2 3543.3 3614.6 3626.5 3658.1 1129.2 1163.2 1166.5 1167.3 1190.9 12640.0 12684.2 12692.4 12735.6 12749.8 4608.7 4662.2 4828.5 4855.1 4901.5 9942.9 9953.4 9957.0 9960.9 9970.2 5467.3 5470.0 5486.5 5488.6 5488.7 4178.1 4500.2 4606.5 4918.9 5077.7 3979.8 3994.9 4087.7 4089.2 4098.0 1245.1 1267.8 1269.8 1271.2 1280.6 1022.2 1042.7 1073.2 1077.4 1080.6 6283.2 6437.9 6482.2 6495.1 6554.3 475.5 479.3 482.5 490.6 491.3 4339.9 4507.9 4543.4 4608.6 4609.8 10779.8 10834.6 10883.3 10891.8 10894.1 10692.1 10831.1 11125.9 11141.1 11396.9 8372.3 8546.4 8575.9 8586.7 8596.1 6367.9 6459.1 6493.4 6593.9 6650.1 9914.3 10357.1 10358.3 10416.8 10505.1 9409.8 10574.7 10724.2 10814.0 10851.3 7880.9 8059.4 8094.4 8305.2 8315.0 8656.4 8807.6 8877.2 8933.4 8965.7 7900.9 8031.0 8034.8 8044.6 8232.8 7437.1 7707.4 7760.5 7836.2 7867.5 7316.3 7388.6 7457.7 7458.4 7585.6 5526.9 5547.5 5708.6 5722.3 5902.6 5512.4 5611.4 5739.4 5804.7 5813.9 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#4] prototype.html Cycles:180 Average:4833.63 Median:5558.49 stddev:1406.07 (25.3%) stddev-sans-first:1369.22 18:31:12 INFO - PROCESS | 18705 | Values: 354.1 356.8 357.6 362.5 367.6 5482.0 5510.2 5514.0 5517.5 5530.4 5426.0 5430.6 5456.6 5466.5 5475.7 3930.5 4012.1 4274.5 4300.0 4329.3 4321.5 4325.2 4329.5 4339.5 4345.2 4293.7 4299.7 4300.9 4307.8 4312.3 4076.4 4105.0 4249.9 4277.8 4310.8 3825.2 3827.1 3827.7 3829.5 3839.1 5225.6 5273.6 5291.5 5318.7 5323.6 4810.7 4892.9 4916.2 4922.8 4923.1 5614.7 5630.5 5650.8 5652.0 5661.9 4152.9 4187.8 4206.8 4212.6 4215.0 5624.8 5787.5 5820.5 5841.1 5893.7 4184.4 4244.4 4250.1 4259.5 4289.5 4332.6 4341.9 4343.5 4350.3 4353.5 4636.5 4887.8 4891.7 4911.3 4923.8 5760.4 5772.6 5776.8 5779.6 5793.5 1473.4 1490.6 1496.6 1496.6 1502.7 927.7 934.9 958.7 963.4 971.1 5469.8 5554.0 5562.4 5577.1 5587.8 5862.9 5882.5 5891.3 5899.0 5906.0 5399.8 5579.3 5620.4 5623.4 5626.3 3066.0 3089.0 3090.2 3098.8 3109.6 5825.4 6062.5 6067.5 6067.9 6069.3 5900.2 5916.8 5945.6 5950.6 5968.2 5971.7 6058.1 6067.3 6069.3 6110.3 5675.7 5762.2 5784.9 5815.0 5901.8 5777.3 5779.7 5800.8 5818.6 5838.0 5794.5 6067.8 6079.4 6086.4 6087.6 4310.7 4384.3 4390.3 4393.6 4402.1 5860.7 5866.9 5888.7 5924.2 5927.9 5554.6 5729.5 5742.5 5775.5 5782.4 5701.7 5703.7 5707.7 5708.3 5732.3 5481.3 5687.1 5696.9 5718.3 5730.5 5327.7 5736.6 5767.9 5774.3 5803.8 5609.4 5611.9 5615.9 5618.0 5621.9 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - PROCESS | 18705 | [#5] yui.html Cycles:180 Average:22590.85 Median:3861.56 stddev:33398.29 (864.9%) stddev-sans-first:33451.91 18:31:12 INFO - PROCESS | 18705 | Values: 804.2 857.2 886.3 891.1 905.5 16996.5 18150.6 18161.0 18172.2 18183.0 15636.0 15676.5 15799.4 16151.8 16463.6 728.2 743.2 744.7 750.3 858.8 871.7 874.7 879.6 880.5 881.7 944.3 945.7 946.5 946.6 946.6 944.9 945.6 945.7 945.7 946.7 560.7 656.8 674.3 679.3 691.1 16075.4 16123.3 16148.4 16166.2 16171.7 14776.8 15693.5 15808.4 15863.7 16314.3 14353.2 14364.9 14520.6 14630.3 14707.0 4515.6 4528.3 4551.2 4600.3 4623.7 84724.6 87209.6 88344.1 88409.6 89688.6 11518.7 11560.5 11612.0 12472.7 12721.2 21986.6 22049.6 22055.1 22060.8 22092.0 8983.8 9053.5 9091.9 9142.5 9449.1 4923.1 5051.4 5063.4 5088.4 5237.2 3704.2 3819.6 3856.0 3860.0 3863.1 647.5 653.8 659.8 664.7 672.5 714.9 725.6 741.2 747.1 781.6 9989.4 10251.4 10391.3 10420.5 10443.8 618.5 630.0 667.7 699.6 708.4 4628.2 4871.5 4895.7 4916.4 4956.2 77064.6 83346.0 133942.0 134250.0 135238.0 108564.5 111377.0 112586.4 112605.0 116312.0 100990.5 101327.0 101503.0 102596.0 102766.5 8678.5 9109.4 9186.6 9359.2 9368.8 54571.5 55183.7 55615.2 57137.4 58285.4 81536.0 88191.6 88211.6 88855.0 90977.0 12092.7 12354.2 12525.2 12615.1 12688.4 13800.8 13914.1 13915.5 13940.7 14008.6 12741.6 12980.1 13068.4 13194.5 13368.9 12149.6 12392.4 12842.2 12983.0 13108.9 12498.0 12579.1 12673.1 13200.1 13291.0 10061.0 10604.5 10825.8 10878.9 11058.9 10330.5 11042.3 11244.7 11301.3 11348.3 18:31:12 INFO - PROCESS | 18705 | -------- Summary: end -------- 18:31:12 INFO - PROCESS | 18705 | 18:31:12 INFO - TEST-INFO | 18705: exit 0 18:31:12 INFO - TEST-OK | dromaeo_css | took 1112741ms 18:31:12 INFO - TEST-START | kraken 18:31:12 INFO - operating with platform_type : linux_ 18:31:12 INFO - Initialising browser for kraken test... 18:31:12 INFO - TEST-INFO | started process 18910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0G9ms/profile http://localhost:56086/getInfo.html) 18:31:16 INFO - PROCESS | 18910 | __metrics Screen width/height:1600/1200 18:31:16 INFO - PROCESS | 18910 | colorDepth:24 18:31:16 INFO - PROCESS | 18910 | Browser inner width/height: 1024/697 18:31:16 INFO - PROCESS | 18910 | __metrics 18:31:17 INFO - PROCESS | 18910 | 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" 18:31:17 INFO - PROCESS | 18910 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:31:18 INFO - TEST-INFO | 18910: exit 0 18:31:18 INFO - Browser initialized. 18:31:18 INFO - Running cycle 1/1 for kraken test... 18:31:18 INFO - TEST-INFO | started process 18999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0G9ms/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:30 INFO - PROCESS | 18999 | RSS: Main: 146128896 18:31:30 INFO - PROCESS | 18999 | 18:31:32 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56086/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:33 INFO - PROCESS | 18999 | RSS: Main: 200802304 18:31:33 INFO - PROCESS | 18999 | 18:31:35 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56086/tests/kraken/driver.html?testName=audio-dft) 18:31:36 INFO - PROCESS | 18999 | RSS: Main: 185266176 18:31:36 INFO - PROCESS | 18999 | 18:31:39 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56086/tests/kraken/driver.html?testName=audio-fft) 18:31:39 INFO - PROCESS | 18999 | RSS: Main: 276172800 18:31:39 INFO - PROCESS | 18999 | 18:31:41 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56086/tests/kraken/driver.html?testName=audio-oscillator) 18:31:42 INFO - PROCESS | 18999 | RSS: Main: 182341632 18:31:42 INFO - PROCESS | 18999 | 18:31:44 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56086/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:45 INFO - PROCESS | 18999 | RSS: Main: 181125120 18:31:45 INFO - PROCESS | 18999 | 18:31:48 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56086/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:48 INFO - PROCESS | 18999 | RSS: Main: 216723456 18:31:48 INFO - PROCESS | 18999 | 18:31:52 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56086/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:52 INFO - PROCESS | 18999 | RSS: Main: 215920640 18:31:52 INFO - PROCESS | 18999 | 18:31:55 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56086/tests/kraken/driver.html?testName=json-parse-financial) 18:31:56 INFO - PROCESS | 18999 | RSS: Main: 215764992 18:31:56 INFO - PROCESS | 18999 | 18:31:57 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56086/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:58 INFO - PROCESS | 18999 | RSS: Main: 178057216 18:31:58 INFO - PROCESS | 18999 | 18:32:00 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:00 INFO - PROCESS | 18999 | RSS: Main: 185233408 18:32:00 INFO - PROCESS | 18999 | 18:32:02 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:03 INFO - PROCESS | 18999 | RSS: Main: 187080704 18:32:03 INFO - PROCESS | 18999 | 18:32:05 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:06 INFO - PROCESS | 18999 | RSS: Main: 186974208 18:32:06 INFO - PROCESS | 18999 | 18:32:09 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:09 INFO - PROCESS | 18999 | RSS: Main: 185319424 18:32:09 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | Cycle 1(1): loaded http://localhost:56086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56086/tests/kraken/driver.html?testName=ai-astar) 18:32:11 INFO - PROCESS | 18999 | RSS: Main: 236732416 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | __start_tp_report 18:32:11 INFO - PROCESS | 18999 | _x_x_mozilla_page_load 18:32:11 INFO - PROCESS | 18999 | _x_x_mozilla_page_load_details 18:32:11 INFO - PROCESS | 18999 | |i|pagename|runs| 18:32:11 INFO - PROCESS | 18999 | |0;ai-astar;111;109;120;110;110;109;110;110;110;111 18:32:11 INFO - PROCESS | 18999 | |1;audio-beat-detection;157;116;136;168;116;144;139;117;172;114 18:32:11 INFO - PROCESS | 18999 | |2;audio-dft;161;162;163;161;160;159;161;161;162;160 18:32:11 INFO - PROCESS | 18999 | |3;audio-fft;77;74;75;181;74;75;75;74;176;74 18:32:11 INFO - PROCESS | 18999 | |4;audio-oscillator;83;83;153;83;83;83;152;84;83;149 18:32:11 INFO - PROCESS | 18999 | |5;imaging-gaussian-blur;117;116;117;119;117;117;118;117;117;116 18:32:11 INFO - PROCESS | 18999 | |6;imaging-darkroom;146;145;146;145;145;145;145;147;146;146 18:32:11 INFO - PROCESS | 18999 | |7;imaging-desaturate;95;95;95;95;95;94;94;95;95;95 18:32:11 INFO - PROCESS | 18999 | |8;json-parse-financial;50;53;51;50;50;51;52;50;50;51 18:32:11 INFO - PROCESS | 18999 | |9;json-stringify-tinderbox;59;60;59;59;59;59;60;59;60;63 18:32:11 INFO - PROCESS | 18999 | |10;stanford-crypto-aes;72;74;76;73;77;72;73;72;72;73 18:32:11 INFO - PROCESS | 18999 | |11;stanford-crypto-ccm;118;128;115;129;127;114;119;115;151;118 18:32:11 INFO - PROCESS | 18999 | |12;stanford-crypto-pbkdf2;154;155;151;158;159;153;152;155;151;154 18:32:11 INFO - PROCESS | 18999 | |13;stanford-crypto-sha256-iterative;65;63;65;63;62;64;64;60;65;65 18:32:11 INFO - PROCESS | 18999 | __end_tp_report 18:32:11 INFO - PROCESS | 18999 | __start_cc_report 18:32:11 INFO - PROCESS | 18999 | _x_x_mozilla_cycle_collect,1678 18:32:11 INFO - PROCESS | 18999 | __end_cc_report 18:32:11 INFO - PROCESS | 18999 | __startTimestamp1473816731505__endTimestamp 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | ------- Summary: start ------- 18:32:11 INFO - PROCESS | 18999 | Number of tests: 14 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.00 stddev:3.23 (2.9%) stddev-sans-first:3.43 18:32:11 INFO - PROCESS | 18999 | Values: 111.0 109.0 120.0 110.0 110.0 109.0 110.0 110.0 110.0 111.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:141.50 stddev:22.22 (15.7%) stddev-sans-first:22.47 18:32:11 INFO - PROCESS | 18999 | Values: 157.0 116.0 136.0 168.0 116.0 144.0 139.0 117.0 172.0 114.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 18:32:11 INFO - PROCESS | 18999 | Values: 161.0 162.0 163.0 161.0 160.0 159.0 161.0 161.0 162.0 160.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#3] audio-fft Cycles:10 Average:95.50 Median:74.50 stddev:43.77 (58.8%) stddev-sans-first:45.91 18:32:11 INFO - PROCESS | 18999 | Values: 77.0 74.0 75.0 181.0 74.0 75.0 75.0 74.0 176.0 74.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:83.00 stddev:32.96 (39.7%) stddev-sans-first:34.10 18:32:11 INFO - PROCESS | 18999 | Values: 83.0 83.0 153.0 83.0 83.0 83.0 152.0 84.0 83.0 149.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 18:32:11 INFO - PROCESS | 18999 | Values: 117.0 116.0 117.0 119.0 117.0 117.0 118.0 117.0 117.0 116.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 18:32:11 INFO - PROCESS | 18999 | Values: 146.0 145.0 146.0 145.0 145.0 145.0 145.0 147.0 146.0 146.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:32:11 INFO - PROCESS | 18999 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.03 (2.0%) stddev-sans-first:1.05 18:32:11 INFO - PROCESS | 18999 | Values: 50.0 53.0 51.0 50.0 50.0 51.0 52.0 50.0 50.0 51.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:1.25 (2.1%) stddev-sans-first:1.30 18:32:11 INFO - PROCESS | 18999 | Values: 59.0 60.0 59.0 59.0 59.0 59.0 60.0 59.0 60.0 63.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:73.00 stddev:1.78 (2.4%) stddev-sans-first:1.81 18:32:11 INFO - PROCESS | 18999 | Values: 72.0 74.0 76.0 73.0 77.0 72.0 73.0 72.0 72.0 73.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:123.00 stddev:11.23 (9.1%) stddev-sans-first:11.74 18:32:11 INFO - PROCESS | 18999 | Values: 118.0 128.0 115.0 129.0 127.0 114.0 119.0 115.0 151.0 118.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:154.50 stddev:2.70 (1.7%) stddev-sans-first:2.86 18:32:11 INFO - PROCESS | 18999 | Values: 154.0 155.0 151.0 158.0 159.0 153.0 152.0 155.0 151.0 154.0 18:32:11 INFO - PROCESS | 18999 | 18:32:11 INFO - PROCESS | 18999 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.50 stddev:1.65 (2.6%) stddev-sans-first:1.67 18:32:11 INFO - PROCESS | 18999 | Values: 65.0 63.0 65.0 63.0 62.0 64.0 64.0 60.0 65.0 65.0 18:32:11 INFO - PROCESS | 18999 | -------- Summary: end -------- 18:32:11 INFO - PROCESS | 18999 | 18:32:12 INFO - TEST-INFO | 18999: exit 0 18:32:12 INFO - TEST-OK | kraken | took 59887ms 18:32:12 INFO - SUITE-END | took 1172s 18:32:13 INFO - Completed test suite (00:19:33) 18:32:13 INFO - javascript benchmark 18:32:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.4237437261295, 3212.59842519685, 3225.629052658945, 3276.7870088444265, 3282.524034015068, 5487.890242195159, 5563.109902415619, 5563.638363506379, 5570.86072848179, 5584.441555844413, 5443.0, 5511.3937466878615, 5521.751521181546, 5528.143137813645, 5545.694986775726, 4905.533974272456, 4985.40175178979, 4985.725785081828, 5012.0, 5064.24036394542, 4989.550940415362, 5091.109055915219, 5096.210087436453, 5099.566536844372, 5099.6685215460875, 4991.900161996758, 5035.194368900977, 5054.342935418391, 5061.443241243479, 5093.668911520739, 5119.56483698886, 5135.35808023997, 5141.485851414866, 5141.845744627667, 5172.40517340505, 4843.975780121112, 4919.950800491985, 4963.329950456714, 5017.64876458648, 5033.622478314104, 5353.732313384354, 5358.33020872391, 5385.003774301727, 5388.488093631099, 5391.730413479349, 5847.649141051551, 5875.941240587582, 5883.14694369314, 5884.235049443588, 5885.411458854123, 5653.208550802891, 5889.087191485327, 5906.704664766745, 5906.8818623627285, 5948.910766338508, 5588.050031494656, 5598.104303311532, 5602.38373778884, 5604.8038318659055, 5625.718714064321, 6076.179715738407, 6083.6045657032155, 6084.908726369108, 6091.329953705089, 6096.146539484475, 5616.1014237721765, 5765.3369862465515, 5796.101604251347, 5811.27359080115, 5894.498967587803, 5897.439743224472, 5905.586608937333, 5911.527077833763, 5911.793087242012, 5941.405859414024, 5910.438508341701, 5918.585699001114, 5923.963306421359, 5924.496417804448, 5926.644401335933, 5650.434956504317, 5822.097574875901, 5854.2974843018255, 5866.266716660418, 5946.762129514771, 4539.251023581072, 4552.84065057728, 4553.5218802025975, 4559.931601025987, 4594.104149690778, 4049.5545489996075, 4073.5926407359025, 4076.2662720709964, 4102.548719640837, 4109.876703698834, 5453.727313634303, 5526.889462210734, 5563.193336966117, 5580.525655319343, 5584.21820945068, 5849.766009359663, 5863.061910094364, 5870.853228669232, 5880.323762767251, 5881.3530511643685, 5423.783048678088, 5600.0, 5601.691906945157, 5612.0, 5615.354234263031, 5099.923501147486, 5123.795048198031, 5140.563052140536, 5161.690298582097, 5186.481351864859, 6179.3202747697715, 6209.223847019122, 6212.81361559154, 6213.565050446426, 6215.223097112861, 5644.745986430703, 5977.581569290108, 5990.940090599038, 5992.460678538952, 5998.370171131968, 6078.240219972504, 6104.0, 6116.35778243278, 6170.043643235126, 6181.165542651865, 5837.299524056971, 5868.530517558671, 5871.089981052944, 5872.442117998783, 5901.822945311647, 5732.197492350991, 5881.382454842351, 5921.230240068763, 5931.614445061057, 5938.257717785277, 5714.657120572779, 5754.884902302014, 5760.222369980167, 5801.129830525286, 5840.970795145997, 5785.4214578541805, 5792.681402522901, 5795.536357091507, 5799.913001305068, 5801.071828507418, 5539.667619942817, 5780.45085716873, 5782.392848750989, 5794.826155215182, 5796.478316951495, 5733.3406658234835, 5741.368449470639, 5750.827475175752, 5859.531237500904, 5863.530917526673, 5696.946064977992, 5702.857428564319, 5710.685912274865, 5711.143328500758, 5714.428557144252], "value": 5454.63209494349, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.6704546022418, 618.4712071179141, 662.2648859765659, 679.4869873245699, 682.0383259603959, 34852.65147348529, 46258.76870615651, 47272.52727472728, 47302.76348618252, 47303.0, 49498.25752613714, 49958.25062624064, 49995.750021249936, 50014.49985500144, 50037.24944125841, 8492.278156356686, 9254.583543740531, 9409.388389754644, 9415.576299066559, 10893.564257429693, 10902.454877256105, 11289.435528223597, 11328.490217940212, 11329.206955513117, 11333.659990200265, 11361.829572556418, 11396.0, 11410.486528106256, 11410.600628977987, 11419.31484110956, 11254.831177532344, 11295.0, 11297.322160670386, 11313.377764222965, 11315.32108073514, 11057.557697692082, 11130.944345278222, 11142.610008649739, 11147.442627868655, 11153.553857845676, 45003.549964500264, 45434.77282613599, 45524.089518209454, 45596.772016140036, 45652.08695826098, 3759.830807613637, 3897.629725176132, 4089.325261331877, 4295.2912769393015, 4621.699589526704, 34933.30133397343, 35023.0, 35438.64561354405, 35669.82165089158, 36508.63491365079, 4767.189577771787, 4857.781399837016, 4858.1012512685265, 4901.975490122562, 4980.302757613937, 383773.0, 384767.0761646202, 384851.0, 384925.0, 385165.0, 37499.25001500009, 65541.0, 66222.66888665622, 66321.0, 66347.66826165839, 37913.05217369532, 40589.79705101455, 40709.1858162841, 40756.59243407528, 40789.0, 15169.772453413208, 15197.240137993056, 15228.086314821147, 15335.0, 15338.769918451233, 12211.877881221075, 12260.386980650932, 12293.56972505958, 12379.381030948416, 12856.29290389019, 9173.678921237724, 9187.310951678517, 9201.309901757395, 9242.214411775074, 9261.398009129385, 3491.127218195451, 3572.8034958077556, 3601.4597810328137, 3614.3855544557487, 3618.8973547298183, 1080.6379862746069, 1093.7375029992904, 1108.5842808946645, 1167.8014737494645, 1174.0196935558733, 34692.47961280583, 34928.84864165609, 35184.64815351865, 35360.46959295613, 35845.64154358423, 1125.9662210133708, 1156.4333476596576, 1185.7095011722183, 1243.8072098824696, 1245.1532957588927, 18271.177796999167, 18491.075446227635, 18623.255069797055, 18691.971941543346, 18712.625747484974, 705959.0, 706849.0, 715079.0, 715389.0, 716041.0, 63914.0, 64388.35611643917, 64398.0, 64534.35465645284, 64645.67677161584, 1326.6683329167709, 1348.1237195822598, 1396.6089494941637, 1440.0927415728024, 1442.4086124689038, 31053.534196986613, 35812.28375432529, 36082.4587631191, 36230.09424764402, 36318.0, 54661.72669136629, 55006.72496637651, 55123.448765513436, 55236.44763552313, 55382.0, 467138.0, 467438.0, 468334.0, 469610.0, 470966.0, 30580.694193057785, 31272.687273126976, 31499.370012600077, 31586.368272633958, 33143.83428082844, 47777.761111195614, 48152.27771583499, 48170.0, 48907.26639100487, 49041.0, 40673.38989915213, 42470.362944555236, 42871.3569296467, 43040.354394683476, 43434.0, 41610.79194604007, 42341.0, 42518.78740606277, 43109.353359699, 43395.56604433915, 41945.0, 42705.78647106745, 42920.0, 43167.0, 43268.0, 34191.0, 36803.934230788764, 37050.44424333691, 37859.81070094632, 37906.0, 28333.858330708215, 28713.0, 28874.0, 28897.42205155927, 29040.0], "value": 20766.308512788197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.9435878976942, 408.3670311017921, 416.98123584438696, 423.3543845635406, 443.81138016343044, 15669.921650391747, 20126.49683757903, 20606.793932060675, 20758.169673213073, 21329.146834126655, 19540.31608893689, 19582.11880465379, 19609.705854412194, 19630.41108766736, 19734.91457969811, 5766.855828604276, 5818.418158184179, 5986.281646202462, 6024.427679370453, 6031.758729650809, 5891.793787217448, 5964.970175149118, 5967.731452084645, 5970.134330522072, 5970.373110823366, 5987.670678112702, 6237.095621134933, 6278.6232826030355, 6362.650054247015, 6369.777057802978, 5904.3505214426805, 6134.110553969649, 6304.905426418607, 6339.239291285052, 6339.492840572766, 6042.456178943872, 6070.150178974947, 6081.847953801146, 6082.361352058015, 6089.421504957022, 15435.614109647237, 16865.662686746196, 21413.036413361435, 25160.245192644248, 25458.745412546006, 2406.4344878953434, 2423.6185374336637, 2432.2946345559767, 2633.2231991562535, 3102.495831985459, 10604.575816967312, 10862.0, 11025.558977640885, 11268.0, 11680.766384672344, 1924.7112933060014, 1942.9319973800991, 1984.9503762405911, 1999.800019998003, 2021.7270668459716, 78508.0, 81459.59270203611, 84680.57659711663, 87816.560917195, 89024.0, 11393.601223957121, 11507.71230719222, 11696.766064678659, 11783.587574434854, 11870.821937670942, 17027.063511506985, 17038.48884533466, 17044.659106817795, 17048.57378565521, 17054.74417883733, 8125.756227313072, 8252.298554622925, 8257.81167990483, 8262.256396924306, 8506.405546308435, 7887.566183859828, 7909.841803163905, 8044.356451483867, 8075.717349892726, 8119.959400203079, 5020.3724534433195, 5054.393472783216, 5067.45442639991, 5121.795128194831, 5180.274761533388, 2113.3237364043357, 2125.691774692692, 2164.231697747277, 2168.495387827062, 2218.3566765638107, 987.4519641598981, 993.562832353762, 1036.9740756481149, 1038.2783965144304, 1065.5524679634573, 19541.41375758701, 20736.688949665768, 20811.68782468264, 20868.373948781562, 20869.895650521954, 1020.4284014280106, 1021.7139201023576, 1085.9619913303154, 1112.293693504614, 1131.6095946898306, 10696.679099627023, 11634.6509604712, 11695.473703683354, 11697.824532632018, 11708.297502149899, 505315.0, 514771.0, 517004.0, 519560.0, 521408.0, 66823.33176668268, 90018.09981900097, 90505.0, 92733.5363323179, 93001.0, 985.8915519292872, 1010.8736407949007, 1032.0505135275564, 1037.6731329631143, 1050.1808589300358, 17516.649667006845, 17708.645827083128, 18356.26574937041, 18383.724244136618, 18501.167447464628, 23926.88036559806, 41101.588984110975, 41587.7920610395, 41877.581224187365, 43434.56565434305, 57222.7138864303, 57889.0, 57937.71031144817, 58319.70840145772, 59361.703191485496, 8985.505797181218, 8998.775030624287, 9021.639134434548, 9076.863847042168, 9100.362974591715, 11994.280343179436, 13640.79538806899, 13740.175589464665, 13769.724605508034, 13778.311084445939, 10707.060690202363, 11662.12534059966, 11959.043276537683, 11978.64064078079, 12608.49566017349, 7545.6981720730555, 11513.597024104116, 12117.212381195193, 12414.193077449936, 12703.618891433272, 12617.306048167438, 12655.240685558896, 12711.872881271069, 12726.87273127257, 13097.214167150001, 9320.95339523298, 9445.716628501155, 9503.477308748084, 9507.714768556954, 9701.951490242503, 9765.462899540593, 12046.337451440255, 12243.326617035793, 12288.938555307166, 12983.35083245815], "value": 9584.153525170115, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.6674398144448, 1554.572492564545, 1568.7333153363927, 1706.1128213329068, 1865.5429419792154, 8571.742847714562, 8891.0, 8936.0, 9180.311476639245, 9182.86225706615, 8927.241184499326, 9014.414063085895, 9019.72940811775, 9021.233195178402, 9034.954825225865, 2445.229752627921, 2470.72821989581, 2471.1968610201757, 2472.3571871313497, 2476.05909754294, 2426.2721183644926, 2489.5892177790643, 2490.651308816767, 2494.102123235634, 2495.4011037350997, 2360.4688944987324, 2368.5144545368157, 2467.5311690778785, 2468.1854987854053, 2473.1096805150223, 2374.548835721216, 2380.8928598213124, 2388.4148383645943, 2391.0, 2413.5172965406814, 2333.334999525144, 2342.8125749939963, 2343.5430091132234, 2346.085026839534, 2352.8235382346393, 8904.243139333164, 9007.504587247699, 9067.365284430092, 9068.365214434993, 9078.319126065571, 5180.922286165711, 5373.0, 5391.218273350342, 5391.487808658171, 5392.487713667196, 3541.203229273419, 3543.344481270973, 3614.5843228028853, 3626.528551288342, 3658.140337020798, 1129.24904938216, 1163.2090178678495, 1166.5042356998326, 1167.3287859480747, 1190.8749581294012, 12640.0, 12684.23894566329, 12692.428840702098, 12735.554255601102, 12749.808752868621, 4608.700434471749, 4662.184117779375, 4828.468868424541, 4855.101806165871, 4901.534354236342, 9942.900570994341, 9953.353032052893, 9956.950215248877, 9960.850587241197, 9970.202383809277, 5467.289252397163, 5469.972650136803, 5486.533644640171, 5488.588355873326, 5488.670679759227, 4178.143480586473, 4500.167469018176, 4606.516315786861, 4918.90162196754, 5077.720725360066, 3979.801009949491, 3994.940075898864, 4087.6729861611, 4089.2434899543678, 4097.97951010243, 1245.0662003497378, 1267.847858257015, 1269.8222248885163, 1271.167385362589, 1280.5966120671958, 1022.1669339488442, 1042.6663467690266, 1073.2380010192694, 1077.4289626497969, 1080.5839751695653, 6283.246010478681, 6437.871242575122, 6482.157319548429, 6495.1231583736535, 6554.344565543408, 475.500724239554, 479.2882569384531, 482.4933819489522, 490.57320131485835, 491.26065271766475, 4339.8698039058245, 4507.909841803211, 4543.386642803245, 4608.5621865922685, 4609.792559334838, 10779.838302425469, 10834.566617335218, 10883.346999180074, 10891.782164356668, 10894.128469722404, 10692.09117225029, 10831.133509319236, 11125.944370278097, 11141.053010494037, 11396.94301528487, 8372.330213582898, 8546.401751877309, 8575.914240857637, 8586.65653373858, 8596.054434012254, 6367.9044814328745, 6459.095726598374, 6493.448056915215, 6593.868122637617, 6650.135482387259, 9914.30599858003, 10357.067863892285, 10358.274920755475, 10416.843747343644, 10505.054545090978, 9409.811803763749, 10574.682759517227, 10724.195685323475, 10814.0, 10851.294665846695, 7880.921190788019, 8059.43583949118, 8094.352451803724, 8305.211004954519, 8315.00219973607, 8656.350773692124, 8807.603657835542, 8877.24543413817, 8933.419327743675, 8965.686200982935, 7900.920990790249, 8031.0, 8034.83930321402, 8044.557549334842, 8232.835343292982, 7437.107547094383, 7707.383409327128, 7760.456768026184, 7836.177201394, 7867.488613240121, 7316.341529262392, 7388.556686598821, 7457.738979135921, 7458.440616953859, 7585.582792946441, 5526.861828454321, 5547.528460080938, 5708.600397972268, 5722.313322401338, 5902.6458412495385, 5512.4211957743855, 5611.396557664306, 5739.426057394394, 5804.6807425592, 5813.94186058134], "value": 4732.3414281231135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.0776277796341, 356.7698834251906, 357.57270062275575, 362.45269642839304, 367.60666087286614, 5482.0, 5510.173473978905, 5513.972430137844, 5517.503424691777, 5530.391656917746, 5425.9728701356535, 5430.56555475562, 5456.61803673743, 5466.535344495713, 5475.72621368932, 3930.528336599612, 4012.0972781124306, 4274.487061552618, 4300.0, 4329.307310830268, 4321.481422229337, 4325.199838029975, 4329.523752387235, 4339.544347843478, 4345.196138714332, 4293.65650747941, 4299.742015479082, 4300.935485967713, 4307.784610769465, 4312.331588603755, 4076.4496792932873, 4105.0, 4249.851255206053, 4277.8288868445225, 4310.784460776948, 3825.1775868188474, 3827.081500439874, 3827.7129215308964, 3829.463875057494, 3839.063116715715, 5225.581953443715, 5273.551748101416, 5291.497307755757, 5318.680879147263, 5323.574114070865, 4810.663253572251, 4892.9235568174945, 4916.222334381063, 4922.778474968635, 4923.10460472717, 5614.747336369833, 5630.465105814941, 5650.785081207519, 5652.004437648053, 5661.915071273934, 4152.937705934413, 4187.790610469464, 4206.831726730927, 4212.588782757749, 4215.0, 5624.803131890364, 5787.50806181475, 5820.534357251452, 5841.094630332305, 5893.734781934781, 4184.4141820145205, 4244.405783190356, 4250.149970006011, 4259.531451540328, 4289.506706728766, 4332.588404101605, 4341.934870976938, 4343.457067866517, 4350.347447882843, 4353.499347575006, 4636.4899861015265, 4887.780049897763, 4891.706497610155, 4911.336969509142, 4923.803047878045, 5760.36635970043, 5772.567057470623, 5776.826695199151, 5779.566532510079, 5793.536517078624, 1473.4253641079988, 1490.6422458609857, 1496.5585152380074, 1496.6108811709034, 1502.706972140444, 927.6753136402206, 934.9158575728217, 958.6980101268078, 963.4075043847984, 971.13568923658, 5469.808556700496, 5553.972230138824, 5562.360328562268, 5577.135543990688, 5587.804426845041, 5862.882742345129, 5882.499987501024, 5891.293044834647, 5898.970505147447, 5905.966455870292, 5399.757010934517, 5579.2746942897165, 5620.409856965041, 5623.353314368818, 5626.2685850839125, 3065.9540106898417, 3088.984555077255, 3090.2119959410206, 3098.7830851840604, 3109.5646609474693, 5825.427134673614, 6062.484688801501, 6067.544934129958, 6067.905368262218, 6069.302030266487, 5900.1739756434135, 5916.757480929053, 5945.613535120204, 5950.619752099108, 5968.224130863046, 5971.671558064349, 6058.1215723719815, 6067.33148384189, 6069.33237343883, 6110.297315808739, 5675.744591493475, 5762.2221000164445, 5784.94215057844, 5814.970925145516, 5901.822945311647, 5777.306723193244, 5779.739911704068, 5800.796972105956, 5818.563607729353, 5837.956715149664, 5794.5364370851075, 6067.757289708362, 6079.422454866781, 6086.360932102066, 6087.634741915499, 4310.676699247631, 4384.276594361894, 4390.253656878274, 4393.582609652078, 4402.141582391531, 5860.736266867916, 5866.94133058681, 5888.735006924784, 5924.170616113662, 5927.940720592739, 5554.611177217556, 5729.455701708331, 5742.483176513967, 5775.48020678141, 5782.421757824184, 5701.6864072474755, 5703.686297253691, 5707.714614269187, 5708.315002199763, 5732.34078081026, 5481.342238931354, 5687.118496633028, 5696.863676121455, 5718.313802343745, 5730.484256416942, 5327.706976116196, 5736.598438109292, 5767.884642307046, 5774.2782152230975, 5803.7968671096305, 5609.382967873612, 5611.915821262603, 5615.943840561542, 5618.0, 5621.9156712650165], "value": 4387.8324767580025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.1556365815892, 857.2413414128495, 886.2865393358347, 891.1088911088913, 905.5312035106753, 16996.490105296845, 18150.63698726028, 18161.0, 18172.18225179867, 18182.999935003587, 15635.983661062022, 15676.529704108867, 15799.447019354326, 16151.757723634126, 16463.588410289747, 728.1589763822793, 743.1602289412956, 744.7356188553067, 750.2759836757527, 858.7552547523957, 871.6600525794945, 874.711345256064, 879.5865943006809, 880.5245167609484, 881.6781874615783, 944.2681921510849, 945.6595625574788, 946.4747065378712, 946.5551190940247, 946.5551190940247, 944.8724422203052, 945.6359301668873, 945.6690158444524, 945.6784693204274, 946.74911148545, 560.7448610882079, 656.7799787071336, 674.3189378727471, 679.2663922963188, 691.0705101638289, 16075.356985720675, 16123.274452649659, 16148.354065837353, 16166.191690415431, 16171.676566468605, 14776.778348324784, 15693.529194124081, 15808.367665293374, 15863.682726345409, 16314.265858036417, 14353.210573418457, 14364.936855785132, 14520.564383068524, 14630.268486575735, 14707.0, 4515.61617262533, 4528.25283828168, 4551.203539380628, 4600.309953506967, 4623.7225766453785, 84724.57637711834, 87209.56395218047, 88344.11655883488, 88409.55795220983, 89688.5515572418, 11518.712032199095, 11560.479778409992, 11612.012978896719, 12472.688182795502, 12721.173123746927, 21986.560268794536, 22049.559008819735, 22055.11779528801, 22060.779392206194, 22092.0, 8983.820323593622, 9053.456792592466, 9091.863622045543, 9142.542872856331, 9449.055094490634, 4923.138450771101, 5051.368578927634, 5063.367079115111, 5088.414832294259, 5237.188235823453, 3704.1665625234104, 3819.6371344722206, 3856.0359910022494, 3859.9807000964815, 3863.130795570974, 647.4626060369882, 653.7679123911038, 659.8416380068845, 664.6709878610022, 672.5225090185925, 714.8856183010694, 725.5537844225763, 741.2143128284036, 747.0960138232799, 781.6365390093633, 9989.400635961865, 10251.38491690501, 10391.272610917315, 10420.531076101593, 10443.84334234987, 618.5391883047068, 629.9716512756937, 667.6995352091578, 699.6152116335995, 708.3553965891117, 4628.166929952574, 4871.48849370811, 4895.681780684244, 4916.360873086476, 4956.207006878882, 77064.61467692626, 83346.0, 133942.0, 134250.0, 135238.0, 108564.4571777136, 111377.0, 112586.43706781414, 112605.0, 116312.0, 100990.4950475243, 101327.0, 101503.0, 102596.0, 102766.48616756868, 8678.479291242546, 9109.407888487227, 9186.586603602987, 9359.204467620328, 9368.81262374735, 54571.454285458225, 55183.72408137934, 55615.16577251425, 57137.42862571321, 58285.417145827996, 81536.0, 88191.55904220438, 88211.55894220488, 88855.0, 90977.0, 12092.697682558006, 12354.196977196454, 12525.248485090924, 12615.116941814353, 12688.365581721062, 13800.792988104986, 13914.09558378702, 13915.512957046454, 13940.721185576029, 14008.579742607739, 12741.55404560836, 12980.091393602357, 13068.411921463367, 13194.472221111431, 13368.933155334551, 12149.635510934333, 12392.442340094538, 12842.165259258118, 12983.0, 13108.868911311147, 12498.0, 12579.119461637598, 12673.112882098165, 13200.14199077057, 13291.0, 10061.0, 10604.469776511298, 10825.837612435977, 10878.910321959469, 11058.889411105785, 10330.535125919501, 11042.282251653616, 11244.66266012021, 11301.26541774782, 11348.319100853974], "value": 7965.962397123493, "unit": "score"}], "name": "dromaeo_css", "value": 7511.28970721119, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 120.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 116.0, 136.0, 168.0, 116.0, 144.0, 139.0, 117.0, 172.0, 114.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 160.0, 159.0, 161.0, 161.0, 162.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 75.0, 181.0, 74.0, 75.0, 75.0, 74.0, 176.0, 74.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 153.0, 83.0, 83.0, 83.0, 152.0, 84.0, 83.0, 149.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 119.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 147.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 53.0, 51.0, 50.0, 50.0, 51.0, 52.0, 50.0, 50.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 63.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 76.0, 73.0, 77.0, 72.0, 73.0, 72.0, 72.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 128.0, 115.0, 129.0, 127.0, 114.0, 119.0, 115.0, 151.0, 118.0], "value": 123.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 151.0, 158.0, 159.0, 153.0, 152.0, 155.0, 151.0, 154.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 63.0, 62.0, 64.0, 64.0, 60.0, 65.0, 65.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1491.6000000000001, "alertThreshold": 2.0}]} 18:32:13 INFO - Return code: 0 18:32:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:13 INFO - # TBPL SUCCESS # 18:32:13 INFO - Running post-action listener: _resource_record_post_action 18:32:13 INFO - [mozharness: 2016-09-14 01:32:13.313735Z] Finished run-tests step (success) 18:32:13 INFO - Running post-run listener: _resource_record_post_run 18:32:13 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 330018816; Read time: 20; Write time: 262860 18:32:13 INFO - TinderboxPrint: CPU usage
12.9% 18:32:13 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 20 18:32:13 INFO - TinderboxPrint: I/O write bytes / time
330,018,816 / 262,860 18:32:13 INFO - TinderboxPrint: CPU idle
8,271.4 (87.1%) 18:32:13 INFO - TinderboxPrint: CPU user
1,181.2 (12.4%) 18:32:13 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79654912; Read time: 0; Write time: 82396 18:32:13 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249933824; Read time: 12; Write time: 180360 18:32:13 INFO - Running post-run listener: _upload_blobber_files 18:32:13 INFO - Blob upload gear active. 18:32:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:13 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 18:32:13 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'] 18:32:13 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 18:32:14 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:32:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:22 INFO - (blobuploader) - INFO - Done attempting. 18:32:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:24 INFO - (blobuploader) - INFO - Done attempting. 18:32:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:32:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:24 INFO - (blobuploader) - INFO - Done attempting. 18:32:24 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:24 INFO - Return code: 0 18:32:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d374d55460dfaca5919a98768433b6904be7d48ccb0ef2dc6293e3eae75aa662f62aef731b39150c54b61fbd6c6e7a82acc80a39f9d4277ab6e44ef0662942", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56749dea9723b251e22689a9d8c6eaf1fb8340debd9c7b8a778b577cbd92048a8c7ee4be6aa1848bd4deed4a33dc1467724e956f7dd5a7233f569808493f7e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a505fab66d261a33ff9a3bc3e0398450359631846b6bc1218696b4d36f00faebcfabf73141d01e3c4d9aa2ef4a7ab3e219c9bda29e30f72d815a4450bb38c8e"} 18:32:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:24 INFO - Contents: 18:32:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d374d55460dfaca5919a98768433b6904be7d48ccb0ef2dc6293e3eae75aa662f62aef731b39150c54b61fbd6c6e7a82acc80a39f9d4277ab6e44ef0662942", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56749dea9723b251e22689a9d8c6eaf1fb8340debd9c7b8a778b577cbd92048a8c7ee4be6aa1848bd4deed4a33dc1467724e956f7dd5a7233f569808493f7e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a505fab66d261a33ff9a3bc3e0398450359631846b6bc1218696b4d36f00faebcfabf73141d01e3c4d9aa2ef4a7ab3e219c9bda29e30f72d815a4450bb38c8e"} 18:32:24 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:24 INFO - Copying logs to upload dir... 18:32:24 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.639452 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-13 18:32:24.075496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 18:32:24.079246) ========= 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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 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/64d374d55460dfaca5919a98768433b6904be7d48ccb0ef2dc6293e3eae75aa662f62aef731b39150c54b61fbd6c6e7a82acc80a39f9d4277ab6e44ef0662942", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56749dea9723b251e22689a9d8c6eaf1fb8340debd9c7b8a778b577cbd92048a8c7ee4be6aa1848bd4deed4a33dc1467724e956f7dd5a7233f569808493f7e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a505fab66d261a33ff9a3bc3e0398450359631846b6bc1218696b4d36f00faebcfabf73141d01e3c4d9aa2ef4a7ab3e219c9bda29e30f72d815a4450bb38c8e"} build_url:https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/LeKrfQVqSwm5eTHUn_gVug/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/64d374d55460dfaca5919a98768433b6904be7d48ccb0ef2dc6293e3eae75aa662f62aef731b39150c54b61fbd6c6e7a82acc80a39f9d4277ab6e44ef0662942", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56749dea9723b251e22689a9d8c6eaf1fb8340debd9c7b8a778b577cbd92048a8c7ee4be6aa1848bd4deed4a33dc1467724e956f7dd5a7233f569808493f7e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a505fab66d261a33ff9a3bc3e0398450359631846b6bc1218696b4d36f00faebcfabf73141d01e3c4d9aa2ef4a7ab3e219c9bda29e30f72d815a4450bb38c8e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 18:32:24.110595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:32:24.111002) ========= 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=1f5564757af27664ca02d71f000001b0-1473813686.698057-1990690525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004378 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:32:24.198209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 18:32:24.199021) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 18:32:24.199793) ========= ========= Total master_lag: 0.19 =========