builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-008 starttime: 1473692794.01 results: success (0) buildid: 20160908025615 builduid: a419c96531e3431bbaf02484b3673641 revision: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.006136) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.006516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.006807) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.082868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.083142) ========= 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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 08:06:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.5M=0s 2016-09-12 08:06:35 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.204609) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.204921) ========= 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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019619 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:34.254493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 08:06:34.254805) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d --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 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d --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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 08:06:35,488 truncating revision to first 12 chars 2016-09-12 08:06:35,488 Setting DEBUG logging. 2016-09-12 08:06:35,488 attempt 1/10 2016-09-12 08:06:35,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46c6e8bb7f6f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 08:06:36,591 unpacking tar archive at: mozilla-aurora-46c6e8bb7f6f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.445776 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 08:06:35.713828) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:35.714125) ========= script_repo_revision: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:35.714541) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:35.714827) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 08:06:35.723219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 08:06:35.723493) ========= /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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:06:37 INFO - MultiFileLogger online at 20160912 08:06:37 in /builds/slave/test 08:06:37 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 08:06:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:06:37 INFO - {'append_to_log': False, 08:06:37 INFO - 'base_work_dir': '/builds/slave/test', 08:06:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:06:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:06:37 INFO - 'branch': 'Mozilla-Aurora', 08:06:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:06:37 INFO - 'config_files': ('talos/linux_config.py',), 08:06:37 INFO - 'default_actions': ('clobber', 08:06:37 INFO - 'read-buildbot-config', 08:06:37 INFO - 'download-and-extract', 08:06:37 INFO - 'populate-webroot', 08:06:37 INFO - 'create-virtualenv', 08:06:37 INFO - 'install', 08:06:37 INFO - 'run-tests'), 08:06:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:06:37 INFO - 'download_minidump_stackwalk': True, 08:06:37 INFO - 'download_symbols': 'ondemand', 08:06:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:06:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:06:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:06:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:06:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:06:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:06:37 INFO - 'installer_path': 'installer.exe', 08:06:37 INFO - 'log_level': 'info', 08:06:37 INFO - 'log_name': 'talos', 08:06:37 INFO - 'log_to_console': True, 08:06:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:06:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:06:37 INFO - 'opt_config_files': (), 08:06:37 INFO - 'pip_index': False, 08:06:37 INFO - 'sps_profile': False, 08:06:37 INFO - 'sps_profile_interval': 0, 08:06:37 INFO - 'suite': 'dromaeojs', 08:06:37 INFO - 'system_bits': '32', 08:06:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:06:37 INFO - 'title': 'talos-linux64-ix-008', 08:06:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:06:37 INFO - 'use_talos_json': True, 08:06:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:06:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:06:37 INFO - 'work_dir': 'build'} 08:06:37 INFO - [mozharness: 2016-09-12 15:06:37.044110Z] Running clobber step. 08:06:37 INFO - Running pre-action listener: _resource_record_pre_action 08:06:37 INFO - Running main action method: clobber 08:06:37 INFO - rmtree: /builds/slave/test/build 08:06:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:06:37 INFO - Running post-action listener: _resource_record_post_action 08:06:37 INFO - [mozharness: 2016-09-12 15:06:37.501596Z] Finished clobber step (success) 08:06:37 INFO - [mozharness: 2016-09-12 15:06:37.501695Z] Running read-buildbot-config step. 08:06:37 INFO - Running pre-action listener: _resource_record_pre_action 08:06:37 INFO - Running main action method: read_buildbot_config 08:06:37 INFO - Using buildbot properties: 08:06:37 INFO - { 08:06:37 INFO - "project": "", 08:06:37 INFO - "product": "firefox", 08:06:37 INFO - "script_repo_revision": "production", 08:06:37 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:06:37 INFO - "repository": "", 08:06:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:06:37 INFO - "buildid": "20160908025615", 08:06:37 INFO - "pgo_build": "True", 08:06:37 INFO - "basedir": "/builds/slave/test", 08:06:37 INFO - "buildnumber": 465, 08:06:37 INFO - "slavename": "talos-linux64-ix-008", 08:06:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:06:37 INFO - "platform": "ubuntu64_hw", 08:06:37 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:06:37 INFO - "branch": "mozilla-aurora", 08:06:37 INFO - "repo_path": "releases/mozilla-aurora", 08:06:37 INFO - "revision": "46c6e8bb7f6f6d7281baa761cc931ccd46feca5d", 08:06:37 INFO - "stage_platform": "linux64-pgo", 08:06:37 INFO - "builduid": "a419c96531e3431bbaf02484b3673641", 08:06:37 INFO - "slavebuilddir": "test" 08:06:37 INFO - } 08:06:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 08:06:37 INFO - Running post-action listener: _resource_record_post_action 08:06:37 INFO - [mozharness: 2016-09-12 15:06:37.504160Z] Finished read-buildbot-config step (success) 08:06:37 INFO - [mozharness: 2016-09-12 15:06:37.504247Z] Running download-and-extract step. 08:06:37 INFO - Running pre-action listener: _resource_record_pre_action 08:06:37 INFO - Running main action method: download_and_extract 08:06:37 INFO - mkdir: /builds/slave/test/build/tests 08:06:37 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')]} 08:06:37 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:06:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:06:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:06:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:06:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:06:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:06:37 INFO - retry: Failed, sleeping 30 seconds before retrying 08:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:07:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:07:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:07:07 INFO - retry: Failed, sleeping 60 seconds before retrying 08:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:08:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:08:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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! 08:08:07 INFO - Caught exception: HTTP Error 404: Not Found 08:08:07 INFO - Caught exception: HTTP Error 404: Not Found 08:08:07 INFO - Caught exception: HTTP Error 404: Not Found 08:08:07 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:08:07 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:08 INFO - Downloaded 1361 bytes. 08:08:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:08:08 INFO - Using the following test package requirements: 08:08:08 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:08:08 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:08:08 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:08:08 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'jsshell-linux-x86_64.zip'], 08:08:08 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:08:08 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:08:08 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:08:08 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:08:08 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:08:08 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:08:08 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:08:08 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:08: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')]} 08:08:08 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:08:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:08:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:08:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:08:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:08:08 INFO - retry: Failed, sleeping 30 seconds before retrying 08:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:08:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:08:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:08:38 INFO - retry: Failed, sleeping 60 seconds before retrying 08:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:09:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:09:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:09:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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! 08:09:38 INFO - Caught exception: HTTP Error 404: Not Found 08:09:38 INFO - Caught exception: HTTP Error 404: Not Found 08:09:38 INFO - Caught exception: HTTP Error 404: Not Found 08:09:38 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:09:38 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:09:39 INFO - Downloaded 21769303 bytes. 08:09:39 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'] 08:09:39 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 08:09:40 INFO - Return code: 0 08:09:40 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 08:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:40 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:09:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:09:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:09:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:09:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:09:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:09:40 INFO - retry: Failed, sleeping 30 seconds before retrying 08:10:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:10:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:10:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:10:10 INFO - retry: Failed, sleeping 60 seconds before retrying 08:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:11:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:11:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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! 08:11:10 INFO - Caught exception: HTTP Error 404: Not Found 08:11:10 INFO - Caught exception: HTTP Error 404: Not Found 08:11:10 INFO - Caught exception: HTTP Error 404: Not Found 08:11:10 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:11:10 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:11 INFO - Downloaded 21769303 bytes. 08:11:11 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'] 08:11:11 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 08:11:12 INFO - Return code: 0 08:11:12 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')]} 08:11:12 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:11:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:11:12 INFO - retry: Failed, sleeping 30 seconds before retrying 08:11:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:11:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:11:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:11:42 INFO - retry: Failed, sleeping 60 seconds before retrying 08:12:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:12:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:12:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:12:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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! 08:12:42 INFO - Caught exception: HTTP Error 404: Not Found 08:12:42 INFO - Caught exception: HTTP Error 404: Not Found 08:12:42 INFO - Caught exception: HTTP Error 404: Not Found 08:12:42 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:12:42 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:12:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:12:43 INFO - Downloaded 14413740 bytes. 08:12:43 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'] 08:12:43 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 08:12:43 INFO - Return code: 0 08:12:43 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')]} 08:12:43 INFO - https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:12:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:12:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:12:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:12:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:12:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:12:43 INFO - retry: Failed, sleeping 30 seconds before retrying 08:13:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:13:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:13:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:13:13 INFO - retry: Failed, sleeping 60 seconds before retrying 08:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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 08:14:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:14:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:14:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KGqY64usSHCitSXpwcabXA/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! 08:14:13 INFO - Caught exception: HTTP Error 404: Not Found 08:14:13 INFO - Caught exception: HTTP Error 404: Not Found 08:14:13 INFO - Caught exception: HTTP Error 404: Not Found 08:14:13 INFO - trying https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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 08:14:15 INFO - Downloaded 63903198 bytes. 08:14:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:14:15 INFO - mkdir: /builds/slave/test/properties 08:14:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:14:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:14:15 INFO - Contents: 08:14:15 INFO - build_url:https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:14:15 INFO - Running post-action listener: _resource_record_post_action 08:14:15 INFO - Running post-action listener: set_extra_try_arguments 08:14:15 INFO - [mozharness: 2016-09-12 15:14:15.803028Z] Finished download-and-extract step (success) 08:14:15 INFO - [mozharness: 2016-09-12 15:14:15.803296Z] Running populate-webroot step. 08:14:15 INFO - Running pre-action listener: _resource_record_pre_action 08:14:15 INFO - Running main action method: populate_webroot 08:14:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:14:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:14:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:14:15 INFO - '--tppagecycles', 08:14:15 INFO - '7'], 08:14:15 INFO - 'tests': ['tsvgm']}}, 08:14:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:14:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:14:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:14:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:14:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:14:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:14:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['damp', 'tps']}, 08:14:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'tests': ['damp', 'tps']}, 08:14:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['dromaeo_dom']}, 08:14:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:14:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['basic_compositor_video']}, 08:14:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:14:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['a11yr', 08:14:15 INFO - 'ts_paint', 08:14:15 INFO - 'tpaint', 08:14:15 INFO - 'sessionrestore', 08:14:15 INFO - 'sessionrestore_no_auto_restore', 08:14:15 INFO - 'tabpaint']}, 08:14:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['tsvgx', 08:14:15 INFO - 'tsvgr_opacity', 08:14:15 INFO - 'tart', 08:14:15 INFO - 'tscrollx', 08:14:15 INFO - 'cart']}, 08:14:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:14:15 INFO - 'tsvgr_opacity', 08:14:15 INFO - 'tart', 08:14:15 INFO - 'tscrollx', 08:14:15 INFO - 'cart']}, 08:14:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'talos_options': ['--disable-e10s'], 08:14:15 INFO - 'tests': ['tp5o']}, 08:14:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'tests': ['tp5o']}, 08:14:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'talos_options': ['--disable-e10s', 08:14:15 INFO - '--xperf_path', 08:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:14:15 INFO - 'tests': ['tp5n']}, 08:14:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:14:15 INFO - 'talos_options': ['--xperf_path', 08:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:14:15 INFO - 'tests': ['tp5n']}}, 08:14:15 INFO - 'talos.zip': {'path': '', 08:14:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:14:15 INFO - Running post-action listener: _resource_record_post_action 08:14:15 INFO - [mozharness: 2016-09-12 15:14:15.824129Z] Finished populate-webroot step (success) 08:14:15 INFO - [mozharness: 2016-09-12 15:14:15.824217Z] Running create-virtualenv step. 08:14:15 INFO - Running pre-action listener: _resource_record_pre_action 08:14:15 INFO - Running main action method: create_virtualenv 08:14:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:14:15 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 08:14:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:14:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:14:15 INFO - Using real prefix '/usr' 08:14:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:14:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:14:18 INFO - Installing pip.................done. 08:14:18 INFO - Return code: 0 08:14:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:14: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')]} 08:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14: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')]} 08:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:18 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 08:14:18 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 08:14:18 INFO - Using env: {'DISPLAY': ':0', 08:14:18 INFO - 'HOME': '/home/cltbld', 08:14:18 INFO - 'LANG': 'en_US.UTF-8', 08:14:18 INFO - 'LANGUAGE': 'en_US:en', 08:14:18 INFO - 'LOGNAME': 'cltbld', 08:14:18 INFO - 'MAIL': '/var/mail/cltbld', 08:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:18 INFO - 'MOZ_NO_REMOTE': '1', 08:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:18 INFO - 'NO_EM_RESTART': '1', 08:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:18 INFO - 'PWD': '/builds/slave/test', 08:14:18 INFO - 'SHELL': '/bin/bash', 08:14:18 INFO - 'SHLVL': '1', 08:14:18 INFO - 'TERM': 'linux', 08:14:18 INFO - 'TMOUT': '86400', 08:14:18 INFO - 'USER': 'cltbld', 08:14:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:18 INFO - '_': '/tools/buildbot/bin/python'} 08:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:18 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 08:14:18 INFO - Cleaning up... 08:14:18 INFO - Return code: 0 08:14:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:14: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')]} 08:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14: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')]} 08:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:18 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:18 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 08:14:18 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 08:14:18 INFO - Using env: {'DISPLAY': ':0', 08:14:18 INFO - 'HOME': '/home/cltbld', 08:14:18 INFO - 'LANG': 'en_US.UTF-8', 08:14:18 INFO - 'LANGUAGE': 'en_US:en', 08:14:18 INFO - 'LOGNAME': 'cltbld', 08:14:18 INFO - 'MAIL': '/var/mail/cltbld', 08:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:18 INFO - 'MOZ_NO_REMOTE': '1', 08:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:18 INFO - 'NO_EM_RESTART': '1', 08:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:18 INFO - 'PWD': '/builds/slave/test', 08:14:18 INFO - 'SHELL': '/bin/bash', 08:14:18 INFO - 'SHLVL': '1', 08:14:18 INFO - 'TERM': 'linux', 08:14:18 INFO - 'TMOUT': '86400', 08:14:18 INFO - 'USER': 'cltbld', 08:14:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:18 INFO - '_': '/tools/buildbot/bin/python'} 08:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:18 INFO - Downloading/unpacking psutil>=3.1.1 08:14:18 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 08:14:18 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 08:14:18 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 08:14:18 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 08:14:18 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 08:14:18 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 08:14:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:14:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:14:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:14:20 INFO - Installing collected packages: psutil 08:14:20 INFO - Running setup.py install for psutil 08:14:20 INFO - building 'psutil._psutil_linux' extension 08:14:20 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 08:14:21 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 08:14:21 INFO - building 'psutil._psutil_posix' extension 08:14:21 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 08:14:21 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 08:14:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:14:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:14:21 INFO - Successfully installed psutil 08:14:21 INFO - Cleaning up... 08:14:21 INFO - Return code: 0 08:14:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:14:21 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')]} 08:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:21 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')]} 08:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:21 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:21 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 08:14:21 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 08:14:21 INFO - Using env: {'DISPLAY': ':0', 08:14:21 INFO - 'HOME': '/home/cltbld', 08:14:21 INFO - 'LANG': 'en_US.UTF-8', 08:14:21 INFO - 'LANGUAGE': 'en_US:en', 08:14:21 INFO - 'LOGNAME': 'cltbld', 08:14:21 INFO - 'MAIL': '/var/mail/cltbld', 08:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:21 INFO - 'MOZ_NO_REMOTE': '1', 08:14:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:21 INFO - 'NO_EM_RESTART': '1', 08:14:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:21 INFO - 'PWD': '/builds/slave/test', 08:14:21 INFO - 'SHELL': '/bin/bash', 08:14:21 INFO - 'SHLVL': '1', 08:14:21 INFO - 'TERM': 'linux', 08:14:21 INFO - 'TMOUT': '86400', 08:14:21 INFO - 'USER': 'cltbld', 08:14:21 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:21 INFO - '_': '/tools/buildbot/bin/python'} 08:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:21 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 08:14:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:14:23 INFO - Installing collected packages: mozsystemmonitor 08:14:23 INFO - Running setup.py install for mozsystemmonitor 08:14:23 INFO - Successfully installed mozsystemmonitor 08:14:23 INFO - Cleaning up... 08:14:23 INFO - Return code: 0 08:14:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:14:23 INFO - Using env: {'DISPLAY': ':0', 08:14:23 INFO - 'HOME': '/home/cltbld', 08:14:23 INFO - 'LANG': 'en_US.UTF-8', 08:14:23 INFO - 'LANGUAGE': 'en_US:en', 08:14:23 INFO - 'LOGNAME': 'cltbld', 08:14:23 INFO - 'MAIL': '/var/mail/cltbld', 08:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:23 INFO - 'MOZ_NO_REMOTE': '1', 08:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:23 INFO - 'NO_EM_RESTART': '1', 08:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:23 INFO - 'PWD': '/builds/slave/test', 08:14:23 INFO - 'SHELL': '/bin/bash', 08:14:23 INFO - 'SHLVL': '1', 08:14:23 INFO - 'TERM': 'linux', 08:14:23 INFO - 'TMOUT': '86400', 08:14:23 INFO - 'USER': 'cltbld', 08:14:23 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:23 INFO - '_': '/tools/buildbot/bin/python'} 08:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:23 INFO - Downloading/unpacking jsonschema==2.5.1 08:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:14:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:14:26 INFO - warning: no files found matching '*.txt' 08:14:26 INFO - no previously-included directories found matching 'build' 08:14:26 INFO - no previously-included directories found matching 'dist' 08:14:26 INFO - no previously-included directories found matching '.git*' 08:14:26 INFO - Installing collected packages: jsonschema, functools32 08:14:26 INFO - Running setup.py install for functools32 08:14:26 INFO - warning: no files found matching '*.txt' 08:14:26 INFO - no previously-included directories found matching 'build' 08:14:26 INFO - no previously-included directories found matching 'dist' 08:14:26 INFO - no previously-included directories found matching '.git*' 08:14:26 INFO - Successfully installed jsonschema functools32 08:14:26 INFO - Cleaning up... 08:14:26 INFO - Return code: 0 08:14:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:14:26 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')]} 08:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:26 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')]} 08:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:26 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:26 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 08:14:26 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 08:14:26 INFO - Using env: {'DISPLAY': ':0', 08:14:26 INFO - 'HOME': '/home/cltbld', 08:14:26 INFO - 'LANG': 'en_US.UTF-8', 08:14:26 INFO - 'LANGUAGE': 'en_US:en', 08:14:26 INFO - 'LOGNAME': 'cltbld', 08:14:26 INFO - 'MAIL': '/var/mail/cltbld', 08:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:26 INFO - 'MOZ_NO_REMOTE': '1', 08:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:26 INFO - 'NO_EM_RESTART': '1', 08:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:26 INFO - 'PWD': '/builds/slave/test', 08:14:26 INFO - 'SHELL': '/bin/bash', 08:14:26 INFO - 'SHLVL': '1', 08:14:26 INFO - 'TERM': 'linux', 08:14:26 INFO - 'TMOUT': '86400', 08:14:26 INFO - 'USER': 'cltbld', 08:14:26 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:26 INFO - '_': '/tools/buildbot/bin/python'} 08:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:14:26 INFO - Cleaning up... 08:14:26 INFO - Return code: 0 08:14:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:14:26 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')]} 08:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:26 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')]} 08:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:26 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:26 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 08:14:26 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 08:14:26 INFO - Using env: {'DISPLAY': ':0', 08:14:26 INFO - 'HOME': '/home/cltbld', 08:14:26 INFO - 'LANG': 'en_US.UTF-8', 08:14:26 INFO - 'LANGUAGE': 'en_US:en', 08:14:26 INFO - 'LOGNAME': 'cltbld', 08:14:26 INFO - 'MAIL': '/var/mail/cltbld', 08:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:26 INFO - 'MOZ_NO_REMOTE': '1', 08:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:26 INFO - 'NO_EM_RESTART': '1', 08:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:26 INFO - 'PWD': '/builds/slave/test', 08:14:26 INFO - 'SHELL': '/bin/bash', 08:14:26 INFO - 'SHLVL': '1', 08:14:26 INFO - 'TERM': 'linux', 08:14:26 INFO - 'TMOUT': '86400', 08:14:26 INFO - 'USER': 'cltbld', 08:14:26 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:26 INFO - '_': '/tools/buildbot/bin/python'} 08:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:26 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 08:14:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:14:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:14:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:14:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:14:29 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 08:14:29 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 08:14:29 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 08:14:29 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 08:14:29 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 08:14:29 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 08:14:29 INFO - Downloading docopt-0.6.1.tar.gz 08:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:14:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:14:29 INFO - Running setup.py install for blobuploader 08:14:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:14:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:14:29 INFO - Running setup.py install for requests 08:14:29 INFO - Running setup.py install for docopt 08:14:29 INFO - Successfully installed blobuploader requests docopt 08:14:29 INFO - Cleaning up... 08:14:30 INFO - Return code: 0 08:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:30 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')]} 08:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:30 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')]} 08:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:30 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:30 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 08:14:30 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 08:14:30 INFO - Using env: {'DISPLAY': ':0', 08:14:30 INFO - 'HOME': '/home/cltbld', 08:14:30 INFO - 'LANG': 'en_US.UTF-8', 08:14:30 INFO - 'LANGUAGE': 'en_US:en', 08:14:30 INFO - 'LOGNAME': 'cltbld', 08:14:30 INFO - 'MAIL': '/var/mail/cltbld', 08:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:30 INFO - 'MOZ_NO_REMOTE': '1', 08:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:30 INFO - 'NO_EM_RESTART': '1', 08:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:30 INFO - 'PWD': '/builds/slave/test', 08:14:30 INFO - 'SHELL': '/bin/bash', 08:14:30 INFO - 'SHLVL': '1', 08:14:30 INFO - 'TERM': 'linux', 08:14:30 INFO - 'TMOUT': '86400', 08:14:30 INFO - 'USER': 'cltbld', 08:14:30 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:30 INFO - '_': '/tools/buildbot/bin/python'} 08:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:30 INFO - Running setup.py (path:/tmp/pip-sv3B3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:30 INFO - Running setup.py (path:/tmp/pip-4qUlHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:30 INFO - Running setup.py (path:/tmp/pip-xEzJLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:30 INFO - Running setup.py (path:/tmp/pip-md4g4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:30 INFO - Running setup.py (path:/tmp/pip-OUmmQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:30 INFO - Running setup.py (path:/tmp/pip-GsMDv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:30 INFO - Running setup.py (path:/tmp/pip-Sz98QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:30 INFO - Running setup.py (path:/tmp/pip-tofAJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:30 INFO - Running setup.py (path:/tmp/pip-pzFnjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:30 INFO - Running setup.py (path:/tmp/pip-yzxTfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:30 INFO - Running setup.py (path:/tmp/pip-SL18HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:31 INFO - Running setup.py (path:/tmp/pip-JX5umi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:31 INFO - Running setup.py (path:/tmp/pip-o3Evfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:31 INFO - Running setup.py (path:/tmp/pip-NyGnmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:31 INFO - Running setup.py (path:/tmp/pip-B5yzrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:31 INFO - Running setup.py (path:/tmp/pip-iPKmfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:31 INFO - Running setup.py (path:/tmp/pip-fnduol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:14:31 INFO - Running setup.py install for manifestparser 08:14:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:14:31 INFO - Running setup.py install for mozcrash 08:14:31 INFO - Running setup.py install for mozdebug 08:14:31 INFO - Running setup.py install for mozdevice 08:14:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:14:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:14:31 INFO - Running setup.py install for mozfile 08:14:32 INFO - Running setup.py install for mozhttpd 08:14:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozinfo 08:14:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozInstall 08:14:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozleak 08:14:32 INFO - Running setup.py install for mozlog 08:14:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for moznetwork 08:14:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozprocess 08:14:32 INFO - Running setup.py install for mozprofile 08:14:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozrunner 08:14:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:14:32 INFO - Running setup.py install for mozscreenshot 08:14:33 INFO - Running setup.py install for moztest 08:14:33 INFO - Running setup.py install for mozversion 08:14:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:14:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:14:33 INFO - Cleaning up... 08:14:33 INFO - Return code: 0 08:14:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:33 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')]} 08:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:33 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')]} 08:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:33 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:33 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 08:14:33 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 08:14:33 INFO - Using env: {'DISPLAY': ':0', 08:14:33 INFO - 'HOME': '/home/cltbld', 08:14:33 INFO - 'LANG': 'en_US.UTF-8', 08:14:33 INFO - 'LANGUAGE': 'en_US:en', 08:14:33 INFO - 'LOGNAME': 'cltbld', 08:14:33 INFO - 'MAIL': '/var/mail/cltbld', 08:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:33 INFO - 'MOZ_NO_REMOTE': '1', 08:14:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:33 INFO - 'NO_EM_RESTART': '1', 08:14:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:33 INFO - 'PWD': '/builds/slave/test', 08:14:33 INFO - 'SHELL': '/bin/bash', 08:14:33 INFO - 'SHLVL': '1', 08:14:33 INFO - 'TERM': 'linux', 08:14:33 INFO - 'TMOUT': '86400', 08:14:33 INFO - 'USER': 'cltbld', 08:14:33 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:33 INFO - '_': '/tools/buildbot/bin/python'} 08:14:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:14:33 INFO - Running setup.py (path:/tmp/pip-ParjTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:14:33 INFO - Running setup.py (path:/tmp/pip-1kGVhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:14:33 INFO - Running setup.py (path:/tmp/pip-ZQxURP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:14:33 INFO - Running setup.py (path:/tmp/pip-SOGVh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:14:33 INFO - Running setup.py (path:/tmp/pip-2xkIix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:14:33 INFO - Running setup.py (path:/tmp/pip-COqrWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:14:33 INFO - Running setup.py (path:/tmp/pip-JKHmnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:14:33 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)) 08:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:14:33 INFO - Running setup.py (path:/tmp/pip-mZSX4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:14:34 INFO - Running setup.py (path:/tmp/pip-cqO5bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:14:34 INFO - Running setup.py (path:/tmp/pip-bchzGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:14:34 INFO - Running setup.py (path:/tmp/pip-aSac3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:14:34 INFO - Running setup.py (path:/tmp/pip-ABhg04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:14:34 INFO - Running setup.py (path:/tmp/pip-RkJJnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:14:34 INFO - Running setup.py (path:/tmp/pip-h8LSaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:34 INFO - Running setup.py (path:/tmp/pip-YS4gbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:14:34 INFO - Running setup.py (path:/tmp/pip-fnOR0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:14:34 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)) 08:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:14:34 INFO - Running setup.py (path:/tmp/pip-iiYTVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:14:34 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)) 08:14:34 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)) 08:14:34 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)) 08:14:34 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)) 08:14:34 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)) 08:14:34 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)) 08:14:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:14:34 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 08:14:34 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 08:14:34 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 08:14:34 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 08:14:34 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 08:14:34 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 08:14:36 INFO - Downloading blessings-1.6.tar.gz 08:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:14:36 INFO - Installing collected packages: blessings 08:14:36 INFO - Running setup.py install for blessings 08:14:36 INFO - Successfully installed blessings 08:14:36 INFO - Cleaning up... 08:14:36 INFO - Return code: 0 08:14:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:14:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:37 INFO - Reading from file tmpfile_stdout 08:14:37 INFO - Current package versions: 08:14:37 INFO - argparse == 1.2.1 08:14:37 INFO - blessings == 1.6 08:14:37 INFO - blobuploader == 1.2.4 08:14:37 INFO - docopt == 0.6.1 08:14:37 INFO - functools32 == 3.2.3-2 08:14:37 INFO - jsonschema == 2.5.1 08:14:37 INFO - manifestparser == 1.1 08:14:37 INFO - mozInstall == 1.12 08:14:37 INFO - mozcrash == 0.17 08:14:37 INFO - mozdebug == 0.1 08:14:37 INFO - mozdevice == 0.48 08:14:37 INFO - mozfile == 1.2 08:14:37 INFO - mozhttpd == 0.7 08:14:37 INFO - mozinfo == 0.9 08:14:37 INFO - mozleak == 0.1 08:14:37 INFO - mozlog == 3.3 08:14:37 INFO - moznetwork == 0.27 08:14:37 INFO - mozprocess == 0.23 08:14:37 INFO - mozprofile == 0.28 08:14:37 INFO - mozrunner == 6.12 08:14:37 INFO - mozscreenshot == 0.1 08:14:37 INFO - mozsystemmonitor == 0.3 08:14:37 INFO - moztest == 0.7 08:14:37 INFO - mozversion == 1.4 08:14:37 INFO - psutil == 3.1.1 08:14:37 INFO - requests == 1.2.3 08:14:37 INFO - wsgiref == 0.1.2 08:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:14:37 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')]} 08:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:37 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')]} 08:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:37 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:37 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 08:14:37 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 08:14:37 INFO - Using env: {'DISPLAY': ':0', 08:14:37 INFO - 'HOME': '/home/cltbld', 08:14:37 INFO - 'LANG': 'en_US.UTF-8', 08:14:37 INFO - 'LANGUAGE': 'en_US:en', 08:14:37 INFO - 'LOGNAME': 'cltbld', 08:14:37 INFO - 'MAIL': '/var/mail/cltbld', 08:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:37 INFO - 'MOZ_NO_REMOTE': '1', 08:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:37 INFO - 'NO_EM_RESTART': '1', 08:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:37 INFO - 'PWD': '/builds/slave/test', 08:14:37 INFO - 'SHELL': '/bin/bash', 08:14:37 INFO - 'SHLVL': '1', 08:14:37 INFO - 'TERM': 'linux', 08:14:37 INFO - 'TMOUT': '86400', 08:14:37 INFO - 'USER': 'cltbld', 08:14:37 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:37 INFO - '_': '/tools/buildbot/bin/python'} 08:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 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)) 08:14:37 INFO - Cleaning up... 08:14:37 INFO - Return code: 0 08:14:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:14:37 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')]} 08:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:37 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')]} 08:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:14:37 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 0x1cdd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f9e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, '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': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', '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 08:14:37 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 08:14:37 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 08:14:37 INFO - Using env: {'DISPLAY': ':0', 08:14:37 INFO - 'HOME': '/home/cltbld', 08:14:37 INFO - 'LANG': 'en_US.UTF-8', 08:14:37 INFO - 'LANGUAGE': 'en_US:en', 08:14:37 INFO - 'LOGNAME': 'cltbld', 08:14:37 INFO - 'MAIL': '/var/mail/cltbld', 08:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:37 INFO - 'MOZ_NO_REMOTE': '1', 08:14:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:37 INFO - 'NO_EM_RESTART': '1', 08:14:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:37 INFO - 'PWD': '/builds/slave/test', 08:14:37 INFO - 'SHELL': '/bin/bash', 08:14:37 INFO - 'SHLVL': '1', 08:14:37 INFO - 'TERM': 'linux', 08:14:37 INFO - 'TMOUT': '86400', 08:14:37 INFO - 'USER': 'cltbld', 08:14:37 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:37 INFO - '_': '/tools/buildbot/bin/python'} 08:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:14:37 INFO - Cleaning up... 08:14:37 INFO - Return code: 0 08:14:37 INFO - Running post-action listener: _resource_record_post_action 08:14:37 INFO - Running post-action listener: _start_resource_monitoring 08:14:37 INFO - Starting resource monitoring. 08:14:37 INFO - [mozharness: 2016-09-12 15:14:37.539297Z] Finished create-virtualenv step (success) 08:14:37 INFO - [mozharness: 2016-09-12 15:14:37.539728Z] Running install step. 08:14:37 INFO - Running pre-action listener: _resource_record_pre_action 08:14:37 INFO - Running main action method: install 08:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:14:37 INFO - Reading from file tmpfile_stdout 08:14:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:14:37 INFO - Reading from file tmpfile_stdout 08:14:37 INFO - Output received: 08:14:37 INFO - Usage: mozinstall [options] installer 08:14:37 INFO - Options: 08:14:37 INFO - -h, --help show this help message and exit 08:14:37 INFO - -d DEST, --destination=DEST 08:14:37 INFO - Directory to install application into. [default: 08:14:37 INFO - "/builds/slave/test"] 08:14:37 INFO - --app=APP Application being installed. [default: firefox] 08:14:37 INFO - mkdir: /builds/slave/test/build/application 08:14:37 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'] 08:14:37 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 08:14:53 INFO - Reading from file tmpfile_stdout 08:14:53 INFO - Output received: 08:14:53 INFO - /builds/slave/test/build/application/firefox/firefox 08:14:53 INFO - Running post-action listener: _resource_record_post_action 08:14:53 INFO - [mozharness: 2016-09-12 15:14:53.842826Z] Finished install step (success) 08:14:53 INFO - [mozharness: 2016-09-12 15:14:53.843281Z] Running run-tests step. 08:14:53 INFO - Running pre-action listener: _resource_record_pre_action 08:14:53 INFO - Running main action method: run_tests 08:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:14:53 INFO - Python 2.7.3 08:14:53 INFO - Return code: 0 08:14:53 INFO - grabbing minidump binary from tooltool 08:14: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')]} 08:14:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9c3b0>, '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 0x1f9d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:14:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:14:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:14:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:14:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:14:53 INFO - Return code: 0 08:14:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:14:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:14:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:14:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:14:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 08:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --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 08:14:53 INFO - Using env: {'DISPLAY': ':0', 08:14:53 INFO - 'HOME': '/home/cltbld', 08:14:53 INFO - 'LANG': 'en_US.UTF-8', 08:14:53 INFO - 'LANGUAGE': 'en_US:en', 08:14:53 INFO - 'LOGNAME': 'cltbld', 08:14:53 INFO - 'MAIL': '/var/mail/cltbld', 08:14:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:14:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:53 INFO - 'MOZ_NO_REMOTE': '1', 08:14:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:53 INFO - 'NO_EM_RESTART': '1', 08:14:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:53 INFO - 'PWD': '/builds/slave/test', 08:14:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:14:53 INFO - 'SHELL': '/bin/bash', 08:14:53 INFO - 'SHLVL': '1', 08:14:53 INFO - 'TERM': 'linux', 08:14:53 INFO - 'TMOUT': '86400', 08:14:53 INFO - 'USER': 'cltbld', 08:14:53 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729', 08:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:53 INFO - '_': '/tools/buildbot/bin/python'} 08:14:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 08:14:54 INFO - mozversion application_buildid: 20160908025615 08:14:54 INFO - mozversion application_changeset: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d 08:14:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:14:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:14:54 INFO - mozversion application_name: Firefox 08:14:54 INFO - mozversion application_remotingname: firefox-dev 08:14:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:14:54 INFO - mozversion application_vendor: Mozilla 08:14:54 INFO - mozversion application_version: 50.0a2 08:14:54 INFO - mozversion platform_buildid: 20160908025615 08:14:54 INFO - mozversion platform_changeset: 46c6e8bb7f6f6d7281baa761cc931ccd46feca5d 08:14:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:14:54 INFO - mozversion platform_version: 50.0a2 08:14:54 INFO - using testdate: 1473693294 08:14:54 INFO - actual date: 1473693294 08:14:54 INFO - starting webserver on 'localhost:41405' 08:14:54 INFO - SUITE-START | Running 2 tests 08:14:54 INFO - TEST-START | dromaeo_css 08:14:54 INFO - operating with platform_type : linux_ 08:14:54 INFO - Initialising browser for dromaeo_css test... 08:14:54 INFO - TEST-INFO | started process 10913 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBKpq9N/profile http://localhost:41405/getInfo.html) 08:14:58 INFO - PROCESS | 10913 | __metrics Screen width/height:1600/1200 08:14:58 INFO - PROCESS | 10913 | colorDepth:24 08:14:58 INFO - PROCESS | 10913 | Browser inner width/height: 1024/697 08:14:58 INFO - PROCESS | 10913 | __metrics 08:14:58 INFO - PROCESS | 10913 | 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" 08:14:58 INFO - PROCESS | 10913 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:14:59 INFO - TEST-INFO | 10913: exit 0 08:14:59 INFO - Browser initialized. 08:14:59 INFO - Running cycle 1/1 for dromaeo_css test... 08:14:59 INFO - TEST-INFO | started process 10999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBKpq9N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:15:11 INFO - PROCESS | 10999 | RSS: Main: 145891328 08:15:11 INFO - PROCESS | 10999 | 08:16:02 INFO - PROCESS | 10999 | 1473693362378 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] 08:18:11 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41405/tests/dromaeo/cssquery-ext.html) 08:18:12 INFO - PROCESS | 10999 | RSS: Main: 265007104 08:18:12 INFO - PROCESS | 10999 | 08:21:15 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-ext.html (next: http://localhost:41405/tests/dromaeo/cssquery-jquery.html) 08:21:15 INFO - PROCESS | 10999 | RSS: Main: 230367232 08:21:15 INFO - PROCESS | 10999 | 08:24:17 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41405/tests/dromaeo/cssquery-mootools.html) 08:24:18 INFO - PROCESS | 10999 | RSS: Main: 207011840 08:24:18 INFO - PROCESS | 10999 | 08:27:20 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41405/tests/dromaeo/cssquery-prototype.html) 08:27:20 INFO - PROCESS | 10999 | RSS: Main: 176508928 08:27:20 INFO - PROCESS | 10999 | 08:30:23 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41405/tests/dromaeo/cssquery-yui.html) 08:30:24 INFO - PROCESS | 10999 | RSS: Main: 177426432 08:30:24 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | Cycle 1(1): loaded http://localhost:41405/tests/dromaeo/cssquery-yui.html (next: http://localhost:41405/tests/dromaeo/cssquery-dojo.html) 08:33:26 INFO - PROCESS | 10999 | RSS: Main: 257609728 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | __start_tp_report 08:33:26 INFO - PROCESS | 10999 | _x_x_mozilla_page_load 08:33:26 INFO - PROCESS | 10999 | _x_x_mozilla_page_load_details 08:33:26 INFO - PROCESS | 10999 | |i|pagename|runs| 08:33:26 INFO - PROCESS | 10999 | |0;dojo.html;3264.804111753295;3279.7704160708754;3309.5366648669174;3334.299797042621;3363.512290717846;5690.687012214332;5812.44781745734;5831.4751672349485;5846.152307915349;5871.647701137935;5774.82675519734;5815.592908496407;5822.446867547586;5831.037878750001;5837.9124313135335;5101.51535604117;5170.715610641413;5227.712475813838;5251;5344.251804747339;5240.633155679104;5278.551323137538;5294.841154765363;5297.999999999981;5303.840884773463;5141.203113517392;5172.120739474299;5198.636095473318;5219.19102539107;5290.0477913975465;5273.630845840793;5276.208568714705;5280.3663560373;5287.788488460457;5323.201519772027;5042.470540593258;5056.191009438472;5065.265536497224;5210.947890521084;5216.686998780047;5622.212890195376;5641.379448260688;5652.717364131777;5683.204351390808;5687.800926967537;6164.260288765332;6196.566240363131;6197.16338294329;6197.194364732556;6206.627602343874;5984.790532331391;6129.756146212528;6155.045967874987;6205.03821907605;6243.87512249757;5838.9124163137585;5852.853678658025;5860.706964651836;5861.179434879078;5871;6388.7125079371535;6396.0725694774;6398.680065996681;6398.8720225595225;6408.967955160288;5842.444967728059;5925.585209035326;5961;5969.522438204933;6060.939390606126;6103.824864110144;6107.633541987495;6135.141080248769;6139.324674285825;6142.692865356804;6007.098935159674;6060.636361818305;6062.787802426894;6072.574919755575;6081.878362432726;5996.280446346466;6012.278526576752;6062.636241825505;6088;6088.604240724428;4684.531546845288;4693.78877950493;4712.481627021025;4777.880552986134;4781.904361912742;4198.223328684203;4204.726692764961;4214.536400995888;4221.261279276115;4237.766922819213;5898.380670029585;5952.672603006789;5956.493698035629;5966.731497082642;5969.880602387928;5883.470487656132;6108.129596501707;6134.386561343919;6153.384661533811;6246.1255424239735;5861.941380586225;5916.437938395931;5919.792807251725;5922.200502932049;5947.464728174485;5200.2199670049795;5206.062908676475;5206.739663016834;5212.061828870764;5217.660852044605;6146.631202127886;6323.30443651198;6378.330275321117;6383.776567820104;6471.094046833353;6234.189555357774;6234.625922444668;6243.96878015607;6246.531510136849;6250.781222657367;6410.358964103552;6448.871022579616;6451.870962580629;6457.128287681291;6463.385978332051;5915.467607915309;5998.190244317136;6039.184710064261;6052.757889684363;6113.908291375722;6170.722317495633;6175.560653541916;6207.96896015517;6222.751089956351;6233.875322493615;6034.517238620988;6075;6076.361981992004;6077.118817771487;6110.908336374869;5844.766209351578;5971.552133589911;6015;6016.368281330398;6093.725782339717;6002.969985150046;6013.849653758691;6032.969835150971;6040.305364883095;6054.697265136813;6035;6074.908876366946;6092.055731361646;6113.5414843886;6114.969425152845;5724.141378793214;5771.422857714195;5879.470847623735;5907.881842363215;5921.940780592139 08:33:26 INFO - PROCESS | 10999 | |1;ext.html;582.7523302596395;608.1577015833071;636.1157990393355;639.8144538083956;640.6028262477265;34782.826085869565;38075.23849523008;38095.23809523808;38099.61900380995;38414.42378364323;38972;47127;47795.28307075397;47944.28083578749;51711.741441292834;9197.356185067049;9332.066793320648;9332.346735728503;9335.953320233424;9336.393134446273;9128.908710912872;9220.631174753002;9224.492652904088;9224.953875230614;9225.12361325673;8575.914240857574;11185.66443006711;11466.197366184371;11477.655670329861;11863.822042669366;11211.719707007309;11271.943640281828;11300.54797808095;11309.547618095266;11310.208285420065;8697.434666746642;11063.114950803916;11132.777344453147;11133.944330278378;11140.610078647289;43797.34303985443;44631;44735;47530.28704569434;47625.76187119042;3731.067233191702;3755.586885442599;3901.1027463683513;4024.4969378827645;4072.9796351018354;35806.82096589526;35860.48976571641;36001.45997810035;36085.81957090224;36086.458703119475;4724.220503616899;4742.170120228946;4815.927761083586;4890.462049174588;4896.583790377822;375803;378485;378653;379226.10386948165;380158;63075;63186.684066580296;63370.68314658397;63382.049269261;63401.36598634047;67564;67574.32425675681;67632.6618366905;67635.66182169058;67755.66122169358;15863.841361586236;16240.187990600423;16261.430849920196;16268.105254210896;16272.918635406748;12684.746305074032;12746.171498852545;12758.106932514635;12777.16948398351;12808.871911280768;16131.435399761187;17310.913445432692;17974.191161397768;18350.908245458955;18356.357527486474;6099.573029887954;6281;6335.176427064452;6386.489080873519;6466;1054.5623566220086;1119.5857532712785;1134.0304040045808;1152.5578260955767;1171.262104873924;34631.480527792104;35094.82452587721;35482.29035419277;35593.64406355903;35623.82188089043;1200.5557943560939;1222.7615614955175;1233.3771445420005;1240.2806372304042;1334.466213514602;18266.721329506807;18421.355252566096;18462.630747384977;18859.24563017464;18862.43412697621;173827;174983;175522;175757;175932;61034.084488732704;62996;63593.36406635967;65560;65735.67132164308;1420.779779134236;1421.9004669673232;1435.798988141661;1446.3708286895444;1448.9565313040625;34204.82897585496;37319.81340093282;37364.252714945;37532;37658;55986;56257.43742562522;56491;56542.43457565536;56757.14864276956;533511;541743;546910;548574;771370;31332.37335253328;31493.370132596763;31518.684813151576;31584.684153158174;31844.203894902814;53665.46334536605;53687.73156134194;54057;54204;54239.45760542502;43516.78241608771;44964.77517612391;45066.09867802691;45698.08603827971;46819.76590117028;44537;45163.54836451725;45247.773761130986;46418.767906160254;46795;45326.54673453223;45329.32006019978;45432.54567454283;45600.54399455963;46996.7650161747;38160.427593586675;39494;39632.801835990635;39672.40491392689;40158.397624035075;29440;30083.32420782244;30394.54408183923;30478.390432190787;30704.232394190323 08:33:26 INFO - PROCESS | 10999 | |2;jquery.html;394.2883096011698;420.7896051974013;421.81861799426235;424.1686294862071;425.823283337415;15594.922025389871;20315.99999999998;20591.38225853225;20738;21277;20793.16827326905;20859.687104693443;20888.477788055305;21042.894785526052;21293.57412851742;5494.890102197954;5936.7031648417405;5964.254468191476;5973.88052238955;5979.701014949257;4386.539413361583;4398.868033958986;5854.707264636772;5946.910796338037;5948.613340132899;6564.737410503598;6602.669866506655;6631.469482441393;6636.668166591675;6645.800625981228;5851.6781577013035;6094.847628809315;6116.785912493041;6542.705578248966;6604.504662150334;5988.8502787430225;6000.96999515004;6004.039353703386;6005.039193728982;6012.458878700938;22284.554308913892;22461.887690561605;23116.3065108047;24720.752792472205;24935.625965610532;2477.7893879020303;2601.5707408277613;2648.1525911708263;2737.986310068437;2878.870450829718;10749.355038697626;11070.39112848793;11151.052160566362;11305.095592352593;11337;2015;2124.0866427436194;2203.2068455356057;2219.3120132758895;2298.5632729781287;81959;84963;85643;86073.13926860713;89500.55249723775;11413.201075924771;11464.999999999833;11724.593048834195;11747.765044699228;11851.762964740657;16727.498175054767;16736.497905062868;16738.58153546171;16747.748783768253;16769.91615041917;8157.1842815717955;8260.752177434686;8362.958185209034;8384.916150838535;8481.109483504266;7875.133735289113;7933.642986065641;8078.232567906157;8078.55567943757;8090.312323452455;4944.381952255968;4993.80024799004;5035.16919708244;5082.263071854561;5143.691378517301;2064.1846470644014;2083.499960009617;2086.4158035750015;2109.662454007382;2151.8493705440783;1013.6350913671073;1018.6943916825071;1026.7279171019686;1050.106503692788;1130.1354463835173;20098.89950550267;20715.585688286148;20757;21044.368668939955;21056.894715526632;1032.865727455441;1044.3577200022085;1059.6450189186555;1066.0991462214417;1076.7684947736275;10900.618478353219;11620.825687614693;11628.418579071013;11720.472578733807;11760.58837940668;524697;529086;532066;535943;541199;92783.53608231916;98161.50919245358;99411.50294248627;101482.49258753659;101527;996.3573495095768;1023.6366090038078;1036.8703912011;1041.4948749856314;1052.8262836632023;17462.388816391365;17996.280148794427;18007.5498112543;18707.81292187115;18858.434246972614;37913.810430948775;45161.32258016067;45198.87002824824;46213;46397.536024639325;49770.502294976584;58636.41363586309;58673;59602.125861818844;60245.698771505864;8116.918830811616;8202.302804261466;8399.622017009371;8564;8680.175383338572;13226.4048117833;13695.041347106006;14000.299985000505;14094;14163.504277350243;6423.903641445476;8285.875711864206;11236.876312368699;11604.419779011185;11725.179237453296;11615.593454229062;11668.708282292671;11672.533098675958;11883.702907427383;12157.75684486323;11733.589324373606;12092.576759813364;12226.327551984727;12266.202696824677;12374.257544547356;8641.179087986631;8759;8839.779005524913;9019.594118264562;9080.40977336471;11382.487788049391;12382.071344649368;12480.5007799687;12648.557300494438;12694.746105078031 08:33:26 INFO - PROCESS | 10999 | |3;mootools.html;1407.5917983784702;1544.5906834688806;1577.5346272849508;1722.7157519009363;1838.8620853435996;9017.323700722447;9351.766205844857;9389.201917836976;9642.71071867845;9685.806283874335;9516.85724714128;9610.711678649652;9611;9623.326367154303;9632.084951929573;2485.2668462803517;2501.5997440409537;2504.3238325652064;2504.749525047499;2513.409348803039;2480.7147178074483;2533.8859751311234;2537.429078457351;2545.210984594772;2546.019782383786;2487.825852190347;2503.7996960243136;2531.924042278725;2535.1633960792988;2541.1995221505167;2439.0487709793106;2463.2610216934936;2466;2471.221565206954;2475.6039033754514;2468.6543883856275;2483.428811373376;2490.2155820916355;2491.1032028469735;2521.810864185175;9467.384619999679;9578.042195780436;9667.226621870233;9721.17370023549;9737.464439455827;5741.799037033684;5957.523398128139;5958.702064896781;5969.552283578705;5980.940190598125;3538.964610353915;3562.9109272268142;3582.910427239314;3661.5423072115987;3666.816659167031;1173.178774857603;1174.277819141222;1185.4013723069882;1188.0436248819697;1208.957686480969;13577.456901724017;13586.116902401312;13597.524086657018;13672.726545469035;13687.931560342235;4917.901641967141;4927.285543596158;5074.112030394666;5092.479222784746;5113.744312784346;10623.36259824413;10665.680029599125;10667.83998240027;10669.893301067044;10675.145988320915;5869.941300587025;5903.468687818031;5911.438413350725;5912.704364781744;5913.142594323799;4785.49752276006;4788.904221915543;5482.479164479368;5484.122540393518;5490.423505531941;4275.657947364203;4324.675649326313;4337.221739086959;4378.737275763465;4383.517813040563;1257.3398965543163;1307.8038294255937;1322.5701646964776;1322.6825561865082;1323.2854258700386;1066.8719753629614;1067.4822710985165;1068.433730123036;1079.578964203961;1081.8214994525815;6436.581622194542;6545.181852268466;6681.198256209286;6774.559653622598;6938.306169383122;474.0779184486141;474.23174457379264;491.5649650059651;493.1985523524273;496.0624419846493;4663.183942810063;4698.01341718243;4735.739534325577;4782.450018247946;4832;12010.979066779422;12314;12459.06557008228;12462.813057803958;12496.25022498653;11419.429028548539;11783.76432471363;11796.174267801343;12207.816882746765;12221.69445763863;8597.699080532151;8757.080506546848;8786.341024423195;8805;8834.381593288537;6838.7264509419065;6994.510384273052;7010.544314619533;7133.928660713534;7165.713371465083;10837;10996.395198263852;11327.15046371509;11426.485808138488;11444.713882153013;11376.94311528437;11417.31496110236;11616.825747613962;11636.010939070275;11693.707657308636;8596.097409772134;8739.12608739121;8804.867926980973;8807;8814.382993190415;8965.865512017195;9255.953720231355;9296.628134874529;9317.39436936624;9324.627014919599;8313.293370063613;8534;8633.611487482953;8654.350923680875;8810.383273171074;8359.474269377499;8371.95814020926;8480.575971201291;8601.913980860112;8682.479051256945;7362.116546014298;7483.625818708934;7485.962570187115;7492.550446973199;7493.88759168602;5621.8594535136945;5730.140478928028;5773.451522105393;5811.302643682785;5928.199693041557;5605.80379686709;5744.167095771173;5747.9137812932;5762.827115186551;5768.394318596487 08:33:26 INFO - PROCESS | 10999 | |4;prototype.html;349.18639569802355;355.16004649005095;356.1861147278469;359.0234561991384;360.8899285717857;5520.668759874406;5550.3617084035395;5556.611037227396;5576.832695019151;5627.071533197028;5459.972700136494;5539.889202215963;5544;5548.029094908395;5555.861103472415;4236.110416812466;4307.1170410066015;4343.717658352213;4344.4786625604975;4366.607005369516;4332.913341733163;4346.304591265398;4350.281638752691;4352.869413917571;4357.651387888977;4289.613934745872;4290.957090429087;4316.611504964552;4327.221100201962;4351.543087975764;4175.979120104395;4313.6980411370905;4317.266064768997;4343.80452879618;4363.280058790296;3805.7335986480957;3808.5810560838286;3808.9619103808886;3810.79040652764;3818.522684664417;5343.973280133574;5374.167004114369;5399.163129714901;5407.242985981966;5419.783208671648;4694.530546945312;4899.436564795059;4930.309756634074;4940.48124946879;4963.2058870580895;5725.8568535786935;5731.312242530881;5737.704918032779;5739.225204597367;5745;4172.666186705056;4176.498820141572;4178.623923846869;4180.644645205161;4186.5604111568155;5747.482726554588;5910.556708246899;5929.644221346733;5958.851028724273;5962.940370596282;4220.839269200995;4228.302330115558;4232.978835105805;4236.216299984451;4239.936400953989;4294.484661840599;4309.396684464177;4315.978420107879;4326.610605045561;4348.586884245991;4781.211100168433;4896.25809546655;4927.127691477216;4972.707096154953;4974.577160941288;5735.770569177186;5743.827685169452;5745.913811292834;5747.281589801275;5751.827445176651;1490.1729540105346;1504.3456096598015;1505.631120375515;1505.917174555388;1513.2585033333587;958.4632605740808;969.7187815533557;983.2183414185739;1021.3922715984127;1035.502958579871;5538.9169162462595;5553;5560.777568897199;5561.304836895388;5573.077288335518;5860.589758716934;5891.528677705774;5897.056470964711;5904.228731380568;5920.2895652521975;5359.1328639177855;5608.037186984523;5637.689927054052;5667.319921609434;5714.457126572969;3109.0828205679377;3162.2410621450686;3166.5566820645126;3172.904812855618;3173.1591128351006;5844.59087863854;6121.846953826101;6173.84565385869;6187.876242475125;6219.782307619212;5772.711364431846;6042.456178943916;6054.545909056838;6061.181645506331;6076.149339092531;5963.344032156459;6102.284483404931;6137.833811575786;6141.84645383869;6162.26052873649;5955.196048533393;5963.70181490915;5972.924873522634;5982.341942386247;5995.610285331614;5619.325680918316;5642.266505354278;5865.530757539472;5875.236219291465;5891.204687367322;6140.17107690456;6142.372271348007;6143.38905964972;6169.598976066542;6181.381861813783;4154.729942553724;4237.766922819274;4279.068837846085;4289.099289149189;4329.2640251156745;5755.827325180251;5780.826575202751;5791.478766910998;5817.272840894888;5817.720101577646;5678.4605462480995;5702;5706.715988902463;5756.740946657326;5779.422057794187;5563.693996830213;5704.974733969381;5753.309602847517;5807.854803629774;5810.1575271586225;5741.25363702716;5752.395998420106;5776.133579963039;5799.391063938395;5803.65178089316;5634.083912525062;5832.50423713989;5868.501177399798;5909.172715819875;5926.644401335933;5641;5682.772689092555;5714.885702285847;5717.599768016199;5731.770729170787 08:33:26 INFO - PROCESS | 10999 | |5;yui.html;776.5069181070019;802.5706247157772;825.372716735281;830.389663597256;857.8541647919853;14779.113253204801;15121.92439037806;15300.923495382522;15463;16371.18144092795;16697;16758;16856.747148792747;16865.40971066013;16885.07132107737;717.8564287142566;833.3374966901328;846.2172490446328;859.5745106172453;863.386995233385;864.0365991918997;864.11859902899;871.1897934920521;871.9607617657189;872;915.7435917942987;919.0763282900675;919.3702313914989;919.6367434863225;919.6367434863258;750.9324160825552;865.5888452984782;902.0663613160381;916.4730280088942;916.7708072981754;655.1221363373055;660.2868901586276;661.5832025823748;661.8279247395664;661.8676264747022;16321.10233937133;16335.754963675554;16360.672786544203;16371.181440927907;16392.672146557;15313.157776322298;15445.459408920633;15464;15493.612659683486;15651.217439127999;14380;14554.927225363912;14603.834280747658;14622.926885365612;14885.181315027668;4760.166970780099;4781.593564547018;4797.64017698674;4847.321375007502;4868.902621947576;82098.58950705267;82142.58928705318;82934;83370;83642;11813.002264075869;12670.936645316715;12704.80942785859;12711.55509557161;12815.551455699006;22329.665055024187;22390;22592.322230333117;22640.433989150402;22824.201152959566;8620.95689521561;8723.825523489495;8827.337949653673;8927.553622318857;8930.732078037669;5084.49155084496;5247.790088396498;5304.257403963448;5320.791941092652;5328.147496400557;3773.509443772292;3889.5138107736534;3894.532656081288;3894.7273690841935;3900.278448486982;584.964902105875;668.6790340636425;670.808819486444;670.902719105727;672.525869262169;670.778643047793;678.4233401608593;695.9756408525778;706.445440329339;787.6888628991517;10871.075958543612;11492.827607585892;11603.187776855708;11645.475953582109;11729.824052639216;698.5983059740568;716.8422946951662;774.0092681367859;796.5419883566972;823.1932705948085;4887.266909963463;5123.7438128093445;5130.281760553526;5165.845024649267;5172.482751724872;67849.98225026629;67982;69194.65402672955;105004;108951;94739;95214;97079;99601.50199248958;100192;57036;83485.16514834895;83618.58190709008;84420;93936.12127757407;9872.422081523917;10074.345167564084;10199.7450063749;10452.947735261276;10672.946635266773;54580.90838183294;55198.44801551933;55548;56577;57569.13646295225;70597;71726;79556.60221698854;81298;84956;13364;13953.581392558239;14250.931180161322;14513.346899389764;14661.340239689453;15901.510157755893;16096.919515402347;16170.67658646844;16229.675406491568;17188;15131.167785771888;15989.520314390587;16168.919155404148;16694;16749.078800665593;14720.631984200481;15017.174055427056;15451.61370965735;15545.611359716098;16089.436869709503;14784.63038424048;15210.08739475635;15345.46290879814;15367.385304587691;15864.682706346039;11796.823047654121;12087.939560302493;12102.515899363927;12728.881424051167;13227.338633068115;12733.490660373853;12759.93620031894;12785;12843.29361885068;13360.482340759738 08:33:26 INFO - PROCESS | 10999 | __end_tp_report 08:33:26 INFO - PROCESS | 10999 | __start_cc_report 08:33:26 INFO - PROCESS | 10999 | _x_x_mozilla_cycle_collect,1022 08:33:26 INFO - PROCESS | 10999 | __end_cc_report 08:33:26 INFO - PROCESS | 10999 | __startTimestamp1473694406808__endTimestamp 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | ------- Summary: start ------- 08:33:26 INFO - PROCESS | 10999 | Number of tests: 6 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#0] dojo.html Cycles:165 Average:5723.60 Median:5956.49 stddev:657.71 (11.0%) stddev-sans-first:630.81 08:33:26 INFO - PROCESS | 10999 | Values: 3264.8 3279.8 3309.5 3334.3 3363.5 5690.7 5812.4 5831.5 5846.2 5871.6 5774.8 5815.6 5822.4 5831.0 5837.9 5101.5 5170.7 5227.7 5251.0 5344.3 5240.6 5278.6 5294.8 5298.0 5303.8 5141.2 5172.1 5198.6 5219.2 5290.0 5273.6 5276.2 5280.4 5287.8 5323.2 5042.5 5056.2 5065.3 5210.9 5216.7 5622.2 5641.4 5652.7 5683.2 5687.8 6164.3 6196.6 6197.2 6197.2 6206.6 5984.8 6129.8 6155.0 6205.0 6243.9 5838.9 5852.9 5860.7 5861.2 5871.0 6388.7 6396.1 6398.7 6398.9 6409.0 5842.4 5925.6 5961.0 5969.5 6060.9 6103.8 6107.6 6135.1 6139.3 6142.7 6007.1 6060.6 6062.8 6072.6 6081.9 5996.3 6012.3 6062.6 6088.0 6088.6 4684.5 4693.8 4712.5 4777.9 4781.9 4198.2 4204.7 4214.5 4221.3 4237.8 5898.4 5952.7 5956.5 5966.7 5969.9 5883.5 6108.1 6134.4 6153.4 6246.1 5861.9 5916.4 5919.8 5922.2 5947.5 5200.2 5206.1 5206.7 5212.1 5217.7 6146.6 6323.3 6378.3 6383.8 6471.1 6234.2 6234.6 6244.0 6246.5 6250.8 6410.4 6448.9 6451.9 6457.1 6463.4 5915.5 5998.2 6039.2 6052.8 6113.9 6170.7 6175.6 6208.0 6222.8 6233.9 6034.5 6075.0 6076.4 6077.1 6110.9 5844.8 5971.6 6015.0 6016.4 6093.7 6003.0 6013.8 6033.0 6040.3 6054.7 6035.0 6074.9 6092.1 6113.5 6115.0 5724.1 5771.4 5879.5 5907.9 5921.9 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#1] ext.html Cycles:180 Average:57189.29 Median:38693.21 stddev:112183.24 (289.9%) stddev-sans-first:112417.44 08:33:26 INFO - PROCESS | 10999 | Values: 582.8 608.2 636.1 639.8 640.6 34782.8 38075.2 38095.2 38099.6 38414.4 38972.0 47127.0 47795.3 47944.3 51711.7 9197.4 9332.1 9332.3 9336.0 9336.4 9128.9 9220.6 9224.5 9225.0 9225.1 8575.9 11185.7 11466.2 11477.7 11863.8 11211.7 11271.9 11300.5 11309.5 11310.2 8697.4 11063.1 11132.8 11133.9 11140.6 43797.3 44631.0 44735.0 47530.3 47625.8 3731.1 3755.6 3901.1 4024.5 4073.0 35806.8 35860.5 36001.5 36085.8 36086.5 4724.2 4742.2 4815.9 4890.5 4896.6 375803.0 378485.0 378653.0 379226.1 380158.0 63075.0 63186.7 63370.7 63382.0 63401.4 67564.0 67574.3 67632.7 67635.7 67755.7 15863.8 16240.2 16261.4 16268.1 16272.9 12684.7 12746.2 12758.1 12777.2 12808.9 16131.4 17310.9 17974.2 18350.9 18356.4 6099.6 6281.0 6335.2 6386.5 6466.0 1054.6 1119.6 1134.0 1152.6 1171.3 34631.5 35094.8 35482.3 35593.6 35623.8 1200.6 1222.8 1233.4 1240.3 1334.5 18266.7 18421.4 18462.6 18859.2 18862.4 173827.0 174983.0 175522.0 175757.0 175932.0 61034.1 62996.0 63593.4 65560.0 65735.7 1420.8 1421.9 1435.8 1446.4 1449.0 34204.8 37319.8 37364.3 37532.0 37658.0 55986.0 56257.4 56491.0 56542.4 56757.1 533511.0 541743.0 546910.0 548574.0 771370.0 31332.4 31493.4 31518.7 31584.7 31844.2 53665.5 53687.7 54057.0 54204.0 54239.5 43516.8 44964.8 45066.1 45698.1 46819.8 44537.0 45163.5 45247.8 46418.8 46795.0 45326.5 45329.3 45432.5 45600.5 46996.8 38160.4 39494.0 39632.8 39672.4 40158.4 29440.0 30083.3 30394.5 30478.4 30704.2 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#2] jquery.html Cycles:180 Average:30881.55 Median:12707.43 stddev:87768.33 (690.7%) stddev-sans-first:87984.69 08:33:26 INFO - PROCESS | 10999 | Values: 394.3 420.8 421.8 424.2 425.8 15594.9 20316.0 20591.4 20738.0 21277.0 20793.2 20859.7 20888.5 21042.9 21293.6 5494.9 5936.7 5964.3 5973.9 5979.7 4386.5 4398.9 5854.7 5946.9 5948.6 6564.7 6602.7 6631.5 6636.7 6645.8 5851.7 6094.8 6116.8 6542.7 6604.5 5988.9 6001.0 6004.0 6005.0 6012.5 22284.6 22461.9 23116.3 24720.8 24935.6 2477.8 2601.6 2648.2 2738.0 2878.9 10749.4 11070.4 11151.1 11305.1 11337.0 2015.0 2124.1 2203.2 2219.3 2298.6 81959.0 84963.0 85643.0 86073.1 89500.6 11413.2 11465.0 11724.6 11747.8 11851.8 16727.5 16736.5 16738.6 16747.7 16769.9 8157.2 8260.8 8363.0 8384.9 8481.1 7875.1 7933.6 8078.2 8078.6 8090.3 4944.4 4993.8 5035.2 5082.3 5143.7 2064.2 2083.5 2086.4 2109.7 2151.8 1013.6 1018.7 1026.7 1050.1 1130.1 20098.9 20715.6 20757.0 21044.4 21056.9 1032.9 1044.4 1059.6 1066.1 1076.8 10900.6 11620.8 11628.4 11720.5 11760.6 524697.0 529086.0 532066.0 535943.0 541199.0 92783.5 98161.5 99411.5 101482.5 101527.0 996.4 1023.6 1036.9 1041.5 1052.8 17462.4 17996.3 18007.5 18707.8 18858.4 37913.8 45161.3 45198.9 46213.0 46397.5 49770.5 58636.4 58673.0 59602.1 60245.7 8116.9 8202.3 8399.6 8564.0 8680.2 13226.4 13695.0 14000.3 14094.0 14163.5 6423.9 8285.9 11236.9 11604.4 11725.2 11615.6 11668.7 11672.5 11883.7 12157.8 11733.6 12092.6 12226.3 12266.2 12374.3 8641.2 8759.0 8839.8 9019.6 9080.4 11382.5 12382.1 12480.5 12648.6 12694.7 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#3] mootools.html Cycles:180 Average:6370.38 Median:4783.97 stddev:3663.55 (76.6%) stddev-sans-first:3654.84 08:33:26 INFO - PROCESS | 10999 | Values: 1407.6 1544.6 1577.5 1722.7 1838.9 9017.3 9351.8 9389.2 9642.7 9685.8 9516.9 9610.7 9611.0 9623.3 9632.1 2485.3 2501.6 2504.3 2504.7 2513.4 2480.7 2533.9 2537.4 2545.2 2546.0 2487.8 2503.8 2531.9 2535.2 2541.2 2439.0 2463.3 2466.0 2471.2 2475.6 2468.7 2483.4 2490.2 2491.1 2521.8 9467.4 9578.0 9667.2 9721.2 9737.5 5741.8 5957.5 5958.7 5969.6 5980.9 3539.0 3562.9 3582.9 3661.5 3666.8 1173.2 1174.3 1185.4 1188.0 1209.0 13577.5 13586.1 13597.5 13672.7 13687.9 4917.9 4927.3 5074.1 5092.5 5113.7 10623.4 10665.7 10667.8 10669.9 10675.1 5869.9 5903.5 5911.4 5912.7 5913.1 4785.5 4788.9 5482.5 5484.1 5490.4 4275.7 4324.7 4337.2 4378.7 4383.5 1257.3 1307.8 1322.6 1322.7 1323.3 1066.9 1067.5 1068.4 1079.6 1081.8 6436.6 6545.2 6681.2 6774.6 6938.3 474.1 474.2 491.6 493.2 496.1 4663.2 4698.0 4735.7 4782.5 4832.0 12011.0 12314.0 12459.1 12462.8 12496.3 11419.4 11783.8 11796.2 12207.8 12221.7 8597.7 8757.1 8786.3 8805.0 8834.4 6838.7 6994.5 7010.5 7133.9 7165.7 10837.0 10996.4 11327.2 11426.5 11444.7 11376.9 11417.3 11616.8 11636.0 11693.7 8596.1 8739.1 8804.9 8807.0 8814.4 8965.9 9256.0 9296.6 9317.4 9324.6 8313.3 8534.0 8633.6 8654.4 8810.4 8359.5 8372.0 8480.6 8601.9 8682.5 7362.1 7483.6 7486.0 7492.6 7493.9 5621.9 5730.1 5773.5 5811.3 5928.2 5605.8 5744.2 5747.9 5762.8 5768.4 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#4] prototype.html Cycles:180 Average:4874.42 Median:5568.39 stddev:1420.99 (25.5%) stddev-sans-first:1383.79 08:33:26 INFO - PROCESS | 10999 | Values: 349.2 355.2 356.2 359.0 360.9 5520.7 5550.4 5556.6 5576.8 5627.1 5460.0 5539.9 5544.0 5548.0 5555.9 4236.1 4307.1 4343.7 4344.5 4366.6 4332.9 4346.3 4350.3 4352.9 4357.7 4289.6 4291.0 4316.6 4327.2 4351.5 4176.0 4313.7 4317.3 4343.8 4363.3 3805.7 3808.6 3809.0 3810.8 3818.5 5344.0 5374.2 5399.2 5407.2 5419.8 4694.5 4899.4 4930.3 4940.5 4963.2 5725.9 5731.3 5737.7 5739.2 5745.0 4172.7 4176.5 4178.6 4180.6 4186.6 5747.5 5910.6 5929.6 5958.9 5962.9 4220.8 4228.3 4233.0 4236.2 4239.9 4294.5 4309.4 4316.0 4326.6 4348.6 4781.2 4896.3 4927.1 4972.7 4974.6 5735.8 5743.8 5745.9 5747.3 5751.8 1490.2 1504.3 1505.6 1505.9 1513.3 958.5 969.7 983.2 1021.4 1035.5 5538.9 5553.0 5560.8 5561.3 5573.1 5860.6 5891.5 5897.1 5904.2 5920.3 5359.1 5608.0 5637.7 5667.3 5714.5 3109.1 3162.2 3166.6 3172.9 3173.2 5844.6 6121.8 6173.8 6187.9 6219.8 5772.7 6042.5 6054.5 6061.2 6076.1 5963.3 6102.3 6137.8 6141.8 6162.3 5955.2 5963.7 5972.9 5982.3 5995.6 5619.3 5642.3 5865.5 5875.2 5891.2 6140.2 6142.4 6143.4 6169.6 6181.4 4154.7 4237.8 4279.1 4289.1 4329.3 5755.8 5780.8 5791.5 5817.3 5817.7 5678.5 5702.0 5706.7 5756.7 5779.4 5563.7 5705.0 5753.3 5807.9 5810.2 5741.3 5752.4 5776.1 5799.4 5803.7 5634.1 5832.5 5868.5 5909.2 5926.6 5641.0 5682.8 5714.9 5717.6 5731.8 08:33:26 INFO - PROCESS | 10999 | 08:33:26 INFO - PROCESS | 10999 | [#5] yui.html Cycles:180 Average:20861.15 Median:4858.11 stddev:27824.09 (572.7%) stddev-sans-first:27861.27 08:33:26 INFO - PROCESS | 10999 | Values: 776.5 802.6 825.4 830.4 857.9 14779.1 15121.9 15300.9 15463.0 16371.2 16697.0 16758.0 16856.7 16865.4 16885.1 717.9 833.3 846.2 859.6 863.4 864.0 864.1 871.2 872.0 872.0 915.7 919.1 919.4 919.6 919.6 750.9 865.6 902.1 916.5 916.8 655.1 660.3 661.6 661.8 661.9 16321.1 16335.8 16360.7 16371.2 16392.7 15313.2 15445.5 15464.0 15493.6 15651.2 14380.0 14554.9 14603.8 14622.9 14885.2 4760.2 4781.6 4797.6 4847.3 4868.9 82098.6 82142.6 82934.0 83370.0 83642.0 11813.0 12670.9 12704.8 12711.6 12815.6 22329.7 22390.0 22592.3 22640.4 22824.2 8621.0 8723.8 8827.3 8927.6 8930.7 5084.5 5247.8 5304.3 5320.8 5328.1 3773.5 3889.5 3894.5 3894.7 3900.3 585.0 668.7 670.8 670.9 672.5 670.8 678.4 696.0 706.4 787.7 10871.1 11492.8 11603.2 11645.5 11729.8 698.6 716.8 774.0 796.5 823.2 4887.3 5123.7 5130.3 5165.8 5172.5 67850.0 67982.0 69194.7 105004.0 108951.0 94739.0 95214.0 97079.0 99601.5 100192.0 57036.0 83485.2 83618.6 84420.0 93936.1 9872.4 10074.3 10199.7 10452.9 10672.9 54580.9 55198.4 55548.0 56577.0 57569.1 70597.0 71726.0 79556.6 81298.0 84956.0 13364.0 13953.6 14250.9 14513.3 14661.3 15901.5 16096.9 16170.7 16229.7 17188.0 15131.2 15989.5 16168.9 16694.0 16749.1 14720.6 15017.2 15451.6 15545.6 16089.4 14784.6 15210.1 15345.5 15367.4 15864.7 11796.8 12087.9 12102.5 12728.9 13227.3 12733.5 12759.9 12785.0 12843.3 13360.5 08:33:26 INFO - PROCESS | 10999 | -------- Summary: end -------- 08:33:26 INFO - PROCESS | 10999 | 08:33:27 INFO - TEST-INFO | 10999: exit 0 08:33:27 INFO - TEST-OK | dromaeo_css | took 1113406ms 08:33:27 INFO - TEST-START | kraken 08:33:27 INFO - operating with platform_type : linux_ 08:33:27 INFO - Initialising browser for kraken test... 08:33:27 INFO - TEST-INFO | started process 11204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYpxQCa/profile http://localhost:41405/getInfo.html) 08:33:31 INFO - PROCESS | 11204 | __metrics Screen width/height:1600/1200 08:33:31 INFO - PROCESS | 11204 | colorDepth:24 08:33:31 INFO - PROCESS | 11204 | Browser inner width/height: 1024/697 08:33:31 INFO - PROCESS | 11204 | __metrics 08:33:32 INFO - PROCESS | 11204 | 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" 08:33:32 INFO - PROCESS | 11204 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:33:33 INFO - TEST-INFO | 11204: exit 0 08:33:33 INFO - Browser initialized. 08:33:33 INFO - Running cycle 1/1 for kraken test... 08:33:33 INFO - TEST-INFO | started process 11292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYpxQCa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:33:45 INFO - PROCESS | 11292 | RSS: Main: 146288640 08:33:45 INFO - PROCESS | 11292 | 08:33:47 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41405/tests/kraken/driver.html?testName=audio-beat-detection) 08:33:48 INFO - PROCESS | 11292 | RSS: Main: 200523776 08:33:48 INFO - PROCESS | 11292 | 08:33:50 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41405/tests/kraken/driver.html?testName=audio-dft) 08:33:51 INFO - PROCESS | 11292 | RSS: Main: 184639488 08:33:51 INFO - PROCESS | 11292 | 08:33:54 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41405/tests/kraken/driver.html?testName=audio-fft) 08:33:54 INFO - PROCESS | 11292 | RSS: Main: 275738624 08:33:54 INFO - PROCESS | 11292 | 08:33:56 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41405/tests/kraken/driver.html?testName=audio-oscillator) 08:33:57 INFO - PROCESS | 11292 | RSS: Main: 181174272 08:33:57 INFO - PROCESS | 11292 | 08:33:59 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41405/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:34:00 INFO - PROCESS | 11292 | RSS: Main: 179961856 08:34:00 INFO - PROCESS | 11292 | 08:34:03 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41405/tests/kraken/driver.html?testName=imaging-darkroom) 08:34:03 INFO - PROCESS | 11292 | RSS: Main: 216567808 08:34:03 INFO - PROCESS | 11292 | 08:34:07 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41405/tests/kraken/driver.html?testName=imaging-desaturate) 08:34:07 INFO - PROCESS | 11292 | RSS: Main: 214618112 08:34:07 INFO - PROCESS | 11292 | 08:34:10 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41405/tests/kraken/driver.html?testName=json-parse-financial) 08:34:11 INFO - PROCESS | 11292 | RSS: Main: 215375872 08:34:11 INFO - PROCESS | 11292 | 08:34:12 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41405/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:34:13 INFO - PROCESS | 11292 | RSS: Main: 177385472 08:34:13 INFO - PROCESS | 11292 | 08:34:14 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:34:15 INFO - PROCESS | 11292 | RSS: Main: 184041472 08:34:15 INFO - PROCESS | 11292 | 08:34:17 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:34:18 INFO - PROCESS | 11292 | RSS: Main: 186855424 08:34:18 INFO - PROCESS | 11292 | 08:34:20 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:34:21 INFO - PROCESS | 11292 | RSS: Main: 182149120 08:34:21 INFO - PROCESS | 11292 | 08:34:23 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:34:24 INFO - PROCESS | 11292 | RSS: Main: 184856576 08:34:24 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | Cycle 1(1): loaded http://localhost:41405/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41405/tests/kraken/driver.html?testName=ai-astar) 08:34:26 INFO - PROCESS | 11292 | RSS: Main: 235565056 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | __start_tp_report 08:34:26 INFO - PROCESS | 11292 | _x_x_mozilla_page_load 08:34:26 INFO - PROCESS | 11292 | _x_x_mozilla_page_load_details 08:34:26 INFO - PROCESS | 11292 | |i|pagename|runs| 08:34:26 INFO - PROCESS | 11292 | |0;ai-astar;110;111;118;110;110;111;109;110;111;109 08:34:26 INFO - PROCESS | 11292 | |1;audio-beat-detection;149;115;145;168;115;146;146;118;173;116 08:34:26 INFO - PROCESS | 11292 | |2;audio-dft;161;160;160;161;161;161;159;159;159;158 08:34:26 INFO - PROCESS | 11292 | |3;audio-fft;73;76;75;174;75;75;76;75;161;76 08:34:26 INFO - PROCESS | 11292 | |4;audio-oscillator;82;83;148;82;83;82;141;82;82;148 08:34:26 INFO - PROCESS | 11292 | |5;imaging-gaussian-blur;116;117;118;117;118;116;117;116;117;116 08:34:26 INFO - PROCESS | 11292 | |6;imaging-darkroom;146;146;146;146;146;146;146;145;145;146 08:34:26 INFO - PROCESS | 11292 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;96 08:34:26 INFO - PROCESS | 11292 | |8;json-parse-financial;52;50;52;49;52;50;52;51;51;50 08:34:26 INFO - PROCESS | 11292 | |9;json-stringify-tinderbox;59;59;59;60;59;59;60;59;59;59 08:34:26 INFO - PROCESS | 11292 | |10;stanford-crypto-aes;72;71;73;72;78;75;72;81;72;76 08:34:26 INFO - PROCESS | 11292 | |11;stanford-crypto-ccm;117;127;118;131;117;117;117;116;150;130 08:34:26 INFO - PROCESS | 11292 | |12;stanford-crypto-pbkdf2;151;152;155;152;152;154;151;152;149;151 08:34:26 INFO - PROCESS | 11292 | |13;stanford-crypto-sha256-iterative;61;66;61;62;62;65;62;60;65;62 08:34:26 INFO - PROCESS | 11292 | __end_tp_report 08:34:26 INFO - PROCESS | 11292 | __start_cc_report 08:34:26 INFO - PROCESS | 11292 | _x_x_mozilla_cycle_collect,1670 08:34:26 INFO - PROCESS | 11292 | __end_cc_report 08:34:26 INFO - PROCESS | 11292 | __startTimestamp1473694466342__endTimestamp 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | ------- Summary: start ------- 08:34:26 INFO - PROCESS | 11292 | Number of tests: 14 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:2.60 (2.4%) stddev-sans-first:2.74 08:34:26 INFO - PROCESS | 11292 | Values: 110.0 111.0 118.0 110.0 110.0 111.0 109.0 110.0 111.0 109.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:146.00 stddev:21.99 (15.1%) stddev-sans-first:23.03 08:34:26 INFO - PROCESS | 11292 | Values: 149.0 115.0 145.0 168.0 115.0 146.0 146.0 118.0 173.0 116.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 08:34:26 INFO - PROCESS | 11292 | Values: 161.0 160.0 160.0 161.0 161.0 161.0 159.0 159.0 159.0 158.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#3] audio-fft Cycles:10 Average:93.60 Median:75.00 stddev:39.08 (52.1%) stddev-sans-first:40.73 08:34:26 INFO - PROCESS | 11292 | Values: 73.0 76.0 75.0 174.0 75.0 75.0 76.0 75.0 161.0 76.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:82.00 stddev:30.68 (37.4%) stddev-sans-first:31.73 08:34:26 INFO - PROCESS | 11292 | Values: 82.0 83.0 148.0 82.0 83.0 82.0 141.0 82.0 82.0 148.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 08:34:26 INFO - PROCESS | 11292 | Values: 116.0 117.0 118.0 117.0 118.0 116.0 117.0 116.0 117.0 116.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:34:26 INFO - PROCESS | 11292 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 08:34:26 INFO - PROCESS | 11292 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.50 stddev:1.10 (2.1%) stddev-sans-first:1.09 08:34:26 INFO - PROCESS | 11292 | Values: 52.0 50.0 52.0 49.0 52.0 50.0 52.0 51.0 51.0 50.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 08:34:26 INFO - PROCESS | 11292 | Values: 59.0 59.0 59.0 60.0 59.0 59.0 60.0 59.0 59.0 59.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:3.26 (4.4%) stddev-sans-first:3.36 08:34:26 INFO - PROCESS | 11292 | Values: 72.0 71.0 73.0 72.0 78.0 75.0 72.0 81.0 72.0 76.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:122.50 stddev:10.88 (8.9%) stddev-sans-first:11.24 08:34:26 INFO - PROCESS | 11292 | Values: 117.0 127.0 118.0 131.0 117.0 117.0 117.0 116.0 150.0 130.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.00 stddev:1.66 (1.1%) stddev-sans-first:1.73 08:34:26 INFO - PROCESS | 11292 | Values: 151.0 152.0 155.0 152.0 152.0 154.0 151.0 152.0 149.0 151.0 08:34:26 INFO - PROCESS | 11292 | 08:34:26 INFO - PROCESS | 11292 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.00 stddev:2.01 (3.2%) stddev-sans-first:2.05 08:34:26 INFO - PROCESS | 11292 | Values: 61.0 66.0 61.0 62.0 62.0 65.0 62.0 60.0 65.0 62.0 08:34:26 INFO - PROCESS | 11292 | -------- Summary: end -------- 08:34:26 INFO - PROCESS | 11292 | 08:34:27 INFO - TEST-INFO | 11292: exit 0 08:34:27 INFO - TEST-OK | kraken | took 59935ms 08:34:27 INFO - SUITE-END | took 1173s 08:34:27 INFO - Completed test suite (00:19:33) 08:34:27 INFO - javascript benchmark 08:34:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.804111753295, 3279.7704160708754, 3309.5366648669174, 3334.299797042621, 3363.512290717846, 5690.687012214332, 5812.44781745734, 5831.4751672349485, 5846.152307915349, 5871.647701137935, 5774.82675519734, 5815.592908496407, 5822.446867547586, 5831.037878750001, 5837.9124313135335, 5101.51535604117, 5170.715610641413, 5227.712475813838, 5251.0, 5344.251804747339, 5240.633155679104, 5278.551323137538, 5294.841154765363, 5297.999999999981, 5303.840884773463, 5141.203113517392, 5172.120739474299, 5198.636095473318, 5219.19102539107, 5290.0477913975465, 5273.630845840793, 5276.208568714705, 5280.3663560373, 5287.788488460457, 5323.201519772027, 5042.470540593258, 5056.191009438472, 5065.265536497224, 5210.947890521084, 5216.686998780047, 5622.212890195376, 5641.379448260688, 5652.717364131777, 5683.204351390808, 5687.800926967537, 6164.260288765332, 6196.566240363131, 6197.16338294329, 6197.194364732556, 6206.627602343874, 5984.790532331391, 6129.756146212528, 6155.045967874987, 6205.03821907605, 6243.87512249757, 5838.9124163137585, 5852.853678658025, 5860.706964651836, 5861.179434879078, 5871.0, 6388.7125079371535, 6396.0725694774, 6398.680065996681, 6398.8720225595225, 6408.967955160288, 5842.444967728059, 5925.585209035326, 5961.0, 5969.522438204933, 6060.939390606126, 6103.824864110144, 6107.633541987495, 6135.141080248769, 6139.324674285825, 6142.692865356804, 6007.098935159674, 6060.636361818305, 6062.787802426894, 6072.574919755575, 6081.878362432726, 5996.280446346466, 6012.278526576752, 6062.636241825505, 6088.0, 6088.604240724428, 4684.531546845288, 4693.78877950493, 4712.481627021025, 4777.880552986134, 4781.904361912742, 4198.223328684203, 4204.726692764961, 4214.536400995888, 4221.261279276115, 4237.766922819213, 5898.380670029585, 5952.672603006789, 5956.493698035629, 5966.731497082642, 5969.880602387928, 5883.470487656132, 6108.129596501707, 6134.386561343919, 6153.384661533811, 6246.1255424239735, 5861.941380586225, 5916.437938395931, 5919.792807251725, 5922.200502932049, 5947.464728174485, 5200.2199670049795, 5206.062908676475, 5206.739663016834, 5212.061828870764, 5217.660852044605, 6146.631202127886, 6323.30443651198, 6378.330275321117, 6383.776567820104, 6471.094046833353, 6234.189555357774, 6234.625922444668, 6243.96878015607, 6246.531510136849, 6250.781222657367, 6410.358964103552, 6448.871022579616, 6451.870962580629, 6457.128287681291, 6463.385978332051, 5915.467607915309, 5998.190244317136, 6039.184710064261, 6052.757889684363, 6113.908291375722, 6170.722317495633, 6175.560653541916, 6207.96896015517, 6222.751089956351, 6233.875322493615, 6034.517238620988, 6075.0, 6076.361981992004, 6077.118817771487, 6110.908336374869, 5844.766209351578, 5971.552133589911, 6015.0, 6016.368281330398, 6093.725782339717, 6002.969985150046, 6013.849653758691, 6032.969835150971, 6040.305364883095, 6054.697265136813, 6035.0, 6074.908876366946, 6092.055731361646, 6113.5414843886, 6114.969425152845, 5724.141378793214, 5771.422857714195, 5879.470847623735, 5907.881842363215, 5921.940780592139], "value": 5678.600479128453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.7523302596395, 608.1577015833071, 636.1157990393355, 639.8144538083956, 640.6028262477265, 34782.826085869565, 38075.23849523008, 38095.23809523808, 38099.61900380995, 38414.42378364323, 38972.0, 47127.0, 47795.28307075397, 47944.28083578749, 51711.741441292834, 9197.356185067049, 9332.066793320648, 9332.346735728503, 9335.953320233424, 9336.393134446273, 9128.908710912872, 9220.631174753002, 9224.492652904088, 9224.953875230614, 9225.12361325673, 8575.914240857574, 11185.66443006711, 11466.197366184371, 11477.655670329861, 11863.822042669366, 11211.719707007309, 11271.943640281828, 11300.54797808095, 11309.547618095266, 11310.208285420065, 8697.434666746642, 11063.114950803916, 11132.777344453147, 11133.944330278378, 11140.610078647289, 43797.34303985443, 44631.0, 44735.0, 47530.28704569434, 47625.76187119042, 3731.067233191702, 3755.586885442599, 3901.1027463683513, 4024.4969378827645, 4072.9796351018354, 35806.82096589526, 35860.48976571641, 36001.45997810035, 36085.81957090224, 36086.458703119475, 4724.220503616899, 4742.170120228946, 4815.927761083586, 4890.462049174588, 4896.583790377822, 375803.0, 378485.0, 378653.0, 379226.10386948165, 380158.0, 63075.0, 63186.684066580296, 63370.68314658397, 63382.049269261, 63401.36598634047, 67564.0, 67574.32425675681, 67632.6618366905, 67635.66182169058, 67755.66122169358, 15863.841361586236, 16240.187990600423, 16261.430849920196, 16268.105254210896, 16272.918635406748, 12684.746305074032, 12746.171498852545, 12758.106932514635, 12777.16948398351, 12808.871911280768, 16131.435399761187, 17310.913445432692, 17974.191161397768, 18350.908245458955, 18356.357527486474, 6099.573029887954, 6281.0, 6335.176427064452, 6386.489080873519, 6466.0, 1054.5623566220086, 1119.5857532712785, 1134.0304040045808, 1152.5578260955767, 1171.262104873924, 34631.480527792104, 35094.82452587721, 35482.29035419277, 35593.64406355903, 35623.82188089043, 1200.5557943560939, 1222.7615614955175, 1233.3771445420005, 1240.2806372304042, 1334.466213514602, 18266.721329506807, 18421.355252566096, 18462.630747384977, 18859.24563017464, 18862.43412697621, 173827.0, 174983.0, 175522.0, 175757.0, 175932.0, 61034.084488732704, 62996.0, 63593.36406635967, 65560.0, 65735.67132164308, 1420.779779134236, 1421.9004669673232, 1435.798988141661, 1446.3708286895444, 1448.9565313040625, 34204.82897585496, 37319.81340093282, 37364.252714945, 37532.0, 37658.0, 55986.0, 56257.43742562522, 56491.0, 56542.43457565536, 56757.14864276956, 533511.0, 541743.0, 546910.0, 548574.0, 771370.0, 31332.37335253328, 31493.370132596763, 31518.684813151576, 31584.684153158174, 31844.203894902814, 53665.46334536605, 53687.73156134194, 54057.0, 54204.0, 54239.45760542502, 43516.78241608771, 44964.77517612391, 45066.09867802691, 45698.08603827971, 46819.76590117028, 44537.0, 45163.54836451725, 45247.773761130986, 46418.767906160254, 46795.0, 45326.54673453223, 45329.32006019978, 45432.54567454283, 45600.54399455963, 46996.7650161747, 38160.427593586675, 39494.0, 39632.801835990635, 39672.40491392689, 40158.397624035075, 29440.0, 30083.32420782244, 30394.54408183923, 30478.390432190787, 30704.232394190323], "value": 21115.504847645596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.2883096011698, 420.7896051974013, 421.81861799426235, 424.1686294862071, 425.823283337415, 15594.922025389871, 20315.99999999998, 20591.38225853225, 20738.0, 21277.0, 20793.16827326905, 20859.687104693443, 20888.477788055305, 21042.894785526052, 21293.57412851742, 5494.890102197954, 5936.7031648417405, 5964.254468191476, 5973.88052238955, 5979.701014949257, 4386.539413361583, 4398.868033958986, 5854.707264636772, 5946.910796338037, 5948.613340132899, 6564.737410503598, 6602.669866506655, 6631.469482441393, 6636.668166591675, 6645.800625981228, 5851.6781577013035, 6094.847628809315, 6116.785912493041, 6542.705578248966, 6604.504662150334, 5988.8502787430225, 6000.96999515004, 6004.039353703386, 6005.039193728982, 6012.458878700938, 22284.554308913892, 22461.887690561605, 23116.3065108047, 24720.752792472205, 24935.625965610532, 2477.7893879020303, 2601.5707408277613, 2648.1525911708263, 2737.986310068437, 2878.870450829718, 10749.355038697626, 11070.39112848793, 11151.052160566362, 11305.095592352593, 11337.0, 2015.0, 2124.0866427436194, 2203.2068455356057, 2219.3120132758895, 2298.5632729781287, 81959.0, 84963.0, 85643.0, 86073.13926860713, 89500.55249723775, 11413.201075924771, 11464.999999999833, 11724.593048834195, 11747.765044699228, 11851.762964740657, 16727.498175054767, 16736.497905062868, 16738.58153546171, 16747.748783768253, 16769.91615041917, 8157.1842815717955, 8260.752177434686, 8362.958185209034, 8384.916150838535, 8481.109483504266, 7875.133735289113, 7933.642986065641, 8078.232567906157, 8078.55567943757, 8090.312323452455, 4944.381952255968, 4993.80024799004, 5035.16919708244, 5082.263071854561, 5143.691378517301, 2064.1846470644014, 2083.499960009617, 2086.4158035750015, 2109.662454007382, 2151.8493705440783, 1013.6350913671073, 1018.6943916825071, 1026.7279171019686, 1050.106503692788, 1130.1354463835173, 20098.89950550267, 20715.585688286148, 20757.0, 21044.368668939955, 21056.894715526632, 1032.865727455441, 1044.3577200022085, 1059.6450189186555, 1066.0991462214417, 1076.7684947736275, 10900.618478353219, 11620.825687614693, 11628.418579071013, 11720.472578733807, 11760.58837940668, 524697.0, 529086.0, 532066.0, 535943.0, 541199.0, 92783.53608231916, 98161.50919245358, 99411.50294248627, 101482.49258753659, 101527.0, 996.3573495095768, 1023.6366090038078, 1036.8703912011, 1041.4948749856314, 1052.8262836632023, 17462.388816391365, 17996.280148794427, 18007.5498112543, 18707.81292187115, 18858.434246972614, 37913.810430948775, 45161.32258016067, 45198.87002824824, 46213.0, 46397.536024639325, 49770.502294976584, 58636.41363586309, 58673.0, 59602.125861818844, 60245.698771505864, 8116.918830811616, 8202.302804261466, 8399.622017009371, 8564.0, 8680.175383338572, 13226.4048117833, 13695.041347106006, 14000.299985000505, 14094.0, 14163.504277350243, 6423.903641445476, 8285.875711864206, 11236.876312368699, 11604.419779011185, 11725.179237453296, 11615.593454229062, 11668.708282292671, 11672.533098675958, 11883.702907427383, 12157.75684486323, 11733.589324373606, 12092.576759813364, 12226.327551984727, 12266.202696824677, 12374.257544547356, 8641.179087986631, 8759.0, 8839.779005524913, 9019.594118264562, 9080.40977336471, 11382.487788049391, 12382.071344649368, 12480.5007799687, 12648.557300494438, 12694.746105078031], "value": 9655.370133663715, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.5917983784702, 1544.5906834688806, 1577.5346272849508, 1722.7157519009363, 1838.8620853435996, 9017.323700722447, 9351.766205844857, 9389.201917836976, 9642.71071867845, 9685.806283874335, 9516.85724714128, 9610.711678649652, 9611.0, 9623.326367154303, 9632.084951929573, 2485.2668462803517, 2501.5997440409537, 2504.3238325652064, 2504.749525047499, 2513.409348803039, 2480.7147178074483, 2533.8859751311234, 2537.429078457351, 2545.210984594772, 2546.019782383786, 2487.825852190347, 2503.7996960243136, 2531.924042278725, 2535.1633960792988, 2541.1995221505167, 2439.0487709793106, 2463.2610216934936, 2466.0, 2471.221565206954, 2475.6039033754514, 2468.6543883856275, 2483.428811373376, 2490.2155820916355, 2491.1032028469735, 2521.810864185175, 9467.384619999679, 9578.042195780436, 9667.226621870233, 9721.17370023549, 9737.464439455827, 5741.799037033684, 5957.523398128139, 5958.702064896781, 5969.552283578705, 5980.940190598125, 3538.964610353915, 3562.9109272268142, 3582.910427239314, 3661.5423072115987, 3666.816659167031, 1173.178774857603, 1174.277819141222, 1185.4013723069882, 1188.0436248819697, 1208.957686480969, 13577.456901724017, 13586.116902401312, 13597.524086657018, 13672.726545469035, 13687.931560342235, 4917.901641967141, 4927.285543596158, 5074.112030394666, 5092.479222784746, 5113.744312784346, 10623.36259824413, 10665.680029599125, 10667.83998240027, 10669.893301067044, 10675.145988320915, 5869.941300587025, 5903.468687818031, 5911.438413350725, 5912.704364781744, 5913.142594323799, 4785.49752276006, 4788.904221915543, 5482.479164479368, 5484.122540393518, 5490.423505531941, 4275.657947364203, 4324.675649326313, 4337.221739086959, 4378.737275763465, 4383.517813040563, 1257.3398965543163, 1307.8038294255937, 1322.5701646964776, 1322.6825561865082, 1323.2854258700386, 1066.8719753629614, 1067.4822710985165, 1068.433730123036, 1079.578964203961, 1081.8214994525815, 6436.581622194542, 6545.181852268466, 6681.198256209286, 6774.559653622598, 6938.306169383122, 474.0779184486141, 474.23174457379264, 491.5649650059651, 493.1985523524273, 496.0624419846493, 4663.183942810063, 4698.01341718243, 4735.739534325577, 4782.450018247946, 4832.0, 12010.979066779422, 12314.0, 12459.06557008228, 12462.813057803958, 12496.25022498653, 11419.429028548539, 11783.76432471363, 11796.174267801343, 12207.816882746765, 12221.69445763863, 8597.699080532151, 8757.080506546848, 8786.341024423195, 8805.0, 8834.381593288537, 6838.7264509419065, 6994.510384273052, 7010.544314619533, 7133.928660713534, 7165.713371465083, 10837.0, 10996.395198263852, 11327.15046371509, 11426.485808138488, 11444.713882153013, 11376.94311528437, 11417.31496110236, 11616.825747613962, 11636.010939070275, 11693.707657308636, 8596.097409772134, 8739.12608739121, 8804.867926980973, 8807.0, 8814.382993190415, 8965.865512017195, 9255.953720231355, 9296.628134874529, 9317.39436936624, 9324.627014919599, 8313.293370063613, 8534.0, 8633.611487482953, 8654.350923680875, 8810.383273171074, 8359.474269377499, 8371.95814020926, 8480.575971201291, 8601.913980860112, 8682.479051256945, 7362.116546014298, 7483.625818708934, 7485.962570187115, 7492.550446973199, 7493.88759168602, 5621.8594535136945, 5730.140478928028, 5773.451522105393, 5811.302643682785, 5928.199693041557, 5605.80379686709, 5744.167095771173, 5747.9137812932, 5762.827115186551, 5768.394318596487], "value": 4978.891786303741, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.18639569802355, 355.16004649005095, 356.1861147278469, 359.0234561991384, 360.8899285717857, 5520.668759874406, 5550.3617084035395, 5556.611037227396, 5576.832695019151, 5627.071533197028, 5459.972700136494, 5539.889202215963, 5544.0, 5548.029094908395, 5555.861103472415, 4236.110416812466, 4307.1170410066015, 4343.717658352213, 4344.4786625604975, 4366.607005369516, 4332.913341733163, 4346.304591265398, 4350.281638752691, 4352.869413917571, 4357.651387888977, 4289.613934745872, 4290.957090429087, 4316.611504964552, 4327.221100201962, 4351.543087975764, 4175.979120104395, 4313.6980411370905, 4317.266064768997, 4343.80452879618, 4363.280058790296, 3805.7335986480957, 3808.5810560838286, 3808.9619103808886, 3810.79040652764, 3818.522684664417, 5343.973280133574, 5374.167004114369, 5399.163129714901, 5407.242985981966, 5419.783208671648, 4694.530546945312, 4899.436564795059, 4930.309756634074, 4940.48124946879, 4963.2058870580895, 5725.8568535786935, 5731.312242530881, 5737.704918032779, 5739.225204597367, 5745.0, 4172.666186705056, 4176.498820141572, 4178.623923846869, 4180.644645205161, 4186.5604111568155, 5747.482726554588, 5910.556708246899, 5929.644221346733, 5958.851028724273, 5962.940370596282, 4220.839269200995, 4228.302330115558, 4232.978835105805, 4236.216299984451, 4239.936400953989, 4294.484661840599, 4309.396684464177, 4315.978420107879, 4326.610605045561, 4348.586884245991, 4781.211100168433, 4896.25809546655, 4927.127691477216, 4972.707096154953, 4974.577160941288, 5735.770569177186, 5743.827685169452, 5745.913811292834, 5747.281589801275, 5751.827445176651, 1490.1729540105346, 1504.3456096598015, 1505.631120375515, 1505.917174555388, 1513.2585033333587, 958.4632605740808, 969.7187815533557, 983.2183414185739, 1021.3922715984127, 1035.502958579871, 5538.9169162462595, 5553.0, 5560.777568897199, 5561.304836895388, 5573.077288335518, 5860.589758716934, 5891.528677705774, 5897.056470964711, 5904.228731380568, 5920.2895652521975, 5359.1328639177855, 5608.037186984523, 5637.689927054052, 5667.319921609434, 5714.457126572969, 3109.0828205679377, 3162.2410621450686, 3166.5566820645126, 3172.904812855618, 3173.1591128351006, 5844.59087863854, 6121.846953826101, 6173.84565385869, 6187.876242475125, 6219.782307619212, 5772.711364431846, 6042.456178943916, 6054.545909056838, 6061.181645506331, 6076.149339092531, 5963.344032156459, 6102.284483404931, 6137.833811575786, 6141.84645383869, 6162.26052873649, 5955.196048533393, 5963.70181490915, 5972.924873522634, 5982.341942386247, 5995.610285331614, 5619.325680918316, 5642.266505354278, 5865.530757539472, 5875.236219291465, 5891.204687367322, 6140.17107690456, 6142.372271348007, 6143.38905964972, 6169.598976066542, 6181.381861813783, 4154.729942553724, 4237.766922819274, 4279.068837846085, 4289.099289149189, 4329.2640251156745, 5755.827325180251, 5780.826575202751, 5791.478766910998, 5817.272840894888, 5817.720101577646, 5678.4605462480995, 5702.0, 5706.715988902463, 5756.740946657326, 5779.422057794187, 5563.693996830213, 5704.974733969381, 5753.309602847517, 5807.854803629774, 5810.1575271586225, 5741.25363702716, 5752.395998420106, 5776.133579963039, 5799.391063938395, 5803.65178089316, 5634.083912525062, 5832.50423713989, 5868.501177399798, 5909.172715819875, 5926.644401335933, 5641.0, 5682.772689092555, 5714.885702285847, 5717.599768016199, 5731.770729170787], "value": 4425.024857868555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.5069181070019, 802.5706247157772, 825.372716735281, 830.389663597256, 857.8541647919853, 14779.113253204801, 15121.92439037806, 15300.923495382522, 15463.0, 16371.18144092795, 16697.0, 16758.0, 16856.747148792747, 16865.40971066013, 16885.07132107737, 717.8564287142566, 833.3374966901328, 846.2172490446328, 859.5745106172453, 863.386995233385, 864.0365991918997, 864.11859902899, 871.1897934920521, 871.9607617657189, 872.0, 915.7435917942987, 919.0763282900675, 919.3702313914989, 919.6367434863225, 919.6367434863258, 750.9324160825552, 865.5888452984782, 902.0663613160381, 916.4730280088942, 916.7708072981754, 655.1221363373055, 660.2868901586276, 661.5832025823748, 661.8279247395664, 661.8676264747022, 16321.10233937133, 16335.754963675554, 16360.672786544203, 16371.181440927907, 16392.672146557, 15313.157776322298, 15445.459408920633, 15464.0, 15493.612659683486, 15651.217439127999, 14380.0, 14554.927225363912, 14603.834280747658, 14622.926885365612, 14885.181315027668, 4760.166970780099, 4781.593564547018, 4797.64017698674, 4847.321375007502, 4868.902621947576, 82098.58950705267, 82142.58928705318, 82934.0, 83370.0, 83642.0, 11813.002264075869, 12670.936645316715, 12704.80942785859, 12711.55509557161, 12815.551455699006, 22329.665055024187, 22390.0, 22592.322230333117, 22640.433989150402, 22824.201152959566, 8620.95689521561, 8723.825523489495, 8827.337949653673, 8927.553622318857, 8930.732078037669, 5084.49155084496, 5247.790088396498, 5304.257403963448, 5320.791941092652, 5328.147496400557, 3773.509443772292, 3889.5138107736534, 3894.532656081288, 3894.7273690841935, 3900.278448486982, 584.964902105875, 668.6790340636425, 670.808819486444, 670.902719105727, 672.525869262169, 670.778643047793, 678.4233401608593, 695.9756408525778, 706.445440329339, 787.6888628991517, 10871.075958543612, 11492.827607585892, 11603.187776855708, 11645.475953582109, 11729.824052639216, 698.5983059740568, 716.8422946951662, 774.0092681367859, 796.5419883566972, 823.1932705948085, 4887.266909963463, 5123.7438128093445, 5130.281760553526, 5165.845024649267, 5172.482751724872, 67849.98225026629, 67982.0, 69194.65402672955, 105004.0, 108951.0, 94739.0, 95214.0, 97079.0, 99601.50199248958, 100192.0, 57036.0, 83485.16514834895, 83618.58190709008, 84420.0, 93936.12127757407, 9872.422081523917, 10074.345167564084, 10199.7450063749, 10452.947735261276, 10672.946635266773, 54580.90838183294, 55198.44801551933, 55548.0, 56577.0, 57569.13646295225, 70597.0, 71726.0, 79556.60221698854, 81298.0, 84956.0, 13364.0, 13953.581392558239, 14250.931180161322, 14513.346899389764, 14661.340239689453, 15901.510157755893, 16096.919515402347, 16170.67658646844, 16229.675406491568, 17188.0, 15131.167785771888, 15989.520314390587, 16168.919155404148, 16694.0, 16749.078800665593, 14720.631984200481, 15017.174055427056, 15451.61370965735, 15545.611359716098, 16089.436869709503, 14784.63038424048, 15210.08739475635, 15345.46290879814, 15367.385304587691, 15864.682706346039, 11796.823047654121, 12087.939560302493, 12102.515899363927, 12728.881424051167, 13227.338633068115, 12733.490660373853, 12759.93620031894, 12785.0, 12843.29361885068, 13360.482340759738], "value": 8100.905494374168, "unit": "score"}], "name": "dromaeo_css", "value": 7689.049037119779, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 118.0, 110.0, 110.0, 111.0, 109.0, 110.0, 111.0, 109.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 145.0, 168.0, 115.0, 146.0, 146.0, 118.0, 173.0, 116.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 159.0, 159.0, 159.0, 158.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 75.0, 174.0, 75.0, 75.0, 76.0, 75.0, 161.0, 76.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 148.0, 82.0, 83.0, 82.0, 141.0, 82.0, 82.0, 148.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 118.0, 117.0, 118.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 49.0, 52.0, 50.0, 52.0, 51.0, 51.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 73.0, 72.0, 78.0, 75.0, 72.0, 81.0, 72.0, 76.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 127.0, 118.0, 131.0, 117.0, 117.0, 117.0, 116.0, 150.0, 130.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 155.0, 152.0, 152.0, 154.0, 151.0, 152.0, 149.0, 151.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 61.0, 62.0, 62.0, 65.0, 62.0, 60.0, 65.0, 62.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1485.1999999999998, "alertThreshold": 2.0}]} 08:34:27 INFO - Return code: 0 08:34:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:34:27 INFO - # TBPL SUCCESS # 08:34:27 INFO - Running post-action listener: _resource_record_post_action 08:34:27 INFO - [mozharness: 2016-09-12 15:34:27.677223Z] Finished run-tests step (success) 08:34:27 INFO - Running post-run listener: _resource_record_post_run 08:34:27 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329293824; Read time: 12; Write time: 299620 08:34:27 INFO - TinderboxPrint: CPU usage
12.9% 08:34:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:34:27 INFO - TinderboxPrint: I/O write bytes / time
329,293,824 / 299,620 08:34:27 INFO - TinderboxPrint: CPU idle
8,270.9 (87.1%) 08:34:27 INFO - TinderboxPrint: CPU user
1,181.2 (12.4%) 08:34:27 INFO - TinderboxPrint: Swap in / out
0 / 0 08:34:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77201408; Read time: 0; Write time: 104888 08:34:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249696256; Read time: 0; Write time: 194484 08:34:28 INFO - Running post-run listener: _upload_blobber_files 08:34:28 INFO - Blob upload gear active. 08:34:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:34:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:34:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:34:28 INFO - (blobuploader) - INFO - Open directory for files ... 08:34:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:34:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:34:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:35 INFO - (blobuploader) - INFO - Done attempting. 08:34:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:34:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:34:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:36 INFO - (blobuploader) - INFO - Done attempting. 08:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:34:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:34:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:34:37 INFO - (blobuploader) - INFO - Done attempting. 08:34:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:34:37 INFO - Return code: 0 08:34:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:34:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:34:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d97795e1a7dfa35ada66540abfe45b2746899fff050fad82c4c6c70e099c79b897089b05262bd5f7c34c0c6ac79b146c7f6374c059636e4a27a816b927e45970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902aa7003bc607b2f171a6efe1e7c898b57e57fcc0c62d782240bf3afd4a9bf67848ead0eb251b9fe4efbcddda70e6a5009cbc8ab8300b044bda9b086ae2c5ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f11ec5226039153a3f24292b5b92f2d5f45a52f11bfba1fef3043fe9fb14577a6c0e95c1e1137f695261cfb1a7e0d6508913c1700a002799e454bbc9335639"} 08:34:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:34:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:34:37 INFO - Contents: 08:34:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d97795e1a7dfa35ada66540abfe45b2746899fff050fad82c4c6c70e099c79b897089b05262bd5f7c34c0c6ac79b146c7f6374c059636e4a27a816b927e45970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902aa7003bc607b2f171a6efe1e7c898b57e57fcc0c62d782240bf3afd4a9bf67848ead0eb251b9fe4efbcddda70e6a5009cbc8ab8300b044bda9b086ae2c5ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f11ec5226039153a3f24292b5b92f2d5f45a52f11bfba1fef3043fe9fb14577a6c0e95c1e1137f695261cfb1a7e0d6508913c1700a002799e454bbc9335639"} 08:34:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:34:37 INFO - Copying logs to upload dir... 08:34:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:34:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.611944 ========= master_lag: 1.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 08:34:38.303943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 08:34:38.312393) ========= 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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 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/d97795e1a7dfa35ada66540abfe45b2746899fff050fad82c4c6c70e099c79b897089b05262bd5f7c34c0c6ac79b146c7f6374c059636e4a27a816b927e45970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902aa7003bc607b2f171a6efe1e7c898b57e57fcc0c62d782240bf3afd4a9bf67848ead0eb251b9fe4efbcddda70e6a5009cbc8ab8300b044bda9b086ae2c5ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f11ec5226039153a3f24292b5b92f2d5f45a52f11bfba1fef3043fe9fb14577a6c0e95c1e1137f695261cfb1a7e0d6508913c1700a002799e454bbc9335639"} build_url:https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/KGqY64usSHCitSXpwcabXA/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/d97795e1a7dfa35ada66540abfe45b2746899fff050fad82c4c6c70e099c79b897089b05262bd5f7c34c0c6ac79b146c7f6374c059636e4a27a816b927e45970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/902aa7003bc607b2f171a6efe1e7c898b57e57fcc0c62d782240bf3afd4a9bf67848ead0eb251b9fe4efbcddda70e6a5009cbc8ab8300b044bda9b086ae2c5ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57f11ec5226039153a3f24292b5b92f2d5f45a52f11bfba1fef3043fe9fb14577a6c0e95c1e1137f695261cfb1a7e0d6508913c1700a002799e454bbc9335639"}' ========= master_lag: 0.67 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 08:34:38.990491) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:34:38.990811) ========= 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=32c5991c409366f7fec8a718000001d2-1473692742.198596-1903907729 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 08:34:39.058638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 08:34:39.059021) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 08:34:39.062286) ========= ========= Total master_lag: 2.76 =========