builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-039 starttime: 1473331953.99 results: success (0) buildid: 20160908004007 builduid: 82f0ced6ecf24a738d84f85ff89b40ea revision: 7d3d4f307002cc8475800df191196b94999ffd0a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:33.988661) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:33.989084) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:33.989353) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.035417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.035677) ========= 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=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 03:52:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2016-09-08 03:52:34 (50.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081645 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.130957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.131271) ========= 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=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020608 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.182301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.182612) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7d3d4f307002cc8475800df191196b94999ffd0a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7d3d4f307002cc8475800df191196b94999ffd0a --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 03:52:34,379 truncating revision to first 12 chars 2016-09-08 03:52:34,379 Setting DEBUG logging. 2016-09-08 03:52:34,379 attempt 1/10 2016-09-08 03:52:34,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7d3d4f307002?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 03:52:34,692 unpacking tar archive at: mozilla-aurora-7d3d4f307002/testing/mozharness/ program finished with exit code 0 elapsedTime=0.746944 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.943436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.943747) ========= script_repo_revision: 7d3d4f307002cc8475800df191196b94999ffd0a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.944182) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.944474) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 03:52:34.955885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-08 03:52:34.956310) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:52:35 INFO - MultiFileLogger online at 20160908 03:52:35 in /builds/slave/test 03:52:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:52:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:52:35 INFO - {'append_to_log': False, 03:52:35 INFO - 'base_work_dir': '/builds/slave/test', 03:52:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:52:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:52:35 INFO - 'branch': 'Mozilla-Aurora', 03:52:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:52:35 INFO - 'config_files': ('talos/linux_config.py',), 03:52:35 INFO - 'default_actions': ('clobber', 03:52:35 INFO - 'read-buildbot-config', 03:52:35 INFO - 'download-and-extract', 03:52:35 INFO - 'populate-webroot', 03:52:35 INFO - 'create-virtualenv', 03:52:35 INFO - 'install', 03:52:35 INFO - 'run-tests'), 03:52:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:52:35 INFO - 'download_minidump_stackwalk': True, 03:52:35 INFO - 'download_symbols': 'ondemand', 03:52:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:52:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:52:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:52:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:52:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:52:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:52:35 INFO - 'installer_path': 'installer.exe', 03:52:35 INFO - 'log_level': 'info', 03:52:35 INFO - 'log_name': 'talos', 03:52:35 INFO - 'log_to_console': True, 03:52:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:52:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:52:35 INFO - 'opt_config_files': (), 03:52:35 INFO - 'pip_index': False, 03:52:35 INFO - 'sps_profile': False, 03:52:35 INFO - 'sps_profile_interval': 0, 03:52:35 INFO - 'suite': 'dromaeojs-e10s', 03:52:35 INFO - 'system_bits': '32', 03:52:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:52:35 INFO - 'title': 'talos-linux64-ix-039', 03:52:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:52:35 INFO - 'use_talos_json': True, 03:52:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:52:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:52:35 INFO - 'work_dir': 'build'} 03:52:35 INFO - [mozharness: 2016-09-08 10:52:35.245846Z] Running clobber step. 03:52:35 INFO - Running pre-action listener: _resource_record_pre_action 03:52:35 INFO - Running main action method: clobber 03:52:35 INFO - rmtree: /builds/slave/test/build 03:52:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:52:35 INFO - Running post-action listener: _resource_record_post_action 03:52:35 INFO - [mozharness: 2016-09-08 10:52:35.696904Z] Finished clobber step (success) 03:52:35 INFO - [mozharness: 2016-09-08 10:52:35.697005Z] Running read-buildbot-config step. 03:52:35 INFO - Running pre-action listener: _resource_record_pre_action 03:52:35 INFO - Running main action method: read_buildbot_config 03:52:35 INFO - Using buildbot properties: 03:52:35 INFO - { 03:52:35 INFO - "project": "", 03:52:35 INFO - "product": "firefox", 03:52:35 INFO - "script_repo_revision": "production", 03:52:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:52:35 INFO - "repository": "", 03:52:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:52:35 INFO - "buildid": "20160908004007", 03:52:35 INFO - "stage_platform": "linux64-pgo", 03:52:35 INFO - "basedir": "/builds/slave/test", 03:52:35 INFO - "nightly_build": "True", 03:52:35 INFO - "buildnumber": 492, 03:52:35 INFO - "slavename": "talos-linux64-ix-039", 03:52:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:52:35 INFO - "platform": "ubuntu64_hw", 03:52:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:52:35 INFO - "branch": "mozilla-aurora", 03:52:35 INFO - "repo_path": "releases/mozilla-aurora", 03:52:35 INFO - "revision": "7d3d4f307002cc8475800df191196b94999ffd0a", 03:52:35 INFO - "pgo_build": "True", 03:52:35 INFO - "builduid": "82f0ced6ecf24a738d84f85ff89b40ea", 03:52:35 INFO - "slavebuilddir": "test" 03:52:35 INFO - } 03:52:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:52:35 INFO - Running post-action listener: _resource_record_post_action 03:52:35 INFO - [mozharness: 2016-09-08 10:52:35.699516Z] Finished read-buildbot-config step (success) 03:52:35 INFO - [mozharness: 2016-09-08 10:52:35.699604Z] Running download-and-extract step. 03:52:35 INFO - Running pre-action listener: _resource_record_pre_action 03:52:35 INFO - Running main action method: download_and_extract 03:52:35 INFO - mkdir: /builds/slave/test/build/tests 03:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:35 INFO - https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:52:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:52:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:52:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:52:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:52:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:52:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:52:35 INFO - retry: Failed, sleeping 30 seconds before retrying 03:53:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:53:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:53:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:53:05 INFO - retry: Failed, sleeping 60 seconds before retrying 03:54:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:54:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:54:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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! 03:54:05 INFO - Caught exception: HTTP Error 404: Not Found 03:54:05 INFO - Caught exception: HTTP Error 404: Not Found 03:54:05 INFO - Caught exception: HTTP Error 404: Not Found 03:54:05 INFO - trying https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:54:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:06 INFO - Downloaded 1361 bytes. 03:54:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:54:06 INFO - Using the following test package requirements: 03:54:06 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:54:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:54:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:54:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'jsshell-linux-x86_64.zip'], 03:54:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:54:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:54:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:54:06 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:54:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:54:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:54:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:54:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:54:06 INFO - https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:54:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:54:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:54:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:54:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:54:06 INFO - retry: Failed, sleeping 30 seconds before retrying 03:54:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:54:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:54:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:54:36 INFO - retry: Failed, sleeping 60 seconds before retrying 03:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:55:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:55:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:55:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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! 03:55:36 INFO - Caught exception: HTTP Error 404: Not Found 03:55:36 INFO - Caught exception: HTTP Error 404: Not Found 03:55:36 INFO - Caught exception: HTTP Error 404: Not Found 03:55:36 INFO - trying https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:55:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:55:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:55:37 INFO - Downloaded 21768887 bytes. 03:55:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:55:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:55:38 INFO - Return code: 0 03:55:38 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 03:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:55:38 INFO - https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:55:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:55:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:55:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:55:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:55:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:55:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:56:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:56:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:56:08 INFO - retry: Failed, sleeping 60 seconds before retrying 03:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:57:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:57:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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! 03:57:08 INFO - Caught exception: HTTP Error 404: Not Found 03:57:08 INFO - Caught exception: HTTP Error 404: Not Found 03:57:08 INFO - Caught exception: HTTP Error 404: Not Found 03:57:08 INFO - trying https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:57:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:09 INFO - Downloaded 21768887 bytes. 03:57:09 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'] 03:57:09 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 03:57:10 INFO - Return code: 0 03:57:10 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')]} 03:57:10 INFO - https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:57:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:57:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:57:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:57:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:57:10 INFO - retry: Failed, sleeping 30 seconds before retrying 03:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:57:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:57:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:57:40 INFO - retry: Failed, sleeping 60 seconds before retrying 03:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:58:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:58:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:58:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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! 03:58:41 INFO - Caught exception: HTTP Error 404: Not Found 03:58:41 INFO - Caught exception: HTTP Error 404: Not Found 03:58:41 INFO - Caught exception: HTTP Error 404: Not Found 03:58:41 INFO - trying https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:58:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:58:41 INFO - Downloaded 14413740 bytes. 03:58:41 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'] 03:58:41 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 03:58:41 INFO - Return code: 0 03:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:42 INFO - https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:58:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:58:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:58:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:58:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:58:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:58:42 INFO - retry: Failed, sleeping 30 seconds before retrying 03:59:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/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 03:59:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:59:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:59:12 INFO - retry: Failed, sleeping 60 seconds before retrying 04:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:00:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:00:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:00:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 04:00:12 INFO - Caught exception: HTTP Error 404: Not Found 04:00:12 INFO - Caught exception: HTTP Error 404: Not Found 04:00:12 INFO - Caught exception: HTTP Error 404: Not Found 04:00:12 INFO - trying https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:00:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:00:13 INFO - Downloaded 63902179 bytes. 04:00:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:00:13 INFO - mkdir: /builds/slave/test/properties 04:00:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:00:13 INFO - Writing to file /builds/slave/test/properties/build_url 04:00:13 INFO - Contents: 04:00:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:00:14 INFO - Running post-action listener: _resource_record_post_action 04:00:14 INFO - Running post-action listener: set_extra_try_arguments 04:00:14 INFO - [mozharness: 2016-09-08 11:00:14.301528Z] Finished download-and-extract step (success) 04:00:14 INFO - [mozharness: 2016-09-08 11:00:14.301763Z] Running populate-webroot step. 04:00:14 INFO - Running pre-action listener: _resource_record_pre_action 04:00:14 INFO - Running main action method: populate_webroot 04:00:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:00:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:00:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:00:14 INFO - '--tppagecycles', 04:00:14 INFO - '7'], 04:00:14 INFO - 'tests': ['tsvgm']}}, 04:00:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:00:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:00:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:00:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:00:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['damp', 'tps']}, 04:00:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'tests': ['damp', 'tps']}, 04:00:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['dromaeo_dom']}, 04:00:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:00:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['basic_compositor_video']}, 04:00:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:00:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'other-e10s': {'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['a11yr', 04:00:14 INFO - 'ts_paint', 04:00:14 INFO - 'tpaint', 04:00:14 INFO - 'sessionrestore', 04:00:14 INFO - 'sessionrestore_no_auto_restore', 04:00:14 INFO - 'tabpaint']}, 04:00:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['tsvgx', 04:00:14 INFO - 'tsvgr_opacity', 04:00:14 INFO - 'tart', 04:00:14 INFO - 'tscrollx', 04:00:14 INFO - 'cart']}, 04:00:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:00:14 INFO - 'tsvgr_opacity', 04:00:14 INFO - 'tart', 04:00:14 INFO - 'tscrollx', 04:00:14 INFO - 'cart']}, 04:00:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'talos_options': ['--disable-e10s'], 04:00:14 INFO - 'tests': ['tp5o']}, 04:00:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'tests': ['tp5o']}, 04:00:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'talos_options': ['--disable-e10s', 04:00:14 INFO - '--xperf_path', 04:00:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:14 INFO - 'tests': ['tp5n']}, 04:00:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:14 INFO - 'talos_options': ['--xperf_path', 04:00:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:14 INFO - 'tests': ['tp5n']}}, 04:00:14 INFO - 'talos.zip': {'path': '', 04:00:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:00:14 INFO - Running post-action listener: _resource_record_post_action 04:00:14 INFO - [mozharness: 2016-09-08 11:00:14.324593Z] Finished populate-webroot step (success) 04:00:14 INFO - [mozharness: 2016-09-08 11:00:14.324683Z] Running create-virtualenv step. 04:00:14 INFO - Running pre-action listener: _resource_record_pre_action 04:00:14 INFO - Running main action method: create_virtualenv 04:00:14 INFO - Creating virtualenv /builds/slave/test/build/venv 04:00:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:00:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:00:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:00:14 INFO - Using real prefix '/usr' 04:00:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:00:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:16 INFO - Installing pip.................done. 04:00:16 INFO - Return code: 0 04:00:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:16 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 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:00:16 INFO - Using env: {'DISPLAY': ':0', 04:00:16 INFO - 'HOME': '/home/cltbld', 04:00:16 INFO - 'LANG': 'en_US.UTF-8', 04:00:16 INFO - 'LANGUAGE': 'en_US:en', 04:00:16 INFO - 'LOGNAME': 'cltbld', 04:00:16 INFO - 'MAIL': '/var/mail/cltbld', 04:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:16 INFO - 'MOZ_NO_REMOTE': '1', 04:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:16 INFO - 'NO_EM_RESTART': '1', 04:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:16 INFO - 'PWD': '/builds/slave/test', 04:00:16 INFO - 'SHELL': '/bin/bash', 04:00:16 INFO - 'SHLVL': '1', 04:00:16 INFO - 'TERM': 'linux', 04:00:16 INFO - 'TMOUT': '86400', 04:00:16 INFO - 'USER': 'cltbld', 04:00:16 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:16 INFO - '_': '/tools/buildbot/bin/python'} 04:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:00:17 INFO - Cleaning up... 04:00:17 INFO - Return code: 0 04:00:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:00:17 INFO - Using env: {'DISPLAY': ':0', 04:00:17 INFO - 'HOME': '/home/cltbld', 04:00:17 INFO - 'LANG': 'en_US.UTF-8', 04:00:17 INFO - 'LANGUAGE': 'en_US:en', 04:00:17 INFO - 'LOGNAME': 'cltbld', 04:00:17 INFO - 'MAIL': '/var/mail/cltbld', 04:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:17 INFO - 'MOZ_NO_REMOTE': '1', 04:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:17 INFO - 'NO_EM_RESTART': '1', 04:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:17 INFO - 'PWD': '/builds/slave/test', 04:00:17 INFO - 'SHELL': '/bin/bash', 04:00:17 INFO - 'SHLVL': '1', 04:00:17 INFO - 'TERM': 'linux', 04:00:17 INFO - 'TMOUT': '86400', 04:00:17 INFO - 'USER': 'cltbld', 04:00:17 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:17 INFO - '_': '/tools/buildbot/bin/python'} 04:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:17 INFO - Downloading/unpacking psutil>=3.1.1 04:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:19 INFO - Installing collected packages: psutil 04:00:19 INFO - Running setup.py install for psutil 04:00:19 INFO - building 'psutil._psutil_linux' extension 04:00:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:00:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:00:19 INFO - building 'psutil._psutil_posix' extension 04:00:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:00:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:00:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:19 INFO - Successfully installed psutil 04:00:19 INFO - Cleaning up... 04:00:19 INFO - Return code: 0 04:00:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:00:19 INFO - Using env: {'DISPLAY': ':0', 04:00:19 INFO - 'HOME': '/home/cltbld', 04:00:19 INFO - 'LANG': 'en_US.UTF-8', 04:00:19 INFO - 'LANGUAGE': 'en_US:en', 04:00:19 INFO - 'LOGNAME': 'cltbld', 04:00:19 INFO - 'MAIL': '/var/mail/cltbld', 04:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:19 INFO - 'MOZ_NO_REMOTE': '1', 04:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:19 INFO - 'NO_EM_RESTART': '1', 04:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:19 INFO - 'PWD': '/builds/slave/test', 04:00:19 INFO - 'SHELL': '/bin/bash', 04:00:19 INFO - 'SHLVL': '1', 04:00:19 INFO - 'TERM': 'linux', 04:00:19 INFO - 'TMOUT': '86400', 04:00:19 INFO - 'USER': 'cltbld', 04:00:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:19 INFO - '_': '/tools/buildbot/bin/python'} 04:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:00:22 INFO - Installing collected packages: mozsystemmonitor 04:00:22 INFO - Running setup.py install for mozsystemmonitor 04:00:22 INFO - Successfully installed mozsystemmonitor 04:00:22 INFO - Cleaning up... 04:00:22 INFO - Return code: 0 04:00:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:00:22 INFO - Using env: {'DISPLAY': ':0', 04:00:22 INFO - 'HOME': '/home/cltbld', 04:00:22 INFO - 'LANG': 'en_US.UTF-8', 04:00:22 INFO - 'LANGUAGE': 'en_US:en', 04:00:22 INFO - 'LOGNAME': 'cltbld', 04:00:22 INFO - 'MAIL': '/var/mail/cltbld', 04:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:22 INFO - 'MOZ_NO_REMOTE': '1', 04:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:22 INFO - 'NO_EM_RESTART': '1', 04:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:22 INFO - 'PWD': '/builds/slave/test', 04:00:22 INFO - 'SHELL': '/bin/bash', 04:00:22 INFO - 'SHLVL': '1', 04:00:22 INFO - 'TERM': 'linux', 04:00:22 INFO - 'TMOUT': '86400', 04:00:22 INFO - 'USER': 'cltbld', 04:00:22 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:22 INFO - '_': '/tools/buildbot/bin/python'} 04:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:22 INFO - Downloading/unpacking jsonschema==2.5.1 04:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:00:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:24 INFO - Downloading functools32-3.2.3-2.tar.gz 04:00:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:00:24 INFO - warning: no files found matching '*.txt' 04:00:24 INFO - no previously-included directories found matching 'build' 04:00:24 INFO - no previously-included directories found matching 'dist' 04:00:24 INFO - no previously-included directories found matching '.git*' 04:00:24 INFO - Installing collected packages: jsonschema, functools32 04:00:24 INFO - Running setup.py install for functools32 04:00:24 INFO - warning: no files found matching '*.txt' 04:00:24 INFO - no previously-included directories found matching 'build' 04:00:24 INFO - no previously-included directories found matching 'dist' 04:00:24 INFO - no previously-included directories found matching '.git*' 04:00:24 INFO - Successfully installed jsonschema functools32 04:00:24 INFO - Cleaning up... 04:00:24 INFO - Return code: 0 04:00:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:00:24 INFO - Using env: {'DISPLAY': ':0', 04:00:24 INFO - 'HOME': '/home/cltbld', 04:00:24 INFO - 'LANG': 'en_US.UTF-8', 04:00:24 INFO - 'LANGUAGE': 'en_US:en', 04:00:24 INFO - 'LOGNAME': 'cltbld', 04:00:24 INFO - 'MAIL': '/var/mail/cltbld', 04:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:24 INFO - 'MOZ_NO_REMOTE': '1', 04:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:24 INFO - 'NO_EM_RESTART': '1', 04:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:24 INFO - 'PWD': '/builds/slave/test', 04:00:24 INFO - 'SHELL': '/bin/bash', 04:00:24 INFO - 'SHLVL': '1', 04:00:24 INFO - 'TERM': 'linux', 04:00:24 INFO - 'TMOUT': '86400', 04:00:24 INFO - 'USER': 'cltbld', 04:00:24 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:24 INFO - '_': '/tools/buildbot/bin/python'} 04:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:00:24 INFO - Cleaning up... 04:00:24 INFO - Return code: 0 04:00:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:00:24 INFO - Using env: {'DISPLAY': ':0', 04:00:24 INFO - 'HOME': '/home/cltbld', 04:00:24 INFO - 'LANG': 'en_US.UTF-8', 04:00:24 INFO - 'LANGUAGE': 'en_US:en', 04:00:24 INFO - 'LOGNAME': 'cltbld', 04:00:24 INFO - 'MAIL': '/var/mail/cltbld', 04:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:24 INFO - 'MOZ_NO_REMOTE': '1', 04:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:24 INFO - 'NO_EM_RESTART': '1', 04:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:24 INFO - 'PWD': '/builds/slave/test', 04:00:24 INFO - 'SHELL': '/bin/bash', 04:00:24 INFO - 'SHLVL': '1', 04:00:24 INFO - 'TERM': 'linux', 04:00:24 INFO - 'TMOUT': '86400', 04:00:24 INFO - 'USER': 'cltbld', 04:00:24 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:24 INFO - '_': '/tools/buildbot/bin/python'} 04:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:25 INFO - Downloading/unpacking blobuploader==1.2.4 04:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:00:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:00:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:27 INFO - Downloading docopt-0.6.1.tar.gz 04:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:00:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:00:27 INFO - Running setup.py install for blobuploader 04:00:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:00:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:00:27 INFO - Running setup.py install for requests 04:00:28 INFO - Running setup.py install for docopt 04:00:28 INFO - Successfully installed blobuploader requests docopt 04:00:28 INFO - Cleaning up... 04:00:28 INFO - Return code: 0 04:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:00:28 INFO - Using env: {'DISPLAY': ':0', 04:00:28 INFO - 'HOME': '/home/cltbld', 04:00:28 INFO - 'LANG': 'en_US.UTF-8', 04:00:28 INFO - 'LANGUAGE': 'en_US:en', 04:00:28 INFO - 'LOGNAME': 'cltbld', 04:00:28 INFO - 'MAIL': '/var/mail/cltbld', 04:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:28 INFO - 'MOZ_NO_REMOTE': '1', 04:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:28 INFO - 'NO_EM_RESTART': '1', 04:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:28 INFO - 'PWD': '/builds/slave/test', 04:00:28 INFO - 'SHELL': '/bin/bash', 04:00:28 INFO - 'SHLVL': '1', 04:00:28 INFO - 'TERM': 'linux', 04:00:28 INFO - 'TMOUT': '86400', 04:00:28 INFO - 'USER': 'cltbld', 04:00:28 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:28 INFO - '_': '/tools/buildbot/bin/python'} 04:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:28 INFO - Running setup.py (path:/tmp/pip-_SjlNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:28 INFO - Running setup.py (path:/tmp/pip-hOWUNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:28 INFO - Running setup.py (path:/tmp/pip-RMQm2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:28 INFO - Running setup.py (path:/tmp/pip-l6NRzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:28 INFO - Running setup.py (path:/tmp/pip-KLgwEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:28 INFO - Running setup.py (path:/tmp/pip-i7bg_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:29 INFO - Running setup.py (path:/tmp/pip-cwUf3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:29 INFO - Running setup.py (path:/tmp/pip-UhZd8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:29 INFO - Running setup.py (path:/tmp/pip-2ZMEs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:29 INFO - Running setup.py (path:/tmp/pip-4Brfja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:29 INFO - Running setup.py (path:/tmp/pip-LTZPf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:29 INFO - Running setup.py (path:/tmp/pip-8WuT28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:29 INFO - Running setup.py (path:/tmp/pip-GvNsgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:29 INFO - Running setup.py (path:/tmp/pip-CMXlfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:29 INFO - Running setup.py (path:/tmp/pip-wt1IJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:29 INFO - Running setup.py (path:/tmp/pip-jwBZqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:29 INFO - Running setup.py (path:/tmp/pip-Q09mul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:00:29 INFO - Running setup.py install for manifestparser 04:00:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:00:29 INFO - Running setup.py install for mozcrash 04:00:29 INFO - Running setup.py install for mozdebug 04:00:30 INFO - Running setup.py install for mozdevice 04:00:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for mozfile 04:00:30 INFO - Running setup.py install for mozhttpd 04:00:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for mozinfo 04:00:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for mozInstall 04:00:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for mozleak 04:00:30 INFO - Running setup.py install for mozlog 04:00:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for moznetwork 04:00:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:00:30 INFO - Running setup.py install for mozprocess 04:00:31 INFO - Running setup.py install for mozprofile 04:00:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:00:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:00:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:00:31 INFO - Running setup.py install for mozrunner 04:00:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:00:31 INFO - Running setup.py install for mozscreenshot 04:00:31 INFO - Running setup.py install for moztest 04:00:31 INFO - Running setup.py install for mozversion 04:00:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:00:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:00:31 INFO - Cleaning up... 04:00:31 INFO - Return code: 0 04:00:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:00:31 INFO - Using env: {'DISPLAY': ':0', 04:00:31 INFO - 'HOME': '/home/cltbld', 04:00:31 INFO - 'LANG': 'en_US.UTF-8', 04:00:31 INFO - 'LANGUAGE': 'en_US:en', 04:00:31 INFO - 'LOGNAME': 'cltbld', 04:00:31 INFO - 'MAIL': '/var/mail/cltbld', 04:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:31 INFO - 'MOZ_NO_REMOTE': '1', 04:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:31 INFO - 'NO_EM_RESTART': '1', 04:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:31 INFO - 'PWD': '/builds/slave/test', 04:00:31 INFO - 'SHELL': '/bin/bash', 04:00:31 INFO - 'SHLVL': '1', 04:00:31 INFO - 'TERM': 'linux', 04:00:31 INFO - 'TMOUT': '86400', 04:00:31 INFO - 'USER': 'cltbld', 04:00:31 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:31 INFO - '_': '/tools/buildbot/bin/python'} 04:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:31 INFO - Running setup.py (path:/tmp/pip-eRFHwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:31 INFO - Running setup.py (path:/tmp/pip-qJ9xd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:31 INFO - Running setup.py (path:/tmp/pip-h3gZRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:32 INFO - Running setup.py (path:/tmp/pip-HpHXmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:32 INFO - Running setup.py (path:/tmp/pip-ZbaSX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:32 INFO - Running setup.py (path:/tmp/pip-ynGmQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:32 INFO - Running setup.py (path:/tmp/pip-rSE5eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:32 INFO - Running setup.py (path:/tmp/pip-oH8Ybp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:32 INFO - Running setup.py (path:/tmp/pip-ozuB83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:32 INFO - Running setup.py (path:/tmp/pip-iD6lVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:32 INFO - Running setup.py (path:/tmp/pip-tM22RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:32 INFO - Running setup.py (path:/tmp/pip-uIqgtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:32 INFO - Running setup.py (path:/tmp/pip-uSFB5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:32 INFO - Running setup.py (path:/tmp/pip-klhqqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:32 INFO - Running setup.py (path:/tmp/pip-mwY7XJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:32 INFO - Running setup.py (path:/tmp/pip-emoX58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:00:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:32 INFO - Running setup.py (path:/tmp/pip-jsjMKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:00:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:34 INFO - Downloading blessings-1.6.tar.gz 04:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:00:35 INFO - Installing collected packages: blessings 04:00:35 INFO - Running setup.py install for blessings 04:00:35 INFO - Successfully installed blessings 04:00:35 INFO - Cleaning up... 04:00:35 INFO - Return code: 0 04:00:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:35 INFO - Reading from file tmpfile_stdout 04:00:35 INFO - Current package versions: 04:00:35 INFO - argparse == 1.2.1 04:00:35 INFO - blessings == 1.6 04:00:35 INFO - blobuploader == 1.2.4 04:00:35 INFO - docopt == 0.6.1 04:00:35 INFO - functools32 == 3.2.3-2 04:00:35 INFO - jsonschema == 2.5.1 04:00:35 INFO - manifestparser == 1.1 04:00:35 INFO - mozInstall == 1.12 04:00:35 INFO - mozcrash == 0.17 04:00:35 INFO - mozdebug == 0.1 04:00:35 INFO - mozdevice == 0.48 04:00:35 INFO - mozfile == 1.2 04:00:35 INFO - mozhttpd == 0.7 04:00:35 INFO - mozinfo == 0.9 04:00:35 INFO - mozleak == 0.1 04:00:35 INFO - mozlog == 3.3 04:00:35 INFO - moznetwork == 0.27 04:00:35 INFO - mozprocess == 0.23 04:00:35 INFO - mozprofile == 0.28 04:00:35 INFO - mozrunner == 6.12 04:00:35 INFO - mozscreenshot == 0.1 04:00:35 INFO - mozsystemmonitor == 0.3 04:00:35 INFO - moztest == 0.7 04:00:35 INFO - mozversion == 1.4 04:00:35 INFO - psutil == 3.1.1 04:00:35 INFO - requests == 1.2.3 04:00:35 INFO - wsgiref == 0.1.2 04:00:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:00:35 INFO - Using env: {'DISPLAY': ':0', 04:00:35 INFO - 'HOME': '/home/cltbld', 04:00:35 INFO - 'LANG': 'en_US.UTF-8', 04:00:35 INFO - 'LANGUAGE': 'en_US:en', 04:00:35 INFO - 'LOGNAME': 'cltbld', 04:00:35 INFO - 'MAIL': '/var/mail/cltbld', 04:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:35 INFO - 'MOZ_NO_REMOTE': '1', 04:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:35 INFO - 'NO_EM_RESTART': '1', 04:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:35 INFO - 'PWD': '/builds/slave/test', 04:00:35 INFO - 'SHELL': '/bin/bash', 04:00:35 INFO - 'SHLVL': '1', 04:00:35 INFO - 'TERM': 'linux', 04:00:35 INFO - 'TMOUT': '86400', 04:00:35 INFO - 'USER': 'cltbld', 04:00:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:35 INFO - '_': '/tools/buildbot/bin/python'} 04:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:00:35 INFO - Cleaning up... 04:00:35 INFO - Return code: 0 04:00:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd23030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe868a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf52400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, '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': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:00:35 INFO - Using env: {'DISPLAY': ':0', 04:00:35 INFO - 'HOME': '/home/cltbld', 04:00:35 INFO - 'LANG': 'en_US.UTF-8', 04:00:35 INFO - 'LANGUAGE': 'en_US:en', 04:00:35 INFO - 'LOGNAME': 'cltbld', 04:00:35 INFO - 'MAIL': '/var/mail/cltbld', 04:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:35 INFO - 'MOZ_NO_REMOTE': '1', 04:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:35 INFO - 'NO_EM_RESTART': '1', 04:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:35 INFO - 'PWD': '/builds/slave/test', 04:00:35 INFO - 'SHELL': '/bin/bash', 04:00:35 INFO - 'SHLVL': '1', 04:00:35 INFO - 'TERM': 'linux', 04:00:35 INFO - 'TMOUT': '86400', 04:00:35 INFO - 'USER': 'cltbld', 04:00:35 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:35 INFO - '_': '/tools/buildbot/bin/python'} 04:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:00:35 INFO - Cleaning up... 04:00:35 INFO - Return code: 0 04:00:35 INFO - Running post-action listener: _resource_record_post_action 04:00:35 INFO - Running post-action listener: _start_resource_monitoring 04:00:35 INFO - Starting resource monitoring. 04:00:35 INFO - [mozharness: 2016-09-08 11:00:35.883594Z] Finished create-virtualenv step (success) 04:00:35 INFO - [mozharness: 2016-09-08 11:00:35.884023Z] Running install step. 04:00:35 INFO - Running pre-action listener: _resource_record_pre_action 04:00:35 INFO - Running main action method: install 04:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:36 INFO - Reading from file tmpfile_stdout 04:00:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:00:36 INFO - Reading from file tmpfile_stdout 04:00:36 INFO - Output received: 04:00:36 INFO - Usage: mozinstall [options] installer 04:00:36 INFO - Options: 04:00:36 INFO - -h, --help show this help message and exit 04:00:36 INFO - -d DEST, --destination=DEST 04:00:36 INFO - Directory to install application into. [default: 04:00:36 INFO - "/builds/slave/test"] 04:00:36 INFO - --app=APP Application being installed. [default: firefox] 04:00:36 INFO - mkdir: /builds/slave/test/build/application 04:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:00:52 INFO - Reading from file tmpfile_stdout 04:00:52 INFO - Output received: 04:00:52 INFO - /builds/slave/test/build/application/firefox/firefox 04:00:52 INFO - Running post-action listener: _resource_record_post_action 04:00:52 INFO - [mozharness: 2016-09-08 11:00:52.199686Z] Finished install step (success) 04:00:52 INFO - [mozharness: 2016-09-08 11:00:52.199962Z] Running run-tests step. 04:00:52 INFO - Running pre-action listener: _resource_record_pre_action 04:00:52 INFO - Running main action method: run_tests 04:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:00:52 INFO - Python 2.7.3 04:00:52 INFO - Return code: 0 04:00:52 INFO - grabbing minidump binary from tooltool 04:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:52 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 0xfe1570>, '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 0xfe2470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfe2900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:00:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:00:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:00:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:00:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:00:52 INFO - Return code: 0 04:00:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:00:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:00:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:00:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:00:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:00:52 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:00:52 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:00:52 INFO - Using env: {'DISPLAY': ':0', 04:00:52 INFO - 'HOME': '/home/cltbld', 04:00:52 INFO - 'LANG': 'en_US.UTF-8', 04:00:52 INFO - 'LANGUAGE': 'en_US:en', 04:00:52 INFO - 'LOGNAME': 'cltbld', 04:00:52 INFO - 'MAIL': '/var/mail/cltbld', 04:00:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:00:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:52 INFO - 'MOZ_NO_REMOTE': '1', 04:00:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:52 INFO - 'NO_EM_RESTART': '1', 04:00:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:52 INFO - 'PWD': '/builds/slave/test', 04:00:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:00:52 INFO - 'SHELL': '/bin/bash', 04:00:52 INFO - 'SHLVL': '1', 04:00:52 INFO - 'TERM': 'linux', 04:00:52 INFO - 'TMOUT': '86400', 04:00:52 INFO - 'USER': 'cltbld', 04:00:52 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296', 04:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:52 INFO - '_': '/tools/buildbot/bin/python'} 04:00:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:00:52 INFO - mozversion application_buildid: 20160908004007 04:00:52 INFO - mozversion application_changeset: 7d3d4f307002cc8475800df191196b94999ffd0a 04:00:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:00:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:00:52 INFO - mozversion application_name: Firefox 04:00:52 INFO - mozversion application_remotingname: firefox-dev 04:00:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:00:52 INFO - mozversion application_vendor: Mozilla 04:00:52 INFO - mozversion application_version: 50.0a2 04:00:52 INFO - mozversion platform_buildid: 20160908004007 04:00:52 INFO - mozversion platform_changeset: 7d3d4f307002cc8475800df191196b94999ffd0a 04:00:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:00:52 INFO - mozversion platform_version: 50.0a2 04:00:52 INFO - using testdate: 1473332452 04:00:52 INFO - actual date: 1473332452 04:00:52 INFO - starting webserver on 'localhost:54488' 04:00:52 INFO - SUITE-START | Running 2 tests 04:00:52 INFO - TEST-START | dromaeo_css 04:00:52 INFO - operating with platform_type : linux_ 04:00:52 INFO - Initialising browser for dromaeo_css test... 04:00:52 INFO - TEST-INFO | started process 21429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphDUVH5/profile http://localhost:54488/getInfo.html) 04:00:57 INFO - PROCESS | 21429 | __metrics Screen width/height:1600/1200 04:00:57 INFO - PROCESS | 21429 | colorDepth:24 04:00:57 INFO - PROCESS | 21429 | Browser inner width/height: 1024/697 04:00:57 INFO - PROCESS | 21429 | __metrics 04:00:57 ERROR - PROCESS | 21429 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 04:00:58 INFO - TEST-INFO | 21429: exit 0 04:00:58 INFO - Browser initialized. 04:00:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:00:58 INFO - TEST-INFO | started process 21554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphDUVH5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:10 INFO - PROCESS | 21554 | RSS: Main: 152256512 04:01:10 INFO - PROCESS | 21554 | 04:02:00 INFO - PROCESS | 21554 | 1473332520029 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:04:08 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54488/tests/dromaeo/cssquery-ext.html) 04:04:10 INFO - PROCESS | 21554 | RSS: Main: 158048256 04:04:10 INFO - PROCESS | 21554 | 04:07:12 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-ext.html (next: http://localhost:54488/tests/dromaeo/cssquery-jquery.html) 04:07:12 INFO - PROCESS | 21554 | RSS: Main: 153911296 04:07:12 INFO - PROCESS | 21554 | 04:10:14 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54488/tests/dromaeo/cssquery-mootools.html) 04:10:15 INFO - PROCESS | 21554 | RSS: Main: 154542080 04:10:15 INFO - PROCESS | 21554 | 04:13:16 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54488/tests/dromaeo/cssquery-prototype.html) 04:13:17 INFO - PROCESS | 21554 | RSS: Main: 152821760 04:13:17 INFO - PROCESS | 21554 | 04:16:20 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54488/tests/dromaeo/cssquery-yui.html) 04:16:20 INFO - PROCESS | 21554 | RSS: Main: 155078656 04:16:20 INFO - PROCESS | 21554 | 04:19:22 INFO - PROCESS | 21554 | Cycle 1(1): loaded http://localhost:54488/tests/dromaeo/cssquery-yui.html (next: http://localhost:54488/tests/dromaeo/cssquery-dojo.html) 04:19:23 INFO - PROCESS | 21554 | RSS: Main: 155537408 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | __start_tp_report 04:19:23 INFO - PROCESS | 21554 | _x_x_mozilla_page_load 04:19:23 INFO - PROCESS | 21554 | _x_x_mozilla_page_load_details 04:19:23 INFO - PROCESS | 21554 | |i|pagename|runs| 04:19:23 INFO - PROCESS | 21554 | |0;dojo.html;3301.6038075430947;3322.667733226677;3341.0311009807174;3361.924184261037;3367.7474189435798;5750.252467179272;5755.280589926259;5767.307923049229;5839.503642190413;5841.357450680422;5758.452946970042;5808.2158908547335;5870.589058765888;5873.030949893273;5887.793927212549;5282.445343238962;5296.443873393296;5326.360836699582;5349.893002139955;5372.838814835542;5157.6647517911215;5163.690178589297;5211.088059589576;5258.13240815265;5316.548093412064;5289.86775330616;5299.125644268691;5313.017091838003;5371.4897084776885;5391.541718953894;5352.116900711378;5362.624616276862;5382.085045542267;5396.487333703292;5401.4868587484125;5053.115704751691;5078.822241221539;5109.4379618241965;5254.264402983586;5276.419593844713;5651.8304450866535;5676.574256930747;5700.059490184115;5714.171445140473;5730.083186690152;6058.182145410357;6074.301455332649;6133.754649814002;6138.232720909887;6165;6006.939930600682;6110.103144028831;6154.78458253959;6173.228346456693;6175.166352542439;5807.0998995155815;5812.215350927613;5814.098814683731;5819.039858423397;5841.649501029951;6342.39747224013;6355.682215889187;6362.745490180433;6373.075903993895;6389.616623002635;5855.238818953509;6055.697215139226;6066.272047354258;6087.269527656621;6113.32753397126;6164.106204600308;6182.752689892354;6223.471004964538;6225.221847269091;6230.875382492325;6204.534659900525;6214.9689251553455;6224.844378890564;6226.59527130735;6244.438000579969;5940.70296485174;5995.910061349084;6028.969855150784;6181.381861813873;6222.0355844844125;4673.158831410379;4682.906341873144;4690.061987602493;4715.056988602293;4717.292406139038;4203.5376108628025;4230.344296634026;4230.407742915994;4230.61924426803;4245;5680.289963754531;5790.971045144832;5875.588708790429;5912.940870591325;5922.585419020627;6166.691665416801;6172.598781079305;6176.536759743038;6176.8764624707255;6189.226346706661;5910.113482977587;5927.110933360031;5927.9703601481715;5930.169776231331;5957;5306.469353064663;5321.547668448148;5350.759215835296;5388.649737767033;5406.08096623575;6463.7091330890225;6483.384078512629;6490.837729056811;6523.902141467882;6524.380183882524;6294.339094395023;6307.873842523032;6312.936870631419;6324.146240257506;6328.873422531431;6054.364291749479;6338.58799178052;6380.074889140956;6384.553081284265;6452.032195170762;6132.122542660378;6142.324344322032;6145.446909778141;6145.67867908384;6146.907796382969;6197.163382943245;6202.193714817045;6208.758248350257;6248.562600617913;6271.216097987752;5897.292324921037;6074.240719910012;6090.086487026982;6100.572959892765;6128.938710612837;6105.992511235774;6126.816195514141;6151.108089327112;6164.260288765377;6173.6913154343;6103.978949047132;6124.631323455516;6131.816045518642;6143.293521245114;6162.845928851635;5836.328822185334;5975.55183361241;5999.460048595647;6027.60820546663;6152.538559607958;5901.616394934315;5913.793017244376;5926.051831706906;5932.110183472513;5939.287285525765 04:19:23 INFO - PROCESS | 21554 | |1;ext.html;571.9428057194281;597.9402059794019;621.4158690830619;639.4404895716249;659.3143131143611;40543.18913621726;44697.77651111748;45186.32220516686;45206.32190517145;45261;44361.778191109;45228.32157517632;45232.77383613077;45418.091638167214;45536.3169552457;9095.636174553027;9136.360454768135;9139.63441462344;9169.312301577374;9209.539523023823;9066.410683305598;9067.909320906805;9146.039665835091;9161.541922903862;9196.448213107202;9197.86203206949;9199.540022998823;9207.723768286929;9257.074292570724;9267.119623635777;9147.22248608869;9151.771205719844;9154.221891139261;9156.404833685823;9168.358214924958;9096.226820720247;9101.63593456261;9172.12864777845;9183.17351438367;9187.862182067274;43585.56414435879;43589.346159807625;43659.126817463475;43721.78139109316;44033.339499907524;3079.491883839164;3533.876314328986;3639.217568222845;3670.871519496805;3750.5686846012513;32502;33060.338793224;33079.17302067443;33227.33545329104;33355.332893342;4526.796294166737;4542.159700455426;4566.10960862632;4579.908401831978;4586.266197408432;331550;373374;379974;547339;548971;63903.680481597294;64502;64767.35232647708;64791.67604161949;64827.675861621334;39802.60197398047;65670;66207.33792662108;66222.66888665526;66351.33648663452;18613;19305.613887722167;19393.32123375675;19413;19414.611707765765;12528.561500347445;12535.498580056877;12551.937240313922;12563.434645440977;12573.87426125727;20713.792862071186;20912.68630970537;21014.894925525274;21066;21352.359429217147;7832.490888092256;7875.133735289113;7949.046114466301;7953.602319883983;8093.3929955252415;1007.450939238117;1085.1535802074395;1143.1483544759042;1156.5489459110954;1180.9468573914194;34047.489287660705;34100.31799363999;34185.658143418244;34324.48513272303;34339.70503582836;1129.7966366054188;1156.5778490850778;1157.6295585412588;1172.5192671005018;1225.2709637765515;17753.911230444024;17958.5510362242;18017.459476215736;18236.726449103273;18304.17631206599;592621;596123;596452;597002;602688;65788;66200.33799662038;66347.66826165935;66719;66802.66598667073;1434.6269969807925;1452.6878716526126;1465.3405967314538;1478.2608695652175;1486.1677460622086;32076.83961580177;33278.50082248817;33603.83198083994;33608.66391336056;33761.15597110092;57989.710051449474;58325.125123122336;58360.4163958355;58429.415705842395;58448.415515846;496559;496771;497807;498830;500782;30809.845950771;31029.68970310268;31584.210394740316;31877.36245275128;31925.68074319227;52263.21605175849;52364;53243.402697919126;53264.46735532595;54944;43874.561254387045;46293.53706462892;46904.06191876212;47238.76380618075;47480.52519474899;47304.7634761824;47331.290030650256;47600;47606.52393476021;47843.76078119587;37670.434943475324;44343;45041;45466.31800522929;45759.54240457686;38666.613333867426;39782.37581336282;39878.80060599678;40276.79861600673;41021.38467922924;30390.84804575963;30431;30850.845745771127;30856.382872342878;31010.379792404477 04:19:23 INFO - PROCESS | 21554 | |2;jquery.html;417.99164016719686;427.26891237172396;427.96362309203715;431.2302539966161;434.5220257716512;19523.12145953432;20357.49106272344;21350.466238344048;21385.786142138575;21584.568308633818;19782;21008;21514.247001354957;21606.459838504044;21664.3500694979;5678.233438485813;5805.071188609823;5829.708514574254;5858.472737453629;5867.266591676041;6019.939800602004;6051.606645568046;6077.817665470021;6084.330723620398;6137.6624285664275;5984.850378740544;5988.221531200959;5989;5990.490808281302;5997.100434934773;5977.163197152402;5996.880062398771;6017.759289628431;6034.275886893557;6075.544334174911;5252.23842542833;5438.673679579238;5479.369872464677;5484.698341591211;5486.1222204447495;23965.7603423967;24219.636705449433;24331.878340608182;24915.377115572075;24935.376615584573;2460.766227208412;2477.256822953107;2546.337952132459;2578.3425226567106;2726.3865630233236;10822.783544329148;11077.335359878432;13478.989075819352;15399.846001540065;15489.380424782996;1949.736785533949;1982.326009156879;2033.4509682385822;2042.652749032668;2106.837564507662;70942.64528677323;91002;91780;94722.52638736762;95904;9901.851472227921;10639.946800266103;10642.308249963728;10642.521086551124;10729.624463143753;14558.563243102724;14594.635134121732;14598.051126676732;14767.335469903664;14768.335424905905;8225.177482251847;8312.33501319893;8362.665493380318;8591.914080859236;8670.436421632574;8511.57442127891;8511.95744021276;8816.955915220382;8825.955870220609;8983.775405614912;4376.737395756265;4477.440319960005;4478.798454069574;4518.774061296987;4536.841210557614;2030.6040322137042;2076.6677331626865;2085.822705070086;2111.313823007498;2143.9356819295444;972.8443449048118;1050.0601961244643;1070.7376692710336;1089.3137820576578;1139.703677043958;20838.791612083987;21634.459138521663;21652.210863098895;21762.40631458361;21819.67270490912;1040.3237895368072;1046.858674079005;1058.4548957287004;1065.3181963543338;1152.7175841918784;11448.54205831774;11733.941330293294;11879.287242765462;11997.880021199677;12145.635630931087;413130;424110;424966;566195;575165;68783;107159;109269.45365273282;109374;110051;1026.9012156992096;1062.8512008318842;1092.8633920759905;1141.1897552737648;1150.396042077899;17961.371352002618;18196.363127290482;18517.629647406706;19428.708569371753;19689.901550492155;44161;44600.776996114815;45129.774351128035;46139.76930115328;47056.29415558838;60211.69894150501;61075.38924610697;63629.681851590445;64964.35035649772;65751.34248657452;8831.205191532792;9247.855729052155;9424;9430;9469.763255918657;14009.649758756113;14206.360713767699;14269.785953210503;14340.354684039035;14703.485378011717;11856;11911.285322880654;12232.633021009384;12389.566365177176;12850.357482126043;11477.942610286895;11674.964509227832;12585.685357866127;12632.74734505323;12753.362331883554;12343.69140771488;12447.688807779878;12505.624831255076;12576.496940122659;12696.428660710106;8485.066642669424;8493.702720404755;8565.700200492953;8612.48325100496;9136.08639136081;11256.268342558034;12000.219985700902;12210.75578488443;12352;12376.443060062498 04:19:23 INFO - PROCESS | 21554 | |3;mootools.html;1558.6648870492845;1560.8205056418512;1783.456045905999;1847.1226167570396;1872.5599484121221;8393.496390216591;9105.135012173841;9137.177654011137;9889.10998010179;9911;9279.072092790735;9636.421814691106;9637.710868673934;9646.228301735862;9648.228141748661;2529.051605647882;2540.136353639762;2551.1836212412036;2554.961675574868;2560.539102961466;2479.4917042006346;2571.2414837622946;2576.278641980239;2577.896884124633;2580.458103798195;2509.5608268553024;2517.6852893388327;2545.7836083932793;2550.579154439515;2552.719200887901;2453.5706251406023;2467.5805113130723;2472.7403622619636;2521.936951576225;2523.8864251108744;2431.088341871798;2443.2670198940423;2444.4622183119864;2444.535538247745;2448.963265551018;9580.95209523948;9636.71089867305;9681.564329605184;9705.320627556073;9724.75688107796;5633.521150702195;5753.108268218391;5783.5951483396175;5794.710264486759;5809.419058094199;3679.4480827875764;3808.9809550952345;3812.4090765931323;3847.4228865670093;3896.084420161288;1148.1561052626314;1160.0661467518644;1249.1505776072268;1254.9309787961658;1269.961901142958;13380.732385352238;13471.124376915566;13495.527656531975;13510.797338039938;13555;4689.788959496831;5050.090983622909;5056.797728090835;5125.359330083739;5126.410462796826;11207.215494915277;11210.327380357205;11219;11231.26996745209;11243.100551955824;5733.827985160452;5746.569007324384;5751.166080918327;5762.193292938992;5766.394528574523;4569.771511424416;5195.610329225323;5240.423553409122;5245.6852588844795;5323.095073837457;4360.520342762294;4366.476022877275;4399.824007039682;4415.050764085737;4431.778411079433;1326.2440408966988;1357.6470117769254;1367.931603419825;1368.5620601407454;1388.7430825297351;949.6533765175661;1025.958961641526;1041.1878734587035;1049.6903413493037;1069.1767339148967;6247.500199983991;6285.277193122758;6337.144485494399;6481.837954051187;6620.271770105284;478.930555069513;496.3720893070269;504.0221969379392;1061.619894137618;1078.3422112511362;4240.257954857888;4317.546657600969;4363.781810909441;4394.923243805289;4401.977990110093;11675.649730508097;11861.46623401949;11896.643100706993;11954.222975506564;12162.452689628988;11778.646640600795;11836.763264734656;11871.109666775026;11885.584004559973;11933.761324773455;8765.474071555727;9046.185843274005;9076.909230907739;9095.68112623666;9124.087591240823;6455.193100862392;6477.1579694639395;6488.480921526361;6501.967490162708;6615.04081908111;10890.727731806768;11186.32882027081;11210;11243.662690118983;11251.043661288555;11082.501287442246;11122.388268645302;11146.554137834395;11206.159538034843;11246.268992515461;7602.277783610548;8167.83664326722;8324.6670133194;8361.414700970874;8483.915160848312;9318.068193180628;9371.765705857408;9414.293927955294;9417.011213822723;9426.62293508252;8326.875096873673;8510.446820956618;8558.700445484377;8564.700235491728;8593.398462107842;8316.916830831615;8415.158484151536;8441.915580844114;8472.406931514735;8481.83036339282;7521.511101778586;7668.769936901902;7716.344110750649;7760.534367937942;7763.495372800976;5304.310439642822;5322.175062865107;5340.5994550408095;5434.184872269191;5487.890242195054;5519.668819870821;5521.2270282161335;5542.722863856871;5672.290963629546;5730.684812335195 04:19:23 INFO - PROCESS | 21554 | |4;prototype.html;349.6014543420501;354.73394953784657;356.65939028228036;368.77504722119517;370.51462584014945;5635.859103522414;5737.397573254811;5895.675737834417;5927.881442371156;5972.761089556423;5753.165790960308;5771.220885180499;5782.884342313151;5792.218050563172;5806.738696758645;4518.819247230107;4530.229860923634;4534.8639540813665;4571.331464015627;4578.519255478177;4487.599134463063;4523.5024147343765;4534.614557762594;4560.33875088112;4569.200389931749;4447.599006313001;4461.553844615545;4506.97746511267;4510.684252102355;4521.502634710196;4518.570735780112;4522.34426008227;4539.7301349325335;4539.863804085882;4568.885777855547;3890.3580909149955;3984.7011474139267;3989.860354887565;4042.393640953851;4053.61490658387;5618.634788738719;5666.660000399948;5702.631368471579;5746.36789953109;5754.280714910637;5211.530962213381;5237.3453318335205;5253.212018197263;5279.208118782175;5295.682259064469;5790.131480277992;6059.7273122709585;6083.878322433527;6090.23872015998;6121.755129794803;4259;4276.652854350869;4285;4295.205387003414;4307.892302692458;6070.544709146788;6288.559800813945;6293.811185664392;6324.557280990332;6327.5570710050315;4428.911421771547;4446.866049157444;4461.13007963444;4461.687681862239;4474.664400169935;4534.342520334533;4541.227991241497;4545.136424079415;4559.589636932626;4569.360289559464;5179.306760484527;5198.246254293106;5209.270702101709;5214.426413094557;5222.321098257202;5919.200907877467;5941.851453713692;6097.817065488042;6102.870968870773;6123.96938015316;1541.029151634463;1559.5321403579107;1562.7499600064164;1563.4059057558054;1565.2486806333025;962.916718691472;995.0596686131681;1005.5676059294574;1014.4319181258371;1014.8731408573929;5227.660202086929;5247.845473995715;5249.816256431007;5308.681479111266;5311;5574.972125139429;5613.158026296007;5616.971915140398;5618.157276408571;5625.774969001276;4992.801727585354;5249;5281.049411105961;5298.496642818926;5337.546308563816;2958.45268625305;2959.4377068356944;2978.2852115492124;2980.269833890667;2986.4773664609042;5677.489025987681;5692.9146062809095;5708.600397972102;5713.971430142823;5743.62666426681;5260.895212005522;5572.247746554163;5616.466435688603;5617.662940223599;5648.548116150698;5759.128283307941;5763.242211125563;5772.3938986406665;5785.84283143373;5816.27296587935;5570.86072848182;5579.875269848898;5596.692181930033;5908.586398952204;6306.842328941813;6283.905741413974;6287.46556542699;6296.111166650078;6299.307076221704;6303.810885673437;6380.489560835215;6536.640484773383;6555.770548030796;6562.3109573496;6586.80239592813;4444.933326000178;4470.731756094645;4496.527864574173;4498.762840218966;4520.660950428665;6188.752449902134;6324.462420694293;6337.302896681269;6404.519661025535;6425.1968503937005;6166.440927015238;6191.442770150708;6236.18929539157;6240.4071613196675;6247.562670613013;6100.664463454553;6195.597286176384;6197.907031394442;6213.627182369072;6221.595596286227;6165.722542485689;6167.938320616916;6178;6185.134081228722;6214.161088253028;6225.190725205694;6268.968655156695;6298.779542715983;6299.149614802126;6300.21247344082;5905.881882362414;6027.156198132353;6028.487578555696;6068.302145253176;6121.204243448323 04:19:23 INFO - PROCESS | 21554 | |5;yui.html;798.9480683755555;803.0564087197541;820.0036955099553;852.4586887326547;890.6927110146997;16597.41909033184;17725.46823595291;18019.4594162175;18072.457826265232;18184.09079546024;16108.75836862448;16145.677086458263;16210.189490525485;16212.027278363306;16256.105914174716;652.8531080506896;862.8964524257098;866.2160744526183;890.2922176869372;895.4985208283382;886.601029536711;890.9420887642315;893.0444424465824;893.5175005497024;894.9910500894973;939.6805086270666;941.1576638908207;942.4015749998731;944.8771659684265;947.5688561704443;931.3154831199063;936.1013427109982;937.0208132501552;937.9624815007392;944;686.0532465198045;687.8177283020003;691.9688614012382;694.4652617484509;697.2400083908514;16246.187690615421;16286.75569866441;16347.591310217222;16399.344026239054;16453.83546164535;16531.586710332216;16700.331986720517;16850.325986960506;16920.238589263514;17369.7394539082;13595.796063059062;13626.1143025703;13752.518661846787;13789.379477923418;13954.232517211549;3989.900252493682;4004.619561141687;4038.2932986727205;4080.7959602019782;4085.9387109193385;82306;85110.57444712736;85153.14846851514;85434;88292;12362.567310144103;12403.889811945905;12439;12468.314242716557;12503;25311.36721581975;25318.873405632854;25385.238442846745;25395.984160633736;25542.10602628899;9007.189352958134;9207.769805754775;9292.907070929205;9378.109079637423;9416.670416535388;5118.078745825787;5199.506046925536;5297.152455607084;5319.627626066138;5358.5177334039345;3927.116398810245;4083.387491876242;4109.363048727452;4134.37984302351;4150.6471949884;617.102116420607;622.5859803230801;624.3319647976622;626.5582764151262;632.4845251120322;645.3560593687994;691.1774987764544;718.6263143165629;770.9630546914391;839.2446797881955;10239.287149540936;10442;10622.521986510626;10810.081143102925;10824.24230303871;691.6126968897433;708.4226355520337;721.3147509865648;772.5017363800317;833.8749187621784;4734.431868175841;5062.898742025139;5140.434552199255;5150.793968241228;5207.3230480037355;114065.42967285111;119207;119847;121459;121562.39218803849;98183;100409.99999999854;101229.49385253027;102134.48932755437;102923.4853825741;69664;86016.56991714876;86813.5659321712;87208;87259;9618.326717130014;9799.216062715108;9831.066048725359;10003.899961000297;10236.078752912274;51115.74442127765;51434.74282628563;51750.48249517457;51902;52985;75075.62462187873;75935.24064759281;77428.22571774435;77911.61044194743;79414;12648.74702505963;12731.108822382344;12933.805992910302;12960.740785184433;13324.067315287837;14458.566243012727;14519.564413067625;14611.92694036523;14648;15124.773128402863;13347.866521334663;13362.933185334012;13895.722085558435;14150.716985660023;14204.076735012191;13301.26843023643;13589.252591107585;13875.236861972688;14482.34829432657;14492.130472171704;12917.28954907489;13386.799198011842;13782.379792909544;14031.158130512202;14359.712805744035;10825.67522974312;11062.834057488983;11199.16006299547;11306.038986686224;11306.94346528262;9604;11110.777784444428;11198.832017519906;11349;11490.655280341269 04:19:23 INFO - PROCESS | 21554 | __end_tp_report 04:19:23 INFO - PROCESS | 21554 | __start_cc_report 04:19:23 INFO - PROCESS | 21554 | _x_x_mozilla_cycle_collect,336 04:19:23 INFO - PROCESS | 21554 | __end_cc_report 04:19:23 INFO - PROCESS | 21554 | __startTimestamp1473333563194__endTimestamp 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | ------- Summary: start ------- 04:19:23 INFO - PROCESS | 21554 | Number of tests: 6 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#0] dojo.html Cycles:165 Average:5758.06 Median:5940.70 stddev:657.92 (11.1%) stddev-sans-first:631.09 04:19:23 INFO - PROCESS | 21554 | Values: 3301.6 3322.7 3341.0 3361.9 3367.7 5750.3 5755.3 5767.3 5839.5 5841.4 5758.5 5808.2 5870.6 5873.0 5887.8 5282.4 5296.4 5326.4 5349.9 5372.8 5157.7 5163.7 5211.1 5258.1 5316.5 5289.9 5299.1 5313.0 5371.5 5391.5 5352.1 5362.6 5382.1 5396.5 5401.5 5053.1 5078.8 5109.4 5254.3 5276.4 5651.8 5676.6 5700.1 5714.2 5730.1 6058.2 6074.3 6133.8 6138.2 6165.0 6006.9 6110.1 6154.8 6173.2 6175.2 5807.1 5812.2 5814.1 5819.0 5841.6 6342.4 6355.7 6362.7 6373.1 6389.6 5855.2 6055.7 6066.3 6087.3 6113.3 6164.1 6182.8 6223.5 6225.2 6230.9 6204.5 6215.0 6224.8 6226.6 6244.4 5940.7 5995.9 6029.0 6181.4 6222.0 4673.2 4682.9 4690.1 4715.1 4717.3 4203.5 4230.3 4230.4 4230.6 4245.0 5680.3 5791.0 5875.6 5912.9 5922.6 6166.7 6172.6 6176.5 6176.9 6189.2 5910.1 5927.1 5928.0 5930.2 5957.0 5306.5 5321.5 5350.8 5388.6 5406.1 6463.7 6483.4 6490.8 6523.9 6524.4 6294.3 6307.9 6312.9 6324.1 6328.9 6054.4 6338.6 6380.1 6384.6 6452.0 6132.1 6142.3 6145.4 6145.7 6146.9 6197.2 6202.2 6208.8 6248.6 6271.2 5897.3 6074.2 6090.1 6100.6 6128.9 6106.0 6126.8 6151.1 6164.3 6173.7 6104.0 6124.6 6131.8 6143.3 6162.8 5836.3 5975.6 5999.5 6027.6 6152.5 5901.6 5913.8 5926.1 5932.1 5939.3 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#1] ext.html Cycles:180 Average:67914.46 Median:24893.90 stddev:137515.62 (552.4%) stddev-sans-first:137808.44 04:19:23 INFO - PROCESS | 21554 | Values: 571.9 597.9 621.4 639.4 659.3 40543.2 44697.8 45186.3 45206.3 45261.0 44361.8 45228.3 45232.8 45418.1 45536.3 9095.6 9136.4 9139.6 9169.3 9209.5 9066.4 9067.9 9146.0 9161.5 9196.4 9197.9 9199.5 9207.7 9257.1 9267.1 9147.2 9151.8 9154.2 9156.4 9168.4 9096.2 9101.6 9172.1 9183.2 9187.9 43585.6 43589.3 43659.1 43721.8 44033.3 3079.5 3533.9 3639.2 3670.9 3750.6 32502.0 33060.3 33079.2 33227.3 33355.3 4526.8 4542.2 4566.1 4579.9 4586.3 331550.0 373374.0 379974.0 547339.0 548971.0 63903.7 64502.0 64767.4 64791.7 64827.7 39802.6 65670.0 66207.3 66222.7 66351.3 18613.0 19305.6 19393.3 19413.0 19414.6 12528.6 12535.5 12551.9 12563.4 12573.9 20713.8 20912.7 21014.9 21066.0 21352.4 7832.5 7875.1 7949.0 7953.6 8093.4 1007.5 1085.2 1143.1 1156.5 1180.9 34047.5 34100.3 34185.7 34324.5 34339.7 1129.8 1156.6 1157.6 1172.5 1225.3 17753.9 17958.6 18017.5 18236.7 18304.2 592621.0 596123.0 596452.0 597002.0 602688.0 65788.0 66200.3 66347.7 66719.0 66802.7 1434.6 1452.7 1465.3 1478.3 1486.2 32076.8 33278.5 33603.8 33608.7 33761.2 57989.7 58325.1 58360.4 58429.4 58448.4 496559.0 496771.0 497807.0 498830.0 500782.0 30809.8 31029.7 31584.2 31877.4 31925.7 52263.2 52364.0 53243.4 53264.5 54944.0 43874.6 46293.5 46904.1 47238.8 47480.5 47304.8 47331.3 47600.0 47606.5 47843.8 37670.4 44343.0 45041.0 45466.3 45759.5 38666.6 39782.4 39878.8 40276.8 41021.4 30390.8 30431.0 30850.8 30856.4 31010.4 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#2] jquery.html Cycles:180 Average:29961.38 Median:21791.04 stddev:80663.94 (370.2%) stddev-sans-first:80859.72 04:19:23 INFO - PROCESS | 21554 | Values: 418.0 427.3 428.0 431.2 434.5 19523.1 20357.5 21350.5 21385.8 21584.6 19782.0 21008.0 21514.2 21606.5 21664.4 5678.2 5805.1 5829.7 5858.5 5867.3 6019.9 6051.6 6077.8 6084.3 6137.7 5984.9 5988.2 5989.0 5990.5 5997.1 5977.2 5996.9 6017.8 6034.3 6075.5 5252.2 5438.7 5479.4 5484.7 5486.1 23965.8 24219.6 24331.9 24915.4 24935.4 2460.8 2477.3 2546.3 2578.3 2726.4 10822.8 11077.3 13479.0 15399.8 15489.4 1949.7 1982.3 2033.5 2042.7 2106.8 70942.6 91002.0 91780.0 94722.5 95904.0 9901.9 10639.9 10642.3 10642.5 10729.6 14558.6 14594.6 14598.1 14767.3 14768.3 8225.2 8312.3 8362.7 8591.9 8670.4 8511.6 8512.0 8817.0 8826.0 8983.8 4376.7 4477.4 4478.8 4518.8 4536.8 2030.6 2076.7 2085.8 2111.3 2143.9 972.8 1050.1 1070.7 1089.3 1139.7 20838.8 21634.5 21652.2 21762.4 21819.7 1040.3 1046.9 1058.5 1065.3 1152.7 11448.5 11733.9 11879.3 11997.9 12145.6 413130.0 424110.0 424966.0 566195.0 575165.0 68783.0 107159.0 109269.5 109374.0 110051.0 1026.9 1062.9 1092.9 1141.2 1150.4 17961.4 18196.4 18517.6 19428.7 19689.9 44161.0 44600.8 45129.8 46139.8 47056.3 60211.7 61075.4 63629.7 64964.4 65751.3 8831.2 9247.9 9424.0 9430.0 9469.8 14009.6 14206.4 14269.8 14340.4 14703.5 11856.0 11911.3 12232.6 12389.6 12850.4 11477.9 11675.0 12585.7 12632.7 12753.4 12343.7 12447.7 12505.6 12576.5 12696.4 8485.1 8493.7 8565.7 8612.5 9136.1 11256.3 12000.2 12210.8 12352.0 12376.4 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#3] mootools.html Cycles:180 Average:6311.59 Median:4500.77 stddev:3621.27 (80.5%) stddev-sans-first:3613.81 04:19:23 INFO - PROCESS | 21554 | Values: 1558.7 1560.8 1783.5 1847.1 1872.6 8393.5 9105.1 9137.2 9889.1 9911.0 9279.1 9636.4 9637.7 9646.2 9648.2 2529.1 2540.1 2551.2 2555.0 2560.5 2479.5 2571.2 2576.3 2577.9 2580.5 2509.6 2517.7 2545.8 2550.6 2552.7 2453.6 2467.6 2472.7 2521.9 2523.9 2431.1 2443.3 2444.5 2444.5 2449.0 9581.0 9636.7 9681.6 9705.3 9724.8 5633.5 5753.1 5783.6 5794.7 5809.4 3679.4 3809.0 3812.4 3847.4 3896.1 1148.2 1160.1 1249.2 1254.9 1270.0 13380.7 13471.1 13495.5 13510.8 13555.0 4689.8 5050.1 5056.8 5125.4 5126.4 11207.2 11210.3 11219.0 11231.3 11243.1 5733.8 5746.6 5751.2 5762.2 5766.4 4569.8 5195.6 5240.4 5245.7 5323.1 4360.5 4366.5 4399.8 4415.1 4431.8 1326.2 1357.6 1367.9 1368.6 1388.7 949.7 1026.0 1041.2 1049.7 1069.2 6247.5 6285.3 6337.1 6481.8 6620.3 478.9 496.4 504.0 1061.6 1078.3 4240.3 4317.5 4363.8 4394.9 4402.0 11675.6 11861.5 11896.6 11954.2 12162.5 11778.6 11836.8 11871.1 11885.6 11933.8 8765.5 9046.2 9076.9 9095.7 9124.1 6455.2 6477.2 6488.5 6502.0 6615.0 10890.7 11186.3 11210.0 11243.7 11251.0 11082.5 11122.4 11146.6 11206.2 11246.3 7602.3 8167.8 8324.7 8361.4 8483.9 9318.1 9371.8 9414.3 9417.0 9426.6 8326.9 8510.4 8558.7 8564.7 8593.4 8316.9 8415.2 8441.9 8472.4 8481.8 7521.5 7668.8 7716.3 7760.5 7763.5 5304.3 5322.2 5340.6 5434.2 5487.9 5519.7 5521.2 5542.7 5672.3 5730.7 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#4] prototype.html Cycles:180 Average:5023.55 Median:5616.72 stddev:1473.98 (26.2%) stddev-sans-first:1435.76 04:19:23 INFO - PROCESS | 21554 | Values: 349.6 354.7 356.7 368.8 370.5 5635.9 5737.4 5895.7 5927.9 5972.8 5753.2 5771.2 5782.9 5792.2 5806.7 4518.8 4530.2 4534.9 4571.3 4578.5 4487.6 4523.5 4534.6 4560.3 4569.2 4447.6 4461.6 4507.0 4510.7 4521.5 4518.6 4522.3 4539.7 4539.9 4568.9 3890.4 3984.7 3989.9 4042.4 4053.6 5618.6 5666.7 5702.6 5746.4 5754.3 5211.5 5237.3 5253.2 5279.2 5295.7 5790.1 6059.7 6083.9 6090.2 6121.8 4259.0 4276.7 4285.0 4295.2 4307.9 6070.5 6288.6 6293.8 6324.6 6327.6 4428.9 4446.9 4461.1 4461.7 4474.7 4534.3 4541.2 4545.1 4559.6 4569.4 5179.3 5198.2 5209.3 5214.4 5222.3 5919.2 5941.9 6097.8 6102.9 6124.0 1541.0 1559.5 1562.7 1563.4 1565.2 962.9 995.1 1005.6 1014.4 1014.9 5227.7 5247.8 5249.8 5308.7 5311.0 5575.0 5613.2 5617.0 5618.2 5625.8 4992.8 5249.0 5281.0 5298.5 5337.5 2958.5 2959.4 2978.3 2980.3 2986.5 5677.5 5692.9 5708.6 5714.0 5743.6 5260.9 5572.2 5616.5 5617.7 5648.5 5759.1 5763.2 5772.4 5785.8 5816.3 5570.9 5579.9 5596.7 5908.6 6306.8 6283.9 6287.5 6296.1 6299.3 6303.8 6380.5 6536.6 6555.8 6562.3 6586.8 4444.9 4470.7 4496.5 4498.8 4520.7 6188.8 6324.5 6337.3 6404.5 6425.2 6166.4 6191.4 6236.2 6240.4 6247.6 6100.7 6195.6 6197.9 6213.6 6221.6 6165.7 6167.9 6178.0 6185.1 6214.2 6225.2 6269.0 6298.8 6299.1 6300.2 5905.9 6027.2 6028.5 6068.3 6121.2 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - PROCESS | 21554 | [#5] yui.html Cycles:180 Average:21752.23 Median:4084.66 stddev:30821.71 (754.6%) stddev-sans-first:30868.02 04:19:23 INFO - PROCESS | 21554 | Values: 798.9 803.1 820.0 852.5 890.7 16597.4 17725.5 18019.5 18072.5 18184.1 16108.8 16145.7 16210.2 16212.0 16256.1 652.9 862.9 866.2 890.3 895.5 886.6 890.9 893.0 893.5 895.0 939.7 941.2 942.4 944.9 947.6 931.3 936.1 937.0 938.0 944.0 686.1 687.8 692.0 694.5 697.2 16246.2 16286.8 16347.6 16399.3 16453.8 16531.6 16700.3 16850.3 16920.2 17369.7 13595.8 13626.1 13752.5 13789.4 13954.2 3989.9 4004.6 4038.3 4080.8 4085.9 82306.0 85110.6 85153.1 85434.0 88292.0 12362.6 12403.9 12439.0 12468.3 12503.0 25311.4 25318.9 25385.2 25396.0 25542.1 9007.2 9207.8 9292.9 9378.1 9416.7 5118.1 5199.5 5297.2 5319.6 5358.5 3927.1 4083.4 4109.4 4134.4 4150.6 617.1 622.6 624.3 626.6 632.5 645.4 691.2 718.6 771.0 839.2 10239.3 10442.0 10622.5 10810.1 10824.2 691.6 708.4 721.3 772.5 833.9 4734.4 5062.9 5140.4 5150.8 5207.3 114065.4 119207.0 119847.0 121459.0 121562.4 98183.0 100410.0 101229.5 102134.5 102923.5 69664.0 86016.6 86813.6 87208.0 87259.0 9618.3 9799.2 9831.1 10003.9 10236.1 51115.7 51434.7 51750.5 51902.0 52985.0 75075.6 75935.2 77428.2 77911.6 79414.0 12648.7 12731.1 12933.8 12960.7 13324.1 14458.6 14519.6 14611.9 14648.0 15124.8 13347.9 13362.9 13895.7 14150.7 14204.1 13301.3 13589.3 13875.2 14482.3 14492.1 12917.3 13386.8 13782.4 14031.2 14359.7 10825.7 11062.8 11199.2 11306.0 11306.9 9604.0 11110.8 11198.8 11349.0 11490.7 04:19:23 INFO - PROCESS | 21554 | -------- Summary: end -------- 04:19:23 INFO - PROCESS | 21554 | 04:19:23 INFO - TEST-INFO | 21554: exit 0 04:19:23 INFO - TEST-OK | dromaeo_css | took 1111494ms 04:19:23 INFO - TEST-START | kraken 04:19:23 INFO - operating with platform_type : linux_ 04:19:23 INFO - Initialising browser for kraken test... 04:19:23 INFO - TEST-INFO | started process 21821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFiId1/profile http://localhost:54488/getInfo.html) 04:19:28 INFO - PROCESS | 21821 | __metrics Screen width/height:1600/1200 04:19:28 INFO - PROCESS | 21821 | colorDepth:24 04:19:28 INFO - PROCESS | 21821 | Browser inner width/height: 1024/697 04:19:28 INFO - PROCESS | 21821 | __metrics 04:19:29 ERROR - PROCESS | 21821 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 04:19:29 INFO - TEST-INFO | 21821: exit 0 04:19:29 INFO - Browser initialized. 04:19:29 INFO - Running cycle 1/1 for kraken test... 04:19:30 INFO - TEST-INFO | started process 21945 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFiId1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:19:41 INFO - PROCESS | 21945 | RSS: Main: 152383488 04:19:41 INFO - PROCESS | 21945 | 04:19:44 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-beat-detection) 04:19:45 INFO - PROCESS | 21945 | RSS: Main: 158060544 04:19:45 INFO - PROCESS | 21945 | 04:19:47 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-dft) 04:19:48 INFO - PROCESS | 21945 | RSS: Main: 157130752 04:19:48 INFO - PROCESS | 21945 | 04:19:51 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-fft) 04:19:51 INFO - PROCESS | 21945 | RSS: Main: 157360128 04:19:51 INFO - PROCESS | 21945 | 04:19:53 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54488/tests/kraken/driver.html?testName=audio-oscillator) 04:19:54 INFO - PROCESS | 21945 | RSS: Main: 157126656 04:19:54 INFO - PROCESS | 21945 | 04:19:56 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:19:57 INFO - PROCESS | 21945 | RSS: Main: 156860416 04:19:57 INFO - PROCESS | 21945 | 04:20:00 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:01 INFO - PROCESS | 21945 | RSS: Main: 156778496 04:20:01 INFO - PROCESS | 21945 | 04:20:04 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54488/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:05 INFO - PROCESS | 21945 | RSS: Main: 156917760 04:20:05 INFO - PROCESS | 21945 | 04:20:07 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54488/tests/kraken/driver.html?testName=json-parse-financial) 04:20:08 INFO - PROCESS | 21945 | RSS: Main: 157929472 04:20:08 INFO - PROCESS | 21945 | 04:20:10 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:10 INFO - PROCESS | 21945 | RSS: Main: 157003776 04:20:10 INFO - PROCESS | 21945 | 04:20:12 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:13 INFO - PROCESS | 21945 | RSS: Main: 157675520 04:20:13 INFO - PROCESS | 21945 | 04:20:14 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:15 INFO - PROCESS | 21945 | RSS: Main: 158228480 04:20:15 INFO - PROCESS | 21945 | 04:20:18 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:18 INFO - PROCESS | 21945 | RSS: Main: 158076928 04:20:18 INFO - PROCESS | 21945 | 04:20:21 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:22 INFO - PROCESS | 21945 | RSS: Main: 158371840 04:20:22 INFO - PROCESS | 21945 | 04:20:23 INFO - PROCESS | 21945 | Cycle 1(1): loaded http://localhost:54488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54488/tests/kraken/driver.html?testName=ai-astar) 04:20:24 INFO - PROCESS | 21945 | RSS: Main: 159133696 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | __start_tp_report 04:20:24 INFO - PROCESS | 21945 | _x_x_mozilla_page_load 04:20:24 INFO - PROCESS | 21945 | _x_x_mozilla_page_load_details 04:20:24 INFO - PROCESS | 21945 | |i|pagename|runs| 04:20:24 INFO - PROCESS | 21945 | |0;ai-astar;102;112;106;110;113;111;109;111;112;109 04:20:24 INFO - PROCESS | 21945 | |1;audio-beat-detection;142;117;147;181;117;149;143;118;177;120 04:20:24 INFO - PROCESS | 21945 | |2;audio-dft;162;160;162;163;162;162;161;165;164;161 04:20:24 INFO - PROCESS | 21945 | |3;audio-fft;74;75;77;179;76;75;75;77;181;76 04:20:24 INFO - PROCESS | 21945 | |4;audio-oscillator;83;83;152;86;83;83;132;84;83;151 04:20:24 INFO - PROCESS | 21945 | |5;imaging-gaussian-blur;118;117;117;116;118;117;117;118;118;117 04:20:24 INFO - PROCESS | 21945 | |6;imaging-darkroom;147;148;148;147;146;147;147;147;147;146 04:20:24 INFO - PROCESS | 21945 | |7;imaging-desaturate;95;95;98;95;96;95;95;95;95;96 04:20:24 INFO - PROCESS | 21945 | |8;json-parse-financial;51;51;51;49;50;49;50;50;50;50 04:20:24 INFO - PROCESS | 21945 | |9;json-stringify-tinderbox;59;59;59;59;60;59;58;59;60;60 04:20:24 INFO - PROCESS | 21945 | |10;stanford-crypto-aes;73;73;73;73;73;73;73;72;78;72 04:20:24 INFO - PROCESS | 21945 | |11;stanford-crypto-ccm;117;118;117;117;118;123;122;140;156;124 04:20:24 INFO - PROCESS | 21945 | |12;stanford-crypto-pbkdf2;154;154;151;150;151;150;156;151;154;149 04:20:24 INFO - PROCESS | 21945 | |13;stanford-crypto-sha256-iterative;65;65;62;61;62;60;68;61;60;60 04:20:24 INFO - PROCESS | 21945 | __end_tp_report 04:20:24 INFO - PROCESS | 21945 | __start_cc_report 04:20:24 INFO - PROCESS | 21945 | _x_x_mozilla_cycle_collect,866 04:20:24 INFO - PROCESS | 21945 | __end_cc_report 04:20:24 INFO - PROCESS | 21945 | __startTimestamp1473333624013__endTimestamp 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | ------- Summary: start ------- 04:20:24 INFO - PROCESS | 21945 | Number of tests: 14 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.31 (3.0%) stddev-sans-first:2.12 04:20:24 INFO - PROCESS | 21945 | Values: 102.0 112.0 106.0 110.0 113.0 111.0 109.0 111.0 112.0 109.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:145.00 stddev:23.86 (16.5%) stddev-sans-first:25.30 04:20:24 INFO - PROCESS | 21945 | Values: 142.0 117.0 147.0 181.0 117.0 149.0 143.0 118.0 177.0 120.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:1.48 (0.9%) stddev-sans-first:1.56 04:20:24 INFO - PROCESS | 21945 | Values: 162.0 160.0 162.0 163.0 162.0 162.0 161.0 165.0 164.0 161.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#3] audio-fft Cycles:10 Average:96.50 Median:75.50 stddev:44.02 (58.3%) stddev-sans-first:45.93 04:20:24 INFO - PROCESS | 21945 | Values: 74.0 75.0 77.0 179.0 76.0 75.0 75.0 77.0 181.0 76.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:83.00 stddev:30.16 (36.3%) stddev-sans-first:31.19 04:20:24 INFO - PROCESS | 21945 | Values: 83.0 83.0 152.0 86.0 83.0 83.0 132.0 84.0 83.0 151.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 04:20:24 INFO - PROCESS | 21945 | Values: 118.0 117.0 117.0 116.0 118.0 117.0 117.0 118.0 118.0 117.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 04:20:24 INFO - PROCESS | 21945 | Values: 147.0 148.0 148.0 147.0 146.0 147.0 147.0 147.0 147.0 146.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 04:20:24 INFO - PROCESS | 21945 | Values: 95.0 95.0 98.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.74 (1.5%) stddev-sans-first:0.71 04:20:24 INFO - PROCESS | 21945 | Values: 51.0 51.0 51.0 49.0 50.0 49.0 50.0 50.0 50.0 50.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 04:20:24 INFO - PROCESS | 21945 | Values: 59.0 59.0 59.0 59.0 60.0 59.0 58.0 59.0 60.0 60.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:1.70 (2.3%) stddev-sans-first:1.80 04:20:24 INFO - PROCESS | 21945 | Values: 73.0 73.0 73.0 73.0 73.0 73.0 73.0 72.0 78.0 72.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:122.50 stddev:12.87 (10.5%) stddev-sans-first:13.30 04:20:24 INFO - PROCESS | 21945 | Values: 117.0 118.0 117.0 117.0 118.0 123.0 122.0 140.0 156.0 124.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:152.50 stddev:2.31 (1.5%) stddev-sans-first:2.33 04:20:24 INFO - PROCESS | 21945 | Values: 154.0 154.0 151.0 150.0 151.0 150.0 156.0 151.0 154.0 149.0 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - PROCESS | 21945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.00 stddev:2.72 (4.4%) stddev-sans-first:2.71 04:20:24 INFO - PROCESS | 21945 | Values: 65.0 65.0 62.0 61.0 62.0 60.0 68.0 61.0 60.0 60.0 04:20:24 INFO - PROCESS | 21945 | -------- Summary: end -------- 04:20:24 INFO - PROCESS | 21945 | 04:20:24 INFO - TEST-INFO | 21945: exit 0 04:20:24 INFO - TEST-OK | kraken | took 61007ms 04:20:24 INFO - SUITE-END | took 1172s 04:20:25 INFO - Completed test suite (00:19:32) 04:20:25 INFO - javascript benchmark 04:20:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7777.011917147513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.6038075430947, 3322.667733226677, 3341.0311009807174, 3361.924184261037, 3367.7474189435798, 5750.252467179272, 5755.280589926259, 5767.307923049229, 5839.503642190413, 5841.357450680422, 5758.452946970042, 5808.2158908547335, 5870.589058765888, 5873.030949893273, 5887.793927212549, 5282.445343238962, 5296.443873393296, 5326.360836699582, 5349.893002139955, 5372.838814835542, 5157.6647517911215, 5163.690178589297, 5211.088059589576, 5258.13240815265, 5316.548093412064, 5289.86775330616, 5299.125644268691, 5313.017091838003, 5371.4897084776885, 5391.541718953894, 5352.116900711378, 5362.624616276862, 5382.085045542267, 5396.487333703292, 5401.4868587484125, 5053.115704751691, 5078.822241221539, 5109.4379618241965, 5254.264402983586, 5276.419593844713, 5651.8304450866535, 5676.574256930747, 5700.059490184115, 5714.171445140473, 5730.083186690152, 6058.182145410357, 6074.301455332649, 6133.754649814002, 6138.232720909887, 6165.0, 6006.939930600682, 6110.103144028831, 6154.78458253959, 6173.228346456693, 6175.166352542439, 5807.0998995155815, 5812.215350927613, 5814.098814683731, 5819.039858423397, 5841.649501029951, 6342.39747224013, 6355.682215889187, 6362.745490180433, 6373.075903993895, 6389.616623002635, 5855.238818953509, 6055.697215139226, 6066.272047354258, 6087.269527656621, 6113.32753397126, 6164.106204600308, 6182.752689892354, 6223.471004964538, 6225.221847269091, 6230.875382492325, 6204.534659900525, 6214.9689251553455, 6224.844378890564, 6226.59527130735, 6244.438000579969, 5940.70296485174, 5995.910061349084, 6028.969855150784, 6181.381861813873, 6222.0355844844125, 4673.158831410379, 4682.906341873144, 4690.061987602493, 4715.056988602293, 4717.292406139038, 4203.5376108628025, 4230.344296634026, 4230.407742915994, 4230.61924426803, 4245.0, 5680.289963754531, 5790.971045144832, 5875.588708790429, 5912.940870591325, 5922.585419020627, 6166.691665416801, 6172.598781079305, 6176.536759743038, 6176.8764624707255, 6189.226346706661, 5910.113482977587, 5927.110933360031, 5927.9703601481715, 5930.169776231331, 5957.0, 5306.469353064663, 5321.547668448148, 5350.759215835296, 5388.649737767033, 5406.08096623575, 6463.7091330890225, 6483.384078512629, 6490.837729056811, 6523.902141467882, 6524.380183882524, 6294.339094395023, 6307.873842523032, 6312.936870631419, 6324.146240257506, 6328.873422531431, 6054.364291749479, 6338.58799178052, 6380.074889140956, 6384.553081284265, 6452.032195170762, 6132.122542660378, 6142.324344322032, 6145.446909778141, 6145.67867908384, 6146.907796382969, 6197.163382943245, 6202.193714817045, 6208.758248350257, 6248.562600617913, 6271.216097987752, 5897.292324921037, 6074.240719910012, 6090.086487026982, 6100.572959892765, 6128.938710612837, 6105.992511235774, 6126.816195514141, 6151.108089327112, 6164.260288765377, 6173.6913154343, 6103.978949047132, 6124.631323455516, 6131.816045518642, 6143.293521245114, 6162.845928851635, 5836.328822185334, 5975.55183361241, 5999.460048595647, 6027.60820546663, 6152.538559607958, 5901.616394934315, 5913.793017244376, 5926.051831706906, 5932.110183472513, 5939.287285525765], "value": 5713.239587354784, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.9428057194281, 597.9402059794019, 621.4158690830619, 639.4404895716249, 659.3143131143611, 40543.18913621726, 44697.77651111748, 45186.32220516686, 45206.32190517145, 45261.0, 44361.778191109, 45228.32157517632, 45232.77383613077, 45418.091638167214, 45536.3169552457, 9095.636174553027, 9136.360454768135, 9139.63441462344, 9169.312301577374, 9209.539523023823, 9066.410683305598, 9067.909320906805, 9146.039665835091, 9161.541922903862, 9196.448213107202, 9197.86203206949, 9199.540022998823, 9207.723768286929, 9257.074292570724, 9267.119623635777, 9147.22248608869, 9151.771205719844, 9154.221891139261, 9156.404833685823, 9168.358214924958, 9096.226820720247, 9101.63593456261, 9172.12864777845, 9183.17351438367, 9187.862182067274, 43585.56414435879, 43589.346159807625, 43659.126817463475, 43721.78139109316, 44033.339499907524, 3079.491883839164, 3533.876314328986, 3639.217568222845, 3670.871519496805, 3750.5686846012513, 32502.0, 33060.338793224, 33079.17302067443, 33227.33545329104, 33355.332893342, 4526.796294166737, 4542.159700455426, 4566.10960862632, 4579.908401831978, 4586.266197408432, 331550.0, 373374.0, 379974.0, 547339.0, 548971.0, 63903.680481597294, 64502.0, 64767.35232647708, 64791.67604161949, 64827.675861621334, 39802.60197398047, 65670.0, 66207.33792662108, 66222.66888665526, 66351.33648663452, 18613.0, 19305.613887722167, 19393.32123375675, 19413.0, 19414.611707765765, 12528.561500347445, 12535.498580056877, 12551.937240313922, 12563.434645440977, 12573.87426125727, 20713.792862071186, 20912.68630970537, 21014.894925525274, 21066.0, 21352.359429217147, 7832.490888092256, 7875.133735289113, 7949.046114466301, 7953.602319883983, 8093.3929955252415, 1007.450939238117, 1085.1535802074395, 1143.1483544759042, 1156.5489459110954, 1180.9468573914194, 34047.489287660705, 34100.31799363999, 34185.658143418244, 34324.48513272303, 34339.70503582836, 1129.7966366054188, 1156.5778490850778, 1157.6295585412588, 1172.5192671005018, 1225.2709637765515, 17753.911230444024, 17958.5510362242, 18017.459476215736, 18236.726449103273, 18304.17631206599, 592621.0, 596123.0, 596452.0, 597002.0, 602688.0, 65788.0, 66200.33799662038, 66347.66826165935, 66719.0, 66802.66598667073, 1434.6269969807925, 1452.6878716526126, 1465.3405967314538, 1478.2608695652175, 1486.1677460622086, 32076.83961580177, 33278.50082248817, 33603.83198083994, 33608.66391336056, 33761.15597110092, 57989.710051449474, 58325.125123122336, 58360.4163958355, 58429.415705842395, 58448.415515846, 496559.0, 496771.0, 497807.0, 498830.0, 500782.0, 30809.845950771, 31029.68970310268, 31584.210394740316, 31877.36245275128, 31925.68074319227, 52263.21605175849, 52364.0, 53243.402697919126, 53264.46735532595, 54944.0, 43874.561254387045, 46293.53706462892, 46904.06191876212, 47238.76380618075, 47480.52519474899, 47304.7634761824, 47331.290030650256, 47600.0, 47606.52393476021, 47843.76078119587, 37670.434943475324, 44343.0, 45041.0, 45466.31800522929, 45759.54240457686, 38666.613333867426, 39782.37581336282, 39878.80060599678, 40276.79861600673, 41021.38467922924, 30390.84804575963, 30431.0, 30850.845745771127, 30856.382872342878, 31010.379792404477], "value": 21623.8288025028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.99164016719686, 427.26891237172396, 427.96362309203715, 431.2302539966161, 434.5220257716512, 19523.12145953432, 20357.49106272344, 21350.466238344048, 21385.786142138575, 21584.568308633818, 19782.0, 21008.0, 21514.247001354957, 21606.459838504044, 21664.3500694979, 5678.233438485813, 5805.071188609823, 5829.708514574254, 5858.472737453629, 5867.266591676041, 6019.939800602004, 6051.606645568046, 6077.817665470021, 6084.330723620398, 6137.6624285664275, 5984.850378740544, 5988.221531200959, 5989.0, 5990.490808281302, 5997.100434934773, 5977.163197152402, 5996.880062398771, 6017.759289628431, 6034.275886893557, 6075.544334174911, 5252.23842542833, 5438.673679579238, 5479.369872464677, 5484.698341591211, 5486.1222204447495, 23965.7603423967, 24219.636705449433, 24331.878340608182, 24915.377115572075, 24935.376615584573, 2460.766227208412, 2477.256822953107, 2546.337952132459, 2578.3425226567106, 2726.3865630233236, 10822.783544329148, 11077.335359878432, 13478.989075819352, 15399.846001540065, 15489.380424782996, 1949.736785533949, 1982.326009156879, 2033.4509682385822, 2042.652749032668, 2106.837564507662, 70942.64528677323, 91002.0, 91780.0, 94722.52638736762, 95904.0, 9901.851472227921, 10639.946800266103, 10642.308249963728, 10642.521086551124, 10729.624463143753, 14558.563243102724, 14594.635134121732, 14598.051126676732, 14767.335469903664, 14768.335424905905, 8225.177482251847, 8312.33501319893, 8362.665493380318, 8591.914080859236, 8670.436421632574, 8511.57442127891, 8511.95744021276, 8816.955915220382, 8825.955870220609, 8983.775405614912, 4376.737395756265, 4477.440319960005, 4478.798454069574, 4518.774061296987, 4536.841210557614, 2030.6040322137042, 2076.6677331626865, 2085.822705070086, 2111.313823007498, 2143.9356819295444, 972.8443449048118, 1050.0601961244643, 1070.7376692710336, 1089.3137820576578, 1139.703677043958, 20838.791612083987, 21634.459138521663, 21652.210863098895, 21762.40631458361, 21819.67270490912, 1040.3237895368072, 1046.858674079005, 1058.4548957287004, 1065.3181963543338, 1152.7175841918784, 11448.54205831774, 11733.941330293294, 11879.287242765462, 11997.880021199677, 12145.635630931087, 413130.0, 424110.0, 424966.0, 566195.0, 575165.0, 68783.0, 107159.0, 109269.45365273282, 109374.0, 110051.0, 1026.9012156992096, 1062.8512008318842, 1092.8633920759905, 1141.1897552737648, 1150.396042077899, 17961.371352002618, 18196.363127290482, 18517.629647406706, 19428.708569371753, 19689.901550492155, 44161.0, 44600.776996114815, 45129.774351128035, 46139.76930115328, 47056.29415558838, 60211.69894150501, 61075.38924610697, 63629.681851590445, 64964.35035649772, 65751.34248657452, 8831.205191532792, 9247.855729052155, 9424.0, 9430.0, 9469.763255918657, 14009.649758756113, 14206.360713767699, 14269.785953210503, 14340.354684039035, 14703.485378011717, 11856.0, 11911.285322880654, 12232.633021009384, 12389.566365177176, 12850.357482126043, 11477.942610286895, 11674.964509227832, 12585.685357866127, 12632.74734505323, 12753.362331883554, 12343.69140771488, 12447.688807779878, 12505.624831255076, 12576.496940122659, 12696.428660710106, 8485.066642669424, 8493.702720404755, 8565.700200492953, 8612.48325100496, 9136.08639136081, 11256.268342558034, 12000.219985700902, 12210.75578488443, 12352.0, 12376.443060062498], "value": 9778.48329199948, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1558.6648870492845, 1560.8205056418512, 1783.456045905999, 1847.1226167570396, 1872.5599484121221, 8393.496390216591, 9105.135012173841, 9137.177654011137, 9889.10998010179, 9911.0, 9279.072092790735, 9636.421814691106, 9637.710868673934, 9646.228301735862, 9648.228141748661, 2529.051605647882, 2540.136353639762, 2551.1836212412036, 2554.961675574868, 2560.539102961466, 2479.4917042006346, 2571.2414837622946, 2576.278641980239, 2577.896884124633, 2580.458103798195, 2509.5608268553024, 2517.6852893388327, 2545.7836083932793, 2550.579154439515, 2552.719200887901, 2453.5706251406023, 2467.5805113130723, 2472.7403622619636, 2521.936951576225, 2523.8864251108744, 2431.088341871798, 2443.2670198940423, 2444.4622183119864, 2444.535538247745, 2448.963265551018, 9580.95209523948, 9636.71089867305, 9681.564329605184, 9705.320627556073, 9724.75688107796, 5633.521150702195, 5753.108268218391, 5783.5951483396175, 5794.710264486759, 5809.419058094199, 3679.4480827875764, 3808.9809550952345, 3812.4090765931323, 3847.4228865670093, 3896.084420161288, 1148.1561052626314, 1160.0661467518644, 1249.1505776072268, 1254.9309787961658, 1269.961901142958, 13380.732385352238, 13471.124376915566, 13495.527656531975, 13510.797338039938, 13555.0, 4689.788959496831, 5050.090983622909, 5056.797728090835, 5125.359330083739, 5126.410462796826, 11207.215494915277, 11210.327380357205, 11219.0, 11231.26996745209, 11243.100551955824, 5733.827985160452, 5746.569007324384, 5751.166080918327, 5762.193292938992, 5766.394528574523, 4569.771511424416, 5195.610329225323, 5240.423553409122, 5245.6852588844795, 5323.095073837457, 4360.520342762294, 4366.476022877275, 4399.824007039682, 4415.050764085737, 4431.778411079433, 1326.2440408966988, 1357.6470117769254, 1367.931603419825, 1368.5620601407454, 1388.7430825297351, 949.6533765175661, 1025.958961641526, 1041.1878734587035, 1049.6903413493037, 1069.1767339148967, 6247.500199983991, 6285.277193122758, 6337.144485494399, 6481.837954051187, 6620.271770105284, 478.930555069513, 496.3720893070269, 504.0221969379392, 1061.619894137618, 1078.3422112511362, 4240.257954857888, 4317.546657600969, 4363.781810909441, 4394.923243805289, 4401.977990110093, 11675.649730508097, 11861.46623401949, 11896.643100706993, 11954.222975506564, 12162.452689628988, 11778.646640600795, 11836.763264734656, 11871.109666775026, 11885.584004559973, 11933.761324773455, 8765.474071555727, 9046.185843274005, 9076.909230907739, 9095.68112623666, 9124.087591240823, 6455.193100862392, 6477.1579694639395, 6488.480921526361, 6501.967490162708, 6615.04081908111, 10890.727731806768, 11186.32882027081, 11210.0, 11243.662690118983, 11251.043661288555, 11082.501287442246, 11122.388268645302, 11146.554137834395, 11206.159538034843, 11246.268992515461, 7602.277783610548, 8167.83664326722, 8324.6670133194, 8361.414700970874, 8483.915160848312, 9318.068193180628, 9371.765705857408, 9414.293927955294, 9417.011213822723, 9426.62293508252, 8326.875096873673, 8510.446820956618, 8558.700445484377, 8564.700235491728, 8593.398462107842, 8316.916830831615, 8415.158484151536, 8441.915580844114, 8472.406931514735, 8481.83036339282, 7521.511101778586, 7668.769936901902, 7716.344110750649, 7760.534367937942, 7763.495372800976, 5304.310439642822, 5322.175062865107, 5340.5994550408095, 5434.184872269191, 5487.890242195054, 5519.668819870821, 5521.2270282161335, 5542.722863856871, 5672.290963629546, 5730.684812335195], "value": 4998.861545801081, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.6014543420501, 354.73394953784657, 356.65939028228036, 368.77504722119517, 370.51462584014945, 5635.859103522414, 5737.397573254811, 5895.675737834417, 5927.881442371156, 5972.761089556423, 5753.165790960308, 5771.220885180499, 5782.884342313151, 5792.218050563172, 5806.738696758645, 4518.819247230107, 4530.229860923634, 4534.8639540813665, 4571.331464015627, 4578.519255478177, 4487.599134463063, 4523.5024147343765, 4534.614557762594, 4560.33875088112, 4569.200389931749, 4447.599006313001, 4461.553844615545, 4506.97746511267, 4510.684252102355, 4521.502634710196, 4518.570735780112, 4522.34426008227, 4539.7301349325335, 4539.863804085882, 4568.885777855547, 3890.3580909149955, 3984.7011474139267, 3989.860354887565, 4042.393640953851, 4053.61490658387, 5618.634788738719, 5666.660000399948, 5702.631368471579, 5746.36789953109, 5754.280714910637, 5211.530962213381, 5237.3453318335205, 5253.212018197263, 5279.208118782175, 5295.682259064469, 5790.131480277992, 6059.7273122709585, 6083.878322433527, 6090.23872015998, 6121.755129794803, 4259.0, 4276.652854350869, 4285.0, 4295.205387003414, 4307.892302692458, 6070.544709146788, 6288.559800813945, 6293.811185664392, 6324.557280990332, 6327.5570710050315, 4428.911421771547, 4446.866049157444, 4461.13007963444, 4461.687681862239, 4474.664400169935, 4534.342520334533, 4541.227991241497, 4545.136424079415, 4559.589636932626, 4569.360289559464, 5179.306760484527, 5198.246254293106, 5209.270702101709, 5214.426413094557, 5222.321098257202, 5919.200907877467, 5941.851453713692, 6097.817065488042, 6102.870968870773, 6123.96938015316, 1541.029151634463, 1559.5321403579107, 1562.7499600064164, 1563.4059057558054, 1565.2486806333025, 962.916718691472, 995.0596686131681, 1005.5676059294574, 1014.4319181258371, 1014.8731408573929, 5227.660202086929, 5247.845473995715, 5249.816256431007, 5308.681479111266, 5311.0, 5574.972125139429, 5613.158026296007, 5616.971915140398, 5618.157276408571, 5625.774969001276, 4992.801727585354, 5249.0, 5281.049411105961, 5298.496642818926, 5337.546308563816, 2958.45268625305, 2959.4377068356944, 2978.2852115492124, 2980.269833890667, 2986.4773664609042, 5677.489025987681, 5692.9146062809095, 5708.600397972102, 5713.971430142823, 5743.62666426681, 5260.895212005522, 5572.247746554163, 5616.466435688603, 5617.662940223599, 5648.548116150698, 5759.128283307941, 5763.242211125563, 5772.3938986406665, 5785.84283143373, 5816.27296587935, 5570.86072848182, 5579.875269848898, 5596.692181930033, 5908.586398952204, 6306.842328941813, 6283.905741413974, 6287.46556542699, 6296.111166650078, 6299.307076221704, 6303.810885673437, 6380.489560835215, 6536.640484773383, 6555.770548030796, 6562.3109573496, 6586.80239592813, 4444.933326000178, 4470.731756094645, 4496.527864574173, 4498.762840218966, 4520.660950428665, 6188.752449902134, 6324.462420694293, 6337.302896681269, 6404.519661025535, 6425.1968503937005, 6166.440927015238, 6191.442770150708, 6236.18929539157, 6240.4071613196675, 6247.562670613013, 6100.664463454553, 6195.597286176384, 6197.907031394442, 6213.627182369072, 6221.595596286227, 6165.722542485689, 6167.938320616916, 6178.0, 6185.134081228722, 6214.161088253028, 6225.190725205694, 6268.968655156695, 6298.779542715983, 6299.149614802126, 6300.21247344082, 5905.881882362414, 6027.156198132353, 6028.487578555696, 6068.302145253176, 6121.204243448323], "value": 4553.43972395974, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.9480683755555, 803.0564087197541, 820.0036955099553, 852.4586887326547, 890.6927110146997, 16597.41909033184, 17725.46823595291, 18019.4594162175, 18072.457826265232, 18184.09079546024, 16108.75836862448, 16145.677086458263, 16210.189490525485, 16212.027278363306, 16256.105914174716, 652.8531080506896, 862.8964524257098, 866.2160744526183, 890.2922176869372, 895.4985208283382, 886.601029536711, 890.9420887642315, 893.0444424465824, 893.5175005497024, 894.9910500894973, 939.6805086270666, 941.1576638908207, 942.4015749998731, 944.8771659684265, 947.5688561704443, 931.3154831199063, 936.1013427109982, 937.0208132501552, 937.9624815007392, 944.0, 686.0532465198045, 687.8177283020003, 691.9688614012382, 694.4652617484509, 697.2400083908514, 16246.187690615421, 16286.75569866441, 16347.591310217222, 16399.344026239054, 16453.83546164535, 16531.586710332216, 16700.331986720517, 16850.325986960506, 16920.238589263514, 17369.7394539082, 13595.796063059062, 13626.1143025703, 13752.518661846787, 13789.379477923418, 13954.232517211549, 3989.900252493682, 4004.619561141687, 4038.2932986727205, 4080.7959602019782, 4085.9387109193385, 82306.0, 85110.57444712736, 85153.14846851514, 85434.0, 88292.0, 12362.567310144103, 12403.889811945905, 12439.0, 12468.314242716557, 12503.0, 25311.36721581975, 25318.873405632854, 25385.238442846745, 25395.984160633736, 25542.10602628899, 9007.189352958134, 9207.769805754775, 9292.907070929205, 9378.109079637423, 9416.670416535388, 5118.078745825787, 5199.506046925536, 5297.152455607084, 5319.627626066138, 5358.5177334039345, 3927.116398810245, 4083.387491876242, 4109.363048727452, 4134.37984302351, 4150.6471949884, 617.102116420607, 622.5859803230801, 624.3319647976622, 626.5582764151262, 632.4845251120322, 645.3560593687994, 691.1774987764544, 718.6263143165629, 770.9630546914391, 839.2446797881955, 10239.287149540936, 10442.0, 10622.521986510626, 10810.081143102925, 10824.24230303871, 691.6126968897433, 708.4226355520337, 721.3147509865648, 772.5017363800317, 833.8749187621784, 4734.431868175841, 5062.898742025139, 5140.434552199255, 5150.793968241228, 5207.3230480037355, 114065.42967285111, 119207.0, 119847.0, 121459.0, 121562.39218803849, 98183.0, 100409.99999999854, 101229.49385253027, 102134.48932755437, 102923.4853825741, 69664.0, 86016.56991714876, 86813.5659321712, 87208.0, 87259.0, 9618.326717130014, 9799.216062715108, 9831.066048725359, 10003.899961000297, 10236.078752912274, 51115.74442127765, 51434.74282628563, 51750.48249517457, 51902.0, 52985.0, 75075.62462187873, 75935.24064759281, 77428.22571774435, 77911.61044194743, 79414.0, 12648.74702505963, 12731.108822382344, 12933.805992910302, 12960.740785184433, 13324.067315287837, 14458.566243012727, 14519.564413067625, 14611.92694036523, 14648.0, 15124.773128402863, 13347.866521334663, 13362.933185334012, 13895.722085558435, 14150.716985660023, 14204.076735012191, 13301.26843023643, 13589.252591107585, 13875.236861972688, 14482.34829432657, 14492.130472171704, 12917.28954907489, 13386.799198011842, 13782.379792909544, 14031.158130512202, 14359.712805744035, 10825.67522974312, 11062.834057488983, 11199.16006299547, 11306.038986686224, 11306.94346528262, 9604.0, 11110.777784444428, 11198.832017519906, 11349.0, 11490.655280341269], "value": 8045.215421852236, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 106.0, 110.0, 113.0, 111.0, 109.0, 111.0, 112.0, 109.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 147.0, 181.0, 117.0, 149.0, 143.0, 118.0, 177.0, 120.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 163.0, 162.0, 162.0, 161.0, 165.0, 164.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 179.0, 76.0, 75.0, 75.0, 77.0, 181.0, 76.0], "value": 96.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 152.0, 86.0, 83.0, 83.0, 132.0, 84.0, 83.0, 151.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 98.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 49.0, 50.0, 49.0, 50.0, 50.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 58.0, 59.0, 60.0, 60.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 73.0, 72.0, 78.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 123.0, 122.0, 140.0, 156.0, 124.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 151.0, 150.0, 151.0, 150.0, 156.0, 151.0, 154.0, 149.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 62.0, 61.0, 62.0, 60.0, 68.0, 61.0, 60.0, 60.0], "value": 62.4, "unit": "score"}]}]} 04:20:25 INFO - Return code: 0 04:20:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:20:25 ERROR - # TBPL SUCCESS # 04:20:25 INFO - Running post-action listener: _resource_record_post_action 04:20:25 INFO - [mozharness: 2016-09-08 11:20:25.333667Z] Finished run-tests step (success) 04:20:25 INFO - Running post-run listener: _resource_record_post_run 04:20:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 327487488; Read time: 24; Write time: 396700 04:20:25 INFO - TinderboxPrint: CPU usage
13.3% 04:20:25 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 04:20:25 INFO - TinderboxPrint: I/O write bytes / time
327,487,488 / 396,700 04:20:25 INFO - TinderboxPrint: CPU idle
8,226.9 (86.7%) 04:20:25 INFO - TinderboxPrint: CPU user
1,202.0 (12.7%) 04:20:25 INFO - TinderboxPrint: Swap in / out
0 / 0 04:20:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78503936; Read time: 0; Write time: 183288 04:20:25 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248696832; Read time: 16; Write time: 213392 04:20:25 INFO - Running post-run listener: _upload_blobber_files 04:20:25 INFO - Blob upload gear active. 04:20:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:20:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:20:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:20:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:29 INFO - (blobuploader) - INFO - Done attempting. 04:20:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:20:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:30 INFO - (blobuploader) - INFO - Done attempting. 04:20:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:20:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:20:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:31 INFO - (blobuploader) - INFO - Done attempting. 04:20:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:20:31 INFO - Return code: 0 04:20:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:20:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:20:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23c4b958b8b8e24b427e568af2e582bf4065ad3c0b9a9b7c6bd6531d6bf55dae4d2f70bd9b84597680538eb9aaa33d60d8a91cd96e7676235943d228f25d9dae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faf5fca90200c7760dd8dd9466f34a09d8835f4dc4a9377ec4dc9e1600caee6cb615b81c468be45a0d3ffd9c6a38c4668c7d45cd1c32f3258b88396677c20a52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8af5108ed4b7c024713138390246232657a8f619125d4b42f32da13f04f72fb4f33a1c3c1712f74442421df99d244c8a637c546a590ee8267d9ae20bb33a6165"} 04:20:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:20:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:20:31 INFO - Contents: 04:20:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23c4b958b8b8e24b427e568af2e582bf4065ad3c0b9a9b7c6bd6531d6bf55dae4d2f70bd9b84597680538eb9aaa33d60d8a91cd96e7676235943d228f25d9dae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faf5fca90200c7760dd8dd9466f34a09d8835f4dc4a9377ec4dc9e1600caee6cb615b81c468be45a0d3ffd9c6a38c4668c7d45cd1c32f3258b88396677c20a52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8af5108ed4b7c024713138390246232657a8f619125d4b42f32da13f04f72fb4f33a1c3c1712f74442421df99d244c8a637c546a590ee8267d9ae20bb33a6165"} 04:20:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:20:31 INFO - Copying logs to upload dir... 04:20:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:20:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.005544 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-08 04:20:30.983219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 04:20:30.986730) ========= 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=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23c4b958b8b8e24b427e568af2e582bf4065ad3c0b9a9b7c6bd6531d6bf55dae4d2f70bd9b84597680538eb9aaa33d60d8a91cd96e7676235943d228f25d9dae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faf5fca90200c7760dd8dd9466f34a09d8835f4dc4a9377ec4dc9e1600caee6cb615b81c468be45a0d3ffd9c6a38c4668c7d45cd1c32f3258b88396677c20a52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8af5108ed4b7c024713138390246232657a8f619125d4b42f32da13f04f72fb4f33a1c3c1712f74442421df99d244c8a637c546a590ee8267d9ae20bb33a6165"} build_url:https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011397 build_url: 'https://queue.taskcluster.net/v1/task/Cxfa1WcBSteMiYfGrR9Wvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23c4b958b8b8e24b427e568af2e582bf4065ad3c0b9a9b7c6bd6531d6bf55dae4d2f70bd9b84597680538eb9aaa33d60d8a91cd96e7676235943d228f25d9dae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/faf5fca90200c7760dd8dd9466f34a09d8835f4dc4a9377ec4dc9e1600caee6cb615b81c468be45a0d3ffd9c6a38c4668c7d45cd1c32f3258b88396677c20a52", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8af5108ed4b7c024713138390246232657a8f619125d4b42f32da13f04f72fb4f33a1c3c1712f74442421df99d244c8a637c546a590ee8267d9ae20bb33a6165"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 04:20:31.018738) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:20:31.019325) ========= 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=5bd038846534b2fdfa68eeae000001e2-1473330609.135988-404840296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 04:20:31.100078) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 04:20:31.100396) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 04:20:31.100683) ========= ========= Total master_lag: 0.18 =========