builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-067 starttime: 1471667990.98 results: success (0) buildid: 20160819184422 builduid: 696d33822efc441cab7f5928ccd54e75 revision: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:50.982354) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:50.982860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:50.983215) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:50.997857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:50.998167) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 21:39:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2016-08-19 21:39:51 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094333 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.106593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.106906) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015719 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.172862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.173255) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 --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 f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 --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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 21:39:51,267 truncating revision to first 12 chars 2016-08-19 21:39:51,268 Setting DEBUG logging. 2016-08-19 21:39:51,268 attempt 1/10 2016-08-19 21:39:51,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f4aa3c3af595?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 21:39:51,604 unpacking tar archive at: mozilla-aurora-f4aa3c3af595/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.847485) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.848308) ========= script_repo_revision: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.849211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.849545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 21:39:51.860484) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-08-19 21:39:51.861175) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:39:52 INFO - MultiFileLogger online at 20160819 21:39:52 in /builds/slave/test 21:39:52 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 21:39:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:39:52 INFO - {'append_to_log': False, 21:39:52 INFO - 'base_work_dir': '/builds/slave/test', 21:39:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:39:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:39:52 INFO - 'branch': 'Mozilla-Aurora', 21:39:52 INFO - 'buildbot_json_path': 'buildprops.json', 21:39:52 INFO - 'config_files': ('talos/linux_config.py',), 21:39:52 INFO - 'default_actions': ('clobber', 21:39:52 INFO - 'read-buildbot-config', 21:39:52 INFO - 'download-and-extract', 21:39:52 INFO - 'populate-webroot', 21:39:52 INFO - 'create-virtualenv', 21:39:52 INFO - 'install', 21:39:52 INFO - 'run-tests'), 21:39:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:39:52 INFO - 'download_minidump_stackwalk': True, 21:39:52 INFO - 'download_symbols': 'ondemand', 21:39:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:39:52 INFO - 'tooltool.py': '/tools/tooltool.py', 21:39:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:39:52 INFO - '/tools/misc-python/virtualenv.py')}, 21:39:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:39:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:39:52 INFO - 'installer_path': 'installer.exe', 21:39:52 INFO - 'log_level': 'info', 21:39:52 INFO - 'log_name': 'talos', 21:39:52 INFO - 'log_to_console': True, 21:39:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:39:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:39:52 INFO - 'opt_config_files': (), 21:39:52 INFO - 'pip_index': False, 21:39:52 INFO - 'sps_profile': False, 21:39:52 INFO - 'sps_profile_interval': 0, 21:39:52 INFO - 'suite': 'dromaeojs-e10s', 21:39:52 INFO - 'system_bits': '32', 21:39:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:39:52 INFO - 'title': 'talos-linux64-ix-067', 21:39:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:39:52 INFO - 'use_talos_json': True, 21:39:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:39:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:39:52 INFO - 'work_dir': 'build'} 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.050332Z] Running clobber step. 21:39:52 INFO - Running pre-action listener: _resource_record_pre_action 21:39:52 INFO - Running main action method: clobber 21:39:52 INFO - rmtree: /builds/slave/test/build 21:39:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:39:52 INFO - Running post-action listener: _resource_record_post_action 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.492548Z] Finished clobber step (success) 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.492670Z] Running read-buildbot-config step. 21:39:52 INFO - Running pre-action listener: _resource_record_pre_action 21:39:52 INFO - Running main action method: read_buildbot_config 21:39:52 INFO - Using buildbot properties: 21:39:52 INFO - { 21:39:52 INFO - "project": "", 21:39:52 INFO - "product": "firefox", 21:39:52 INFO - "script_repo_revision": "production", 21:39:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 21:39:52 INFO - "repository": "", 21:39:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 21:39:52 INFO - "buildid": "20160819184422", 21:39:52 INFO - "pgo_build": "True", 21:39:52 INFO - "basedir": "/builds/slave/test", 21:39:52 INFO - "buildnumber": 494, 21:39:52 INFO - "slavename": "talos-linux64-ix-067", 21:39:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:39:52 INFO - "platform": "ubuntu64_hw", 21:39:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:39:52 INFO - "branch": "mozilla-aurora", 21:39:52 INFO - "repo_path": "releases/mozilla-aurora", 21:39:52 INFO - "revision": "f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2", 21:39:52 INFO - "stage_platform": "linux64-pgo", 21:39:52 INFO - "builduid": "696d33822efc441cab7f5928ccd54e75", 21:39:52 INFO - "slavebuilddir": "test" 21:39:52 INFO - } 21:39:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 21:39:52 INFO - Running post-action listener: _resource_record_post_action 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.495151Z] Finished read-buildbot-config step (success) 21:39:52 INFO - [mozharness: 2016-08-20 04:39:52.495242Z] Running download-and-extract step. 21:39:52 INFO - Running pre-action listener: _resource_record_pre_action 21:39:52 INFO - Running main action method: download_and_extract 21:39:52 INFO - mkdir: /builds/slave/test/build/tests 21:39: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')]} 21:39:52 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:39:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:39:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:39:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:39:52 INFO - retry: Failed, sleeping 30 seconds before retrying 21:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:40:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:40:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:40:22 INFO - retry: Failed, sleeping 60 seconds before retrying 21:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:41:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:41:22 INFO - Caught exception: HTTP Error 404: Not Found 21:41:22 INFO - Caught exception: HTTP Error 404: Not Found 21:41:22 INFO - Caught exception: HTTP Error 404: Not Found 21:41:22 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:22 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 INFO - Downloaded 1361 bytes. 21:41:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:41:23 INFO - Using the following test package requirements: 21:41:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:41:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:41:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:41:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'jsshell-linux-x86_64.zip'], 21:41:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:41:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:41:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:41:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:41:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:41:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:41:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:41:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:41:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:23 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:41:23 INFO - retry: Failed, sleeping 30 seconds before retrying 21:41:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:41:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:41:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:41:53 INFO - retry: Failed, sleeping 60 seconds before retrying 21:42:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:42:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:42:53 INFO - Caught exception: HTTP Error 404: Not Found 21:42:53 INFO - Caught exception: HTTP Error 404: Not Found 21:42:53 INFO - Caught exception: HTTP Error 404: Not Found 21:42:53 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:54 INFO - Downloaded 21768454 bytes. 21:42:54 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'] 21:42:54 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 21:42:55 INFO - Return code: 0 21:42:55 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 21:42:55 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')]} 21:42:55 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:42:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:42:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:42:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:42:55 INFO - retry: Failed, sleeping 30 seconds before retrying 21:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:43:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:43:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:43:25 INFO - retry: Failed, sleeping 60 seconds before retrying 21:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:44:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:44:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:44:25 INFO - Caught exception: HTTP Error 404: Not Found 21:44:25 INFO - Caught exception: HTTP Error 404: Not Found 21:44:25 INFO - Caught exception: HTTP Error 404: Not Found 21:44:25 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:44:25 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:26 INFO - Downloaded 21768454 bytes. 21:44:26 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'] 21:44:26 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 21:44:27 INFO - Return code: 0 21:44:27 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')]} 21:44:27 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:44:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:44:27 INFO - retry: Failed, sleeping 30 seconds before retrying 21:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:44:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:44:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:44:57 INFO - retry: Failed, sleeping 60 seconds before retrying 21:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:45:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:45:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:45:57 INFO - Caught exception: HTTP Error 404: Not Found 21:45:57 INFO - Caught exception: HTTP Error 404: Not Found 21:45:57 INFO - Caught exception: HTTP Error 404: Not Found 21:45:57 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:45:57 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 INFO - Downloaded 14413723 bytes. 21:45:59 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'] 21:45:59 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 21:45:59 INFO - Return code: 0 21:45:59 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')]} 21:45:59 INFO - https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:45:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:45:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:45:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:45:59 INFO - retry: Failed, sleeping 30 seconds before retrying 21:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:46:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:46:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:46:29 INFO - retry: Failed, sleeping 60 seconds before retrying 21:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cbhpT2i2QyCCG44TUvBuFw/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! 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - Caught exception: HTTP Error 404: Not Found 21:47:29 INFO - trying https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:29 INFO - Downloading https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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 21:47:31 INFO - Downloaded 63751724 bytes. 21:47:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:31 INFO - mkdir: /builds/slave/test/properties 21:47:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:31 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:31 INFO - Contents: 21:47:31 INFO - build_url:https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:47:31 INFO - Running post-action listener: _resource_record_post_action 21:47:31 INFO - Running post-action listener: set_extra_try_arguments 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.721933Z] Finished download-and-extract step (success) 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.722165Z] Running populate-webroot step. 21:47:31 INFO - Running pre-action listener: _resource_record_pre_action 21:47:31 INFO - Running main action method: populate_webroot 21:47:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:31 INFO - '--tppagecycles', 21:47:31 INFO - '7'], 21:47:31 INFO - 'tests': ['tsvgm']}}, 21:47:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['damp', 'tps']}, 21:47:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['damp', 'tps']}, 21:47:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['dromaeo_dom']}, 21:47:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['basic_compositor_video']}, 21:47:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:47:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['a11yr', 21:47:31 INFO - 'ts_paint', 21:47:31 INFO - 'tpaint', 21:47:31 INFO - 'sessionrestore', 21:47:31 INFO - 'sessionrestore_no_auto_restore', 21:47:31 INFO - 'tabpaint']}, 21:47:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tsvgx', 21:47:31 INFO - 'tsvgr_opacity', 21:47:31 INFO - 'tart', 21:47:31 INFO - 'tscrollx', 21:47:31 INFO - 'cart']}, 21:47:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:31 INFO - 'tsvgr_opacity', 21:47:31 INFO - 'tart', 21:47:31 INFO - 'tscrollx', 21:47:31 INFO - 'cart']}, 21:47:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s'], 21:47:31 INFO - 'tests': ['tp5o']}, 21:47:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'tests': ['tp5o']}, 21:47:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--disable-e10s', 21:47:31 INFO - '--xperf_path', 21:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:31 INFO - 'tests': ['tp5n']}, 21:47:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:31 INFO - 'talos_options': ['--xperf_path', 21:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:31 INFO - 'tests': ['tp5n']}}, 21:47:31 INFO - 'talos.zip': {'path': '', 21:47:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:31 INFO - Running post-action listener: _resource_record_post_action 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.742277Z] Finished populate-webroot step (success) 21:47:31 INFO - [mozharness: 2016-08-20 04:47:31.742367Z] Running create-virtualenv step. 21:47:31 INFO - Running pre-action listener: _resource_record_pre_action 21:47:31 INFO - Running main action method: create_virtualenv 21:47:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47:31 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 21:47:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:31 INFO - Using real prefix '/usr' 21:47:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:47:34 INFO - Installing pip.................done. 21:47:34 INFO - Return code: 0 21:47:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:47:34 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')]} 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 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')]} 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:34 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 21:47:34 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 21:47:34 INFO - Using env: {'DISPLAY': ':0', 21:47:34 INFO - 'HOME': '/home/cltbld', 21:47:34 INFO - 'LANG': 'en_US.UTF-8', 21:47:34 INFO - 'LANGUAGE': 'en_US:en', 21:47:34 INFO - 'LOGNAME': 'cltbld', 21:47:34 INFO - 'MAIL': '/var/mail/cltbld', 21:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:34 INFO - 'MOZ_NO_REMOTE': '1', 21:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:34 INFO - 'NO_EM_RESTART': '1', 21:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:34 INFO - 'PWD': '/builds/slave/test', 21:47:34 INFO - 'SHELL': '/bin/bash', 21:47:34 INFO - 'SHLVL': '1', 21:47:34 INFO - 'TERM': 'linux', 21:47:34 INFO - 'TMOUT': '86400', 21:47:34 INFO - 'USER': 'cltbld', 21:47:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:34 INFO - '_': '/tools/buildbot/bin/python'} 21:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:34 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 21:47:34 INFO - Cleaning up... 21:47:34 INFO - Return code: 0 21:47:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:47:34 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')]} 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 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')]} 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:34 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:34 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 21:47:34 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 21:47:34 INFO - Using env: {'DISPLAY': ':0', 21:47:34 INFO - 'HOME': '/home/cltbld', 21:47:34 INFO - 'LANG': 'en_US.UTF-8', 21:47:34 INFO - 'LANGUAGE': 'en_US:en', 21:47:34 INFO - 'LOGNAME': 'cltbld', 21:47:34 INFO - 'MAIL': '/var/mail/cltbld', 21:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:34 INFO - 'MOZ_NO_REMOTE': '1', 21:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:34 INFO - 'NO_EM_RESTART': '1', 21:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:34 INFO - 'PWD': '/builds/slave/test', 21:47:34 INFO - 'SHELL': '/bin/bash', 21:47:34 INFO - 'SHLVL': '1', 21:47:34 INFO - 'TERM': 'linux', 21:47:34 INFO - 'TMOUT': '86400', 21:47:34 INFO - 'USER': 'cltbld', 21:47:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:34 INFO - '_': '/tools/buildbot/bin/python'} 21:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:34 INFO - Downloading/unpacking psutil>=3.1.1 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:47:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:36 INFO - Installing collected packages: psutil 21:47:36 INFO - Running setup.py install for psutil 21:47:36 INFO - building 'psutil._psutil_linux' extension 21:47:36 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 21:47:36 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 21:47:36 INFO - building 'psutil._psutil_posix' extension 21:47:36 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 21:47:37 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 21:47:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:37 INFO - Successfully installed psutil 21:47:37 INFO - Cleaning up... 21:47:37 INFO - Return code: 0 21:47:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:47:37 INFO - Using env: {'DISPLAY': ':0', 21:47:37 INFO - 'HOME': '/home/cltbld', 21:47:37 INFO - 'LANG': 'en_US.UTF-8', 21:47:37 INFO - 'LANGUAGE': 'en_US:en', 21:47:37 INFO - 'LOGNAME': 'cltbld', 21:47:37 INFO - 'MAIL': '/var/mail/cltbld', 21:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:37 INFO - 'MOZ_NO_REMOTE': '1', 21:47:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:37 INFO - 'NO_EM_RESTART': '1', 21:47:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:37 INFO - 'PWD': '/builds/slave/test', 21:47:37 INFO - 'SHELL': '/bin/bash', 21:47:37 INFO - 'SHLVL': '1', 21:47:37 INFO - 'TERM': 'linux', 21:47:37 INFO - 'TMOUT': '86400', 21:47:37 INFO - 'USER': 'cltbld', 21:47:37 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:37 INFO - '_': '/tools/buildbot/bin/python'} 21:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:47:37 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 21:47:37 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 21:47:37 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 21:47:37 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 21:47:37 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 21:47:37 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 21:47:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:47:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:47:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:47:39 INFO - Installing collected packages: mozsystemmonitor 21:47:39 INFO - Running setup.py install for mozsystemmonitor 21:47:39 INFO - Successfully installed mozsystemmonitor 21:47:39 INFO - Cleaning up... 21:47:39 INFO - Return code: 0 21:47:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:47:39 INFO - Using env: {'DISPLAY': ':0', 21:47:39 INFO - 'HOME': '/home/cltbld', 21:47:39 INFO - 'LANG': 'en_US.UTF-8', 21:47:39 INFO - 'LANGUAGE': 'en_US:en', 21:47:39 INFO - 'LOGNAME': 'cltbld', 21:47:39 INFO - 'MAIL': '/var/mail/cltbld', 21:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:39 INFO - 'MOZ_NO_REMOTE': '1', 21:47:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:39 INFO - 'NO_EM_RESTART': '1', 21:47:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:39 INFO - 'PWD': '/builds/slave/test', 21:47:39 INFO - 'SHELL': '/bin/bash', 21:47:39 INFO - 'SHLVL': '1', 21:47:39 INFO - 'TERM': 'linux', 21:47:39 INFO - 'TMOUT': '86400', 21:47:39 INFO - 'USER': 'cltbld', 21:47:39 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:39 INFO - '_': '/tools/buildbot/bin/python'} 21:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:39 INFO - Downloading/unpacking jsonschema==2.5.1 21:47:39 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 21:47:39 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 21:47:39 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 21:47:39 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 21:47:39 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 21:47:39 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 21:47:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:47:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:47:41 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 21:47:41 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 21:47:41 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 21:47:41 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 21:47:41 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 21:47:41 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 21:47:41 INFO - Downloading functools32-3.2.3-2.tar.gz 21:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:47:41 INFO - warning: no files found matching '*.txt' 21:47:41 INFO - no previously-included directories found matching 'build' 21:47:41 INFO - no previously-included directories found matching 'dist' 21:47:41 INFO - no previously-included directories found matching '.git*' 21:47:41 INFO - Installing collected packages: jsonschema, functools32 21:47:41 INFO - Running setup.py install for functools32 21:47:41 INFO - warning: no files found matching '*.txt' 21:47:41 INFO - no previously-included directories found matching 'build' 21:47:41 INFO - no previously-included directories found matching 'dist' 21:47:41 INFO - no previously-included directories found matching '.git*' 21:47:42 INFO - Successfully installed jsonschema functools32 21:47:42 INFO - Cleaning up... 21:47:42 INFO - Return code: 0 21:47:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:42 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:42 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 21:47:42 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 21:47:42 INFO - Using env: {'DISPLAY': ':0', 21:47:42 INFO - 'HOME': '/home/cltbld', 21:47:42 INFO - 'LANG': 'en_US.UTF-8', 21:47:42 INFO - 'LANGUAGE': 'en_US:en', 21:47:42 INFO - 'LOGNAME': 'cltbld', 21:47:42 INFO - 'MAIL': '/var/mail/cltbld', 21:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:42 INFO - 'MOZ_NO_REMOTE': '1', 21:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:42 INFO - 'NO_EM_RESTART': '1', 21:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:42 INFO - 'PWD': '/builds/slave/test', 21:47:42 INFO - 'SHELL': '/bin/bash', 21:47:42 INFO - 'SHLVL': '1', 21:47:42 INFO - 'TERM': 'linux', 21:47:42 INFO - 'TMOUT': '86400', 21:47:42 INFO - 'USER': 'cltbld', 21:47:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:42 INFO - '_': '/tools/buildbot/bin/python'} 21:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:47:42 INFO - Cleaning up... 21:47:42 INFO - Return code: 0 21:47:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:42 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:42 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 21:47:42 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 21:47:42 INFO - Using env: {'DISPLAY': ':0', 21:47:42 INFO - 'HOME': '/home/cltbld', 21:47:42 INFO - 'LANG': 'en_US.UTF-8', 21:47:42 INFO - 'LANGUAGE': 'en_US:en', 21:47:42 INFO - 'LOGNAME': 'cltbld', 21:47:42 INFO - 'MAIL': '/var/mail/cltbld', 21:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:42 INFO - 'MOZ_NO_REMOTE': '1', 21:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:42 INFO - 'NO_EM_RESTART': '1', 21:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:42 INFO - 'PWD': '/builds/slave/test', 21:47:42 INFO - 'SHELL': '/bin/bash', 21:47:42 INFO - 'SHLVL': '1', 21:47:42 INFO - 'TERM': 'linux', 21:47:42 INFO - 'TMOUT': '86400', 21:47:42 INFO - 'USER': 'cltbld', 21:47:42 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:42 INFO - '_': '/tools/buildbot/bin/python'} 21:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:42 INFO - Downloading/unpacking blobuploader==1.2.4 21:47:42 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 21:47:42 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 21:47:42 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 21:47:42 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 21:47:42 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 21:47:42 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 21:47:44 INFO - Downloading blobuploader-1.2.4.tar.gz 21:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:47:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:47:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:44 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 21:47:45 INFO - Downloading docopt-0.6.1.tar.gz 21:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:47:45 INFO - Installing collected packages: blobuploader, requests, docopt 21:47:45 INFO - Running setup.py install for blobuploader 21:47:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:47:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:47:45 INFO - Running setup.py install for requests 21:47:45 INFO - Running setup.py install for docopt 21:47:45 INFO - Successfully installed blobuploader requests docopt 21:47:45 INFO - Cleaning up... 21:47:45 INFO - Return code: 0 21:47:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:45 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')]} 21:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:45 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')]} 21:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:45 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:45 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 21:47:45 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 21:47:45 INFO - Using env: {'DISPLAY': ':0', 21:47:45 INFO - 'HOME': '/home/cltbld', 21:47:45 INFO - 'LANG': 'en_US.UTF-8', 21:47:45 INFO - 'LANGUAGE': 'en_US:en', 21:47:45 INFO - 'LOGNAME': 'cltbld', 21:47:45 INFO - 'MAIL': '/var/mail/cltbld', 21:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:45 INFO - 'MOZ_NO_REMOTE': '1', 21:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:45 INFO - 'NO_EM_RESTART': '1', 21:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:45 INFO - 'PWD': '/builds/slave/test', 21:47:45 INFO - 'SHELL': '/bin/bash', 21:47:45 INFO - 'SHLVL': '1', 21:47:45 INFO - 'TERM': 'linux', 21:47:45 INFO - 'TMOUT': '86400', 21:47:45 INFO - 'USER': 'cltbld', 21:47:45 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:45 INFO - '_': '/tools/buildbot/bin/python'} 21:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:45 INFO - Running setup.py (path:/tmp/pip-ixwdbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:46 INFO - Running setup.py (path:/tmp/pip-k2FLnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:46 INFO - Running setup.py (path:/tmp/pip-e70G15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:46 INFO - Running setup.py (path:/tmp/pip-EamXRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:46 INFO - Running setup.py (path:/tmp/pip-N5Hyum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:46 INFO - Running setup.py (path:/tmp/pip-oSi4E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:46 INFO - Running setup.py (path:/tmp/pip-3Z9xdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:46 INFO - Running setup.py (path:/tmp/pip-j_FYN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:46 INFO - Running setup.py (path:/tmp/pip-SRPDmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:46 INFO - Running setup.py (path:/tmp/pip-i29AWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:46 INFO - Running setup.py (path:/tmp/pip-mQiJbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:46 INFO - Running setup.py (path:/tmp/pip-GtjYmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:46 INFO - Running setup.py (path:/tmp/pip-x_mFsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:46 INFO - Running setup.py (path:/tmp/pip-65pGyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:46 INFO - Running setup.py (path:/tmp/pip-zycOcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:47 INFO - Running setup.py (path:/tmp/pip-2bWxJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:47 INFO - Running setup.py (path:/tmp/pip-lNSVcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:47:47 INFO - Running setup.py install for manifestparser 21:47:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozcrash 21:47:47 INFO - Running setup.py install for mozdebug 21:47:47 INFO - Running setup.py install for mozdevice 21:47:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozfile 21:47:47 INFO - Running setup.py install for mozhttpd 21:47:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozinfo 21:47:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozInstall 21:47:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:47:47 INFO - Running setup.py install for mozleak 21:47:48 INFO - Running setup.py install for mozlog 21:47:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for moznetwork 21:47:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozprocess 21:47:48 INFO - Running setup.py install for mozprofile 21:47:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozrunner 21:47:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Running setup.py install for mozscreenshot 21:47:48 INFO - Running setup.py install for moztest 21:47:48 INFO - Running setup.py install for mozversion 21:47:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:47:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:47:48 INFO - Cleaning up... 21:47:48 INFO - Return code: 0 21:47:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:48 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')]} 21:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:48 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')]} 21:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:48 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:48 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 21:47:48 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 21:47:48 INFO - Using env: {'DISPLAY': ':0', 21:47:48 INFO - 'HOME': '/home/cltbld', 21:47:48 INFO - 'LANG': 'en_US.UTF-8', 21:47:48 INFO - 'LANGUAGE': 'en_US:en', 21:47:48 INFO - 'LOGNAME': 'cltbld', 21:47:48 INFO - 'MAIL': '/var/mail/cltbld', 21:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:48 INFO - 'MOZ_NO_REMOTE': '1', 21:47:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:48 INFO - 'NO_EM_RESTART': '1', 21:47:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:48 INFO - 'PWD': '/builds/slave/test', 21:47:48 INFO - 'SHELL': '/bin/bash', 21:47:48 INFO - 'SHLVL': '1', 21:47:48 INFO - 'TERM': 'linux', 21:47:48 INFO - 'TMOUT': '86400', 21:47:48 INFO - 'USER': 'cltbld', 21:47:48 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:48 INFO - '_': '/tools/buildbot/bin/python'} 21:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:49 INFO - Running setup.py (path:/tmp/pip-D4ukdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:49 INFO - Running setup.py (path:/tmp/pip-EUg8tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:49 INFO - Running setup.py (path:/tmp/pip-BrOlCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:49 INFO - Running setup.py (path:/tmp/pip-WbpHd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:49 INFO - Running setup.py (path:/tmp/pip-xHps9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:49 INFO - Running setup.py (path:/tmp/pip-Z6vSyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:49 INFO - Running setup.py (path:/tmp/pip-EcwbOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:49 INFO - Running setup.py (path:/tmp/pip-mHgoxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:49 INFO - Running setup.py (path:/tmp/pip-jLn_DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:49 INFO - Running setup.py (path:/tmp/pip-YcQB4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:49 INFO - Running setup.py (path:/tmp/pip-tDNetl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:49 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)) 21:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:49 INFO - Running setup.py (path:/tmp/pip-rJAR_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:50 INFO - Running setup.py (path:/tmp/pip-QD1uMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:50 INFO - Running setup.py (path:/tmp/pip-hblzD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:50 INFO - Running setup.py (path:/tmp/pip-GvS7Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:50 INFO - Running setup.py (path:/tmp/pip-CvtSyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:50 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)) 21:47:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:50 INFO - Running setup.py (path:/tmp/pip-dggD0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:50 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)) 21:47:50 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)) 21:47:50 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)) 21:47:50 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)) 21:47:50 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)) 21:47:50 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)) 21:47:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:47:50 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 21:47:50 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 21:47:50 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 21:47:50 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 21:47:50 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 21:47:50 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 21:47:52 INFO - Downloading blessings-1.6.tar.gz 21:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:47:52 INFO - Installing collected packages: blessings 21:47:52 INFO - Running setup.py install for blessings 21:47:52 INFO - Successfully installed blessings 21:47:52 INFO - Cleaning up... 21:47:52 INFO - Return code: 0 21:47:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:47:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:52 INFO - Reading from file tmpfile_stdout 21:47:52 INFO - Current package versions: 21:47:52 INFO - argparse == 1.2.1 21:47:52 INFO - blessings == 1.6 21:47:52 INFO - blobuploader == 1.2.4 21:47:52 INFO - docopt == 0.6.1 21:47:52 INFO - functools32 == 3.2.3-2 21:47:52 INFO - jsonschema == 2.5.1 21:47:52 INFO - manifestparser == 1.1 21:47:52 INFO - mozInstall == 1.12 21:47:52 INFO - mozcrash == 0.17 21:47:52 INFO - mozdebug == 0.1 21:47:52 INFO - mozdevice == 0.48 21:47:52 INFO - mozfile == 1.2 21:47:52 INFO - mozhttpd == 0.7 21:47:52 INFO - mozinfo == 0.9 21:47:52 INFO - mozleak == 0.1 21:47:52 INFO - mozlog == 3.3 21:47:52 INFO - moznetwork == 0.27 21:47:52 INFO - mozprocess == 0.23 21:47:52 INFO - mozprofile == 0.28 21:47:52 INFO - mozrunner == 6.12 21:47:52 INFO - mozscreenshot == 0.1 21:47:52 INFO - mozsystemmonitor == 0.3 21:47:52 INFO - moztest == 0.7 21:47:52 INFO - mozversion == 1.4 21:47:52 INFO - psutil == 3.1.1 21:47:52 INFO - requests == 1.2.3 21:47:52 INFO - wsgiref == 0.1.2 21:47:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:52 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:52 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 21:47:52 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 21:47:52 INFO - Using env: {'DISPLAY': ':0', 21:47:52 INFO - 'HOME': '/home/cltbld', 21:47:52 INFO - 'LANG': 'en_US.UTF-8', 21:47:52 INFO - 'LANGUAGE': 'en_US:en', 21:47:52 INFO - 'LOGNAME': 'cltbld', 21:47:52 INFO - 'MAIL': '/var/mail/cltbld', 21:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:52 INFO - 'MOZ_NO_REMOTE': '1', 21:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:52 INFO - 'NO_EM_RESTART': '1', 21:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:52 INFO - 'PWD': '/builds/slave/test', 21:47:52 INFO - 'SHELL': '/bin/bash', 21:47:52 INFO - 'SHLVL': '1', 21:47:52 INFO - 'TERM': 'linux', 21:47:52 INFO - 'TMOUT': '86400', 21:47:52 INFO - 'USER': 'cltbld', 21:47:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:52 INFO - '_': '/tools/buildbot/bin/python'} 21:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 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)) 21:47:52 INFO - Cleaning up... 21:47:52 INFO - Return code: 0 21:47:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:52 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 0xfdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', '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 21:47:52 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 21:47:52 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 21:47:53 INFO - Using env: {'DISPLAY': ':0', 21:47:53 INFO - 'HOME': '/home/cltbld', 21:47:53 INFO - 'LANG': 'en_US.UTF-8', 21:47:53 INFO - 'LANGUAGE': 'en_US:en', 21:47:53 INFO - 'LOGNAME': 'cltbld', 21:47:53 INFO - 'MAIL': '/var/mail/cltbld', 21:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:53 INFO - 'MOZ_NO_REMOTE': '1', 21:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:47:53 INFO - 'NO_EM_RESTART': '1', 21:47:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:53 INFO - 'PWD': '/builds/slave/test', 21:47:53 INFO - 'SHELL': '/bin/bash', 21:47:53 INFO - 'SHLVL': '1', 21:47:53 INFO - 'TERM': 'linux', 21:47:53 INFO - 'TMOUT': '86400', 21:47:53 INFO - 'USER': 'cltbld', 21:47:53 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:53 INFO - '_': '/tools/buildbot/bin/python'} 21:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:47:53 INFO - Cleaning up... 21:47:53 INFO - Return code: 0 21:47:53 INFO - Running post-action listener: _resource_record_post_action 21:47:53 INFO - Running post-action listener: _start_resource_monitoring 21:47:53 INFO - Starting resource monitoring. 21:47:53 INFO - [mozharness: 2016-08-20 04:47:53.242031Z] Finished create-virtualenv step (success) 21:47:53 INFO - [mozharness: 2016-08-20 04:47:53.242499Z] Running install step. 21:47:53 INFO - Running pre-action listener: _resource_record_pre_action 21:47:53 INFO - Running main action method: install 21:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:47:53 INFO - Reading from file tmpfile_stdout 21:47:53 INFO - Detecting whether we're running mozinstall >=1.0... 21:47:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:47:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:47:53 INFO - Reading from file tmpfile_stdout 21:47:53 INFO - Output received: 21:47:53 INFO - Usage: mozinstall [options] installer 21:47:53 INFO - Options: 21:47:53 INFO - -h, --help show this help message and exit 21:47:53 INFO - -d DEST, --destination=DEST 21:47:53 INFO - Directory to install application into. [default: 21:47:53 INFO - "/builds/slave/test"] 21:47:53 INFO - --app=APP Application being installed. [default: firefox] 21:47:53 INFO - mkdir: /builds/slave/test/build/application 21:47:53 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'] 21:47:53 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 21:48:10 INFO - Reading from file tmpfile_stdout 21:48:10 INFO - Output received: 21:48:10 INFO - /builds/slave/test/build/application/firefox/firefox 21:48:10 INFO - Running post-action listener: _resource_record_post_action 21:48:10 INFO - [mozharness: 2016-08-20 04:48:10.105272Z] Finished install step (success) 21:48:10 INFO - [mozharness: 2016-08-20 04:48:10.105548Z] Running run-tests step. 21:48:10 INFO - Running pre-action listener: _resource_record_pre_action 21:48:10 INFO - Running main action method: run_tests 21:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:10 INFO - Python 2.7.3 21:48:10 INFO - Return code: 0 21:48:10 INFO - grabbing minidump binary from tooltool 21:48: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')]} 21:48:10 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 0x129a570>, '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 0x129b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129b900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:10 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 21:48:10 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 21:48:10 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 21:48:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:10 INFO - Return code: 0 21:48:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:48:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:48:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 21:48:10 INFO - Using env: {'DISPLAY': ':0', 21:48:10 INFO - 'HOME': '/home/cltbld', 21:48:10 INFO - 'LANG': 'en_US.UTF-8', 21:48:10 INFO - 'LANGUAGE': 'en_US:en', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MAIL': '/var/mail/cltbld', 21:48:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SHLVL': '1', 21:48:10 INFO - 'TERM': 'linux', 21:48:10 INFO - 'TMOUT': '86400', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - '_': '/tools/buildbot/bin/python'} 21:48:10 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/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 21:48:10 INFO - mozversion application_buildid: 20160819184422 21:48:10 INFO - mozversion application_changeset: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 21:48:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:48:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:10 INFO - mozversion application_name: Firefox 21:48:10 INFO - mozversion application_remotingname: firefox-dev 21:48:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:10 INFO - mozversion application_vendor: Mozilla 21:48:10 INFO - mozversion application_version: 50.0a2 21:48:10 INFO - mozversion platform_buildid: 20160819184422 21:48:10 INFO - mozversion platform_changeset: f4aa3c3af595bf44fcfbc8ec7b6190ed207090e2 21:48:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:10 INFO - mozversion platform_version: 50.0a2 21:48:10 INFO - using testdate: 1471668490 21:48:10 INFO - actual date: 1471668490 21:48:10 INFO - starting webserver on 'localhost:43514' 21:48:10 INFO - SUITE-START | Running 2 tests 21:48:10 INFO - TEST-START | dromaeo_css 21:48:10 INFO - operating with platform_type : linux_ 21:48:10 INFO - Initialising browser for dromaeo_css test... 21:48:10 INFO - TEST-INFO | started process 7096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TzgRz/profile http://localhost:43514/getInfo.html) 21:48:14 INFO - PROCESS | 7096 | __metrics Screen width/height:1600/1200 21:48:14 INFO - PROCESS | 7096 | colorDepth:24 21:48:14 INFO - PROCESS | 7096 | Browser inner width/height: 1024/697 21:48:14 INFO - PROCESS | 7096 | __metrics 21:48:14 ERROR - PROCESS | 7096 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 21:48:15 INFO - TEST-INFO | 7096: exit 0 21:48:15 INFO - Browser initialized. 21:48:15 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:15 INFO - TEST-INFO | started process 7224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1TzgRz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:48:27 INFO - PROCESS | 7224 | RSS: Main: 152313856 21:48:27 INFO - PROCESS | 7224 | 21:49:16 INFO - PROCESS | 7224 | 1471668556924 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] 21:51:25 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43514/tests/dromaeo/cssquery-ext.html) 21:51:26 INFO - PROCESS | 7224 | RSS: Main: 157720576 21:51:26 INFO - PROCESS | 7224 | 21:54:28 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-ext.html (next: http://localhost:43514/tests/dromaeo/cssquery-jquery.html) 21:54:28 INFO - PROCESS | 7224 | RSS: Main: 153595904 21:54:28 INFO - PROCESS | 7224 | 21:57:30 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43514/tests/dromaeo/cssquery-mootools.html) 21:57:31 INFO - PROCESS | 7224 | RSS: Main: 154095616 21:57:31 INFO - PROCESS | 7224 | 22:00:33 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43514/tests/dromaeo/cssquery-prototype.html) 22:00:33 INFO - PROCESS | 7224 | RSS: Main: 152670208 22:00:33 INFO - PROCESS | 7224 | 22:03:36 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43514/tests/dromaeo/cssquery-yui.html) 22:03:36 INFO - PROCESS | 7224 | RSS: Main: 155439104 22:03:36 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | Cycle 1(1): loaded http://localhost:43514/tests/dromaeo/cssquery-yui.html (next: http://localhost:43514/tests/dromaeo/cssquery-dojo.html) 22:06:39 INFO - PROCESS | 7224 | RSS: Main: 155521024 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | __start_tp_report 22:06:39 INFO - PROCESS | 7224 | _x_x_mozilla_page_load 22:06:39 INFO - PROCESS | 7224 | _x_x_mozilla_page_load_details 22:06:39 INFO - PROCESS | 7224 | |i|pagename|runs| 22:06:39 INFO - PROCESS | 7224 | |0;dojo.html;3271.9018429447115;3294.4893541501074;3301.2407146356336;3303.0421177858416;3315.403227419065;5700.629459085162;5722.914156287648;5744.885102297952;5756.597038207322;5762.279715035621;5632.7746890124345;5649.12438572021;5697.202391665171;5701.914471282934;5723.227364305818;5141.922871156927;5169.09540830355;5173.198154286091;5179.663321884065;5242.030224408478;5074.517920797518;5090.720010399426;5176.50823171798;5178.5339319461245;5223.033738758323;5166.535011848933;5180;5202.375714914216;5210.348706411699;5273.288106105665;5242.81650142247;5253.0544501989625;5260.579153667697;5260.842174734745;5271.262023316739;4992.276119962585;5007.223880298559;5153.304303918961;5201.843944681666;5229.398619158807;5598.964191624522;5670.773169073233;5674.687892165929;5689.260396148474;5711.600187986843;6105.694715264263;6117.816465506042;6119.908201376983;6136.263648362181;6164.7534098636;5980.372060933626;6059.6364218147055;6184.598001129957;6186.319504854462;6195.132681424604;5675.290588676416;5770.798022069207;5774.451427114459;5775.249217601685;5783.768649254067;6210.813675589739;6247.031710084944;6271.592346497463;6287.2769631492965;6287.842803929847;5865.794697185578;5875.794347197828;5999.220101386879;6029.969850150721;6093.908591371133;5965.6718880461995;6084.452399283997;6097.695115244309;6100.664463454464;6113.052476866093;6015.969920150371;6059.303180134341;6067.302260240041;6071.908921366183;6083.908741368883;5869.266341707286;5970.164177015221;6039.245094363205;6055.031195008777;6062.242219722535;4609.95390046102;4677.438707355139;4688.109259240733;4693.084848454587;4695.859124226278;4171.91656166875;4173.040200753843;4181.351890456984;4193.874183774492;4220.155968806251;5681.289838770153;5682.914756278659;5884.529237661062;5899.085641725539;5909.438603332676;6049.243844519435;6113.719560719409;6124.545046617194;6126.975740326312;6142.109394137825;5838.153467747153;5881.647101173943;5886.117082437669;5894.587378883437;5911.793087242012;5166.638335316567;5199.66202196856;5251.553617942442;5261.289725887033;5268.762905669254;6301.369863013753;6382.329855365213;6384.7126879290545;6429.517786165963;6456.192975878015;6087.66517841523;6109.6639684817765;6130.111133885562;6148.077788331786;6199.16311297969;6125.937554509019;6263.27972283175;6263.655498947602;6419.26178489461;6420.550561460653;6084;6086.695665216633;6096.542759292982;6116.052011938156;6122.785702500391;6144.679916766854;6174.934920784954;6191.9690401547705;6217.285012223652;6221;5878.088896221093;5920.703964801829;5936.37668044849;6090.390960903874;6094.053247076387;5992.400759923973;6002;6014.789482368096;6016.278046634432;6037.698115094316;5967.671778052249;5981.043033114681;6001.339852616122;6017.939820601738;6019.247594050744;5810.215620891295;5919.112133179885;5930.169776231417;6053.878922421615;6099.542534309856;5852.736626851717;5856.033754430641;5874.412558744091;5885.47030767233;5892.175095486549 22:06:39 INFO - PROCESS | 7224 | |1;ext.html;588.8851673923584;617.1637431280615;626.1609443345916;652.4030512081445;674.0933444517123;41026.17947641049;47011.29483057757;47151;47226.76386618066;47485.52514474854;46932.29601555971;47658.52341476584;47691.99999999991;47720.76139619297;47863.28205076927;9113.407628504143;9262.305327100494;9265.629374825;9278.257739380868;9293.907060929405;9247.121523455295;9354.298427617921;9358.251339892793;9362.157405833474;9382.718518444457;9304.7673808155;9316.487593182372;9356.719298421025;9371;9378.249740020781;9203.125703058198;9243.352965292432;9264.629414823432;9320.02139775324;9341.392809467363;9196.678116265966;9300.069993000714;9314.254859611216;9331.906680933173;9370.625174992992;44247.557524424665;44732.77633611811;44863.551364486266;44961.77519112384;45151.322730159074;3153.731932785716;3650.7627004244905;3667.6149004354693;3688.0779805048737;3813.7521061130938;33988.49017264743;34492;34507.82746086278;34517.482237766206;34592.48111278333;4660.37084993525;4669.579737823578;4672.672912896099;4672.953270467286;4746.644001699852;324601;325844.37077814696;325927;327171;328361;39176.41235381472;39227.41158882619;39272.41091383631;39354.803225983684;39476;39493.60506394899;40287;40495.79752101221;40696;40780;19141;19416;19463.80536194648;19572.608547828964;19606.509837254183;12534.059945504123;12581.056420768478;12596.937015314865;12610.432530536147;12679.112462127747;21620;21647.242346517796;21673.34979950604;21873.453163671034;22024.559508810056;7658.119316278699;7680.155182929873;7701.614919254015;7752.7674169775;7824.726134585376;1088.9074428673493;1091.9071878890384;1148.4315263944234;1171.308927732642;1207.6497815633368;35056;35129.64870351264;35409.46885796715;35526.64473355233;35757.64242357543;1176.1825531255865;1213.3751118174148;1230.194222784078;1272.9172603780726;1279.4434421026883;18925.905370473058;18959.336423225122;19076;19123.521911952033;19170.329038483585;607552;611398;613361;615065;616898;66485.6675716628;67060.32939670638;67096.32903670998;67365;67394.3260567388;1429.349645911108;1446.8987170897935;1453;1489.5233525272026;1495.1851241073641;33619;34715;34893;34988.825055875575;35405.46891796677;58639.413605863396;59151.40848591459;59171.112433314396;59350.70324648349;59525.70237148787;517126;523087;524777;524962;527804;30780.23049423782;31336;31654;32020.35959280848;32021.35957280888;52417.7379113102;52782.2082668768;52961.47038529565;53818.46181538134;54872;42936.35595466133;45490.54509454863;46155.76922115368;47008;47420.52579474299;44085.77957110194;45188.54811451843;45238.547614523435;45362.319565205886;47348;39354.803225983684;44343.77828110839;45875;46027.53972460367;47233.763831182;39068.666925149555;40168.79915600403;40787;40907.15917783766;41344.37983430311;30425.84787076139;31568.36863262676;31676.524852126775;31694.841525792224;32013.67986320107 22:06:39 INFO - PROCESS | 7224 | |2;jquery.html;420.8674267605706;424.4015937528082;429.39026582253206;432.900432900433;438.96927215094934;19436.51408714783;20683.586328273424;20828.687569686466;21019.57960840782;21135.577288454242;19693.916834574127;20894.268700595483;20902.163913443444;21299.787002129975;21555;5894.764209431618;6152.600080994721;6159.90760138596;6171.321154672983;6175.413335733098;5762.3949485304065;6010.248718910137;6048.727807248684;6067.0899365095365;6154.569180157391;6129.111278864562;6133.723982420802;6135.693215339215;6144.078388241777;6144.44699977002;5896.027155519334;5942.554308426886;5957.553183511232;6001.549883758692;6002.129691194796;5598.24423702799;5805.477507024346;5815.912761308584;5846.3861294563885;5865.794697185621;14740.778888316683;15045.473408430762;15072.397104115822;15091.698166036727;15153.318100685496;2304.5275718477674;2378.500514891875;2504;2569.4732579821093;2755.8484283364405;10259.43573103479;10643.78712425755;11123.944380278046;11350.602728904449;11403.3728144952;1989.9602007959759;2003.1086166655843;2049.6515592349338;2069.7205877206534;2101.48513614164;85952;86162;86527.56736216342;88452.55773721155;90262;10517.894821051845;10548.156147508182;10752.0323170915;10828.945855270673;10847.132229421628;14283.42866285337;14335.06822056563;14342.49801256951;14362.210078445576;14380.993330466767;8483.83032339362;8656.74029779095;8705.303575713928;8706.608202630898;8909.866352004725;8816.515091669891;8897.421667591585;8928.955355223183;9068.591913363915;9146.359754817233;4333.328334108218;4420.160169567772;4444.955550444519;4687.156311863897;4925.113479573711;2076.408223656281;2078.3557097299886;2100.842436817245;2112.746470423559;2128.7445506539316;1007.2093406675724;1007.7480629842539;1025.917926565873;1030.64957914308;1052.8631277933819;20328.69506957397;20853.270135545183;21089.156433742784;21199;21654.133834646436;1035.7100011996654;1054.520193312041;1074.4412905289205;1080.4813689429118;1155.3760969076604;11571.710707232218;11582.826257606142;11868.821967670492;11948.701282467833;12484.937575312248;520664.3966780142;525812;535704;537881;541471;63253.36746632567;69451.65274173698;78638;99311;100503.49748251359;1079.7138758229075;1096.3641088168843;1125.9662210133708;1128.3455595754442;1129.5030186717818;18753.1561079749;18991.620167596848;19244.903775481034;19330.71003934914;19527.316543921464;44463.55536444724;45318.77340613276;45449.31826022546;45831.31253031274;46090;64183.67908160429;65706.34293657003;65761.67119164373;66429.66785166043;67185.3281467179;8942.73171804847;8943.776405589912;8990.280777537911;9102.681406151018;9248.121428464285;10469;13428.798568021683;14054.718905622034;14312.427502900184;14319.642008949442;12369.381530923238;12485.687857803627;12625.494980200689;12658.556950506689;13376.331183440594;11708.94145529301;12401.069919756235;12424.875751242733;12484.750304994031;12574.685632859251;12181.573644922386;12340.938295308466;12408.81386779217;12909;13035.608931732064;7722.343600793995;8172.38707096982;8212.753617391489;8710.520921349133;9345.252379809735;9457.43255404678;11014.889851101387;11178;12019.75960480768;12543.12198146121 22:06:39 INFO - PROCESS | 7224 | |3;mootools.html;1592.3869310315529;1594.0754362469768;1773.4679596121168;1827.6801559727044;1880.1727240014393;8586.828263434743;9270.814583708321;9320.533973301342;9965.402075875452;9979.451130187837;9605.08751668592;9688.564014619342;9737.902620973788;9789.510524473784;9799.16707079899;2509.4102885821817;2520.0297885314194;2561.7950563954837;2562.705288891783;2564.064116597438;2509.849409035464;2589.49504846555;2590.870456477178;2593.922182334533;2599.3241757143182;2553.4510080332725;2563.4488584954233;2582.7546383093577;2585.0435338924626;2587.3919628887197;2438.76831700989;2449.375409270642;2457.5576396248666;2480.5410998965067;2495.700515938099;2451.8038556915403;2453.1904471524354;2462.839915405511;2480.937976550583;2517.3706573356662;9725.854112188323;9745.756356091084;9763.658271960518;9779.266555008402;9802.705918822447;5649.971750141223;5746.080627099685;5753.712314384264;5798.159266906275;5808.1578171165365;3484.494748261488;3610.29599228148;3681.7606855554304;3692.667659910594;3707.443883417482;1209.5222387157023;1224.6326102169314;1245.1844042152313;1287.427094942751;1309.5547513845338;14173.43306267748;14218.786718199235;14272.571822845228;14299.57101286963;14334.928325358307;4702.811887524529;5150.536451719363;5159.819406320761;5171.120909445403;5206.661566998133;11713.355765432812;11731.413429328499;11751.059915206763;11751.353675547927;11814.173007889538;5901.3508514063415;5905.409459054146;5951.4941229995065;5956.52347812174;5982.880342393128;5234.9476505235225;5331;5332.866678333073;5442.619016668875;5458.608054945963;4258.1057977824385;4321.6974811762875;4329.328954012133;4370.694051416434;4385.232584297799;1242.9067819913544;1253.1415980053691;1264.5700461843016;1270.247059058512;1273.9235645861093;933.887933447977;964.5418426247477;1003.4832061488339;1022.437659287389;1030.3457304611625;6564.3614739132;6645.06969024337;6720.092787473726;6766.898496522556;6954.895676564855;483.5285596543412;493.1837808427059;495.4723219771004;507.48490282362695;507.5051824471111;4498.527654596221;4684.805374629448;4706.741129237925;4731.574158325767;4745.881352966204;12208.816867746813;12366.44351004207;12525.1232413732;12535.059870509749;12561.371931403393;11547.769044619228;12093.81859272094;12503.874961250453;12537.749245015231;12543.24740515572;9104.499252541042;9228.400153990104;9305.162535371714;9347.252219822398;9347.672831450867;6422.550421470452;6600.339966003361;6618.437432818265;6684.131062961784;6771.661416929035;11114.110871130128;11317.943410282896;11340.886591133984;11537.365444900275;11715.121365897763;10316.896831031894;11141.442927853737;11691.473883675426;11701.356425396345;11750.882491174978;8406.537640429591;8671.6097775599;8793.91206087931;8850.557472126238;8862.335324850534;9378.624855005995;9419.199368053518;9464.763380915258;9505.66730164441;9520.904790952001;8299.336053115858;8301.169883011651;8420.326373889951;8446.239838414573;8612.569371531523;8363.121872203332;8407.285380742705;8413.032501262433;8416.452930559493;8555.529445880287;7579.962100189464;7589.392848572212;7682.154962954182;7685.615719213905;7724.073111226464;5571.693556854249;5646.971765141148;5714.285714285715;5760.596758227051;5868.677222752619;5677.3754886963225;5700.971495142498;5761.547067284668;5917.763289468545;5951.256092988377 22:06:39 INFO - PROCESS | 7224 | |4;prototype.html;352.1442893768143;357.95883473400556;361.95475565554307;362.76782858970245;363.71993564955;5764.769409223626;5769.913451298234;5787.1608616750655;5832.533397328214;5843.240378750762;5633;5695.202671625976;5700.344460387057;5703.942960570404;5730.971345143268;4497.887552811189;4534.251848445003;4537.750423726694;4552.135094332083;4561.1789877821975;4536.614387777043;4555.2256116460285;4561.908761824761;4569.611583015431;4569.611583015448;4484.865454036384;4521.688710274017;4551.840685576006;4569.063342014879;4587.954120458803;4309.35359696045;4492.3710680504755;4493.6743660620195;4500.347449619803;4505.481869585007;3936.685065194806;3940.133170702441;3946.5264168299987;3952.505936757905;3969.2458432897656;5631.8592035199445;5642.7742890284335;5656.151577263402;5667.801626943037;5690.516306113986;4993.475685053054;5118.360204974379;5130.820421285237;5147.819826306099;5152.587792976553;6007.098935159717;6034.125051867499;6061.969690151565;6066.423689749511;6121.816345509642;4265.872023839259;4331.566843315674;4374.49693285273;4376.124775045004;4383.123375324916;6089.939100609025;6318.62088274705;6327.367263273682;6370.617762934239;6384.776532821375;4400.185965596375;4400.867973960722;4409.11817636474;4413.536578659258;4420.182266280748;4407.779611019436;4416.343871048357;4419.64642828573;4422.402975598317;4466.240739074366;5009.273655320031;5096.133657278272;5101.438841727407;5115.616328775357;5145.125328694131;5961.631678101859;6051.425114614105;6058.66709323948;6062.42406971337;6088.620815447381;1535.278419143001;1553.3087775939598;1558.5713928669707;1560.2744723703531;1562;949.1599934058409;992.7220378294089;999.2206079258191;1011.1809434358194;1012.4178597306578;5264.184051472028;5269.947300527022;5279.624169123909;5285.418603953562;5333.759980800874;5509.3664228613425;5529.451753509024;5561.610687251854;5565.387807341189;5649.796579333981;4990.30135780991;5181.378234611795;5256.632035757537;5289.841304760864;5362.785488580413;2926.8390238537086;2946.291151132381;2959.9704002959693;2963.7184467475554;2978.597889284962;5716.314042314865;5784.392638772868;5795.217645617872;5829.950608890355;5853.2440267919455;5952.375000624872;6447.838804029843;6491.902621460682;6492.220933488012;6494.3439422947795;6578.736850525926;6643.435307998778;6644.60132392058;6680.665966701646;6697.363750443603;6137.478314343331;6247.406496382836;6277.654728989949;6284.4972402206795;6358.300586935526;6239.192363436381;6271.96864015677;6281.937180628135;6282.717277722605;6297.307296197323;6268.811935641938;6422.036694495676;6520.609283172629;6538.640374779433;6545.050967609574;4492.573205545468;4522.819087236474;4528.501864794804;4531.912341038061;4536.251518499411;6189.412005859436;6255.499560035095;6315.621062736251;6326.398992095744;6350.68246587678;6094.573379863545;6094.695265236632;6103.816885493442;6136.2636483622255;6145.170401995852;5915.792947246826;6073.180120683651;6103.7253323601435;6151.339138432688;6156.415140561639;5967.6419414835245;6040.248327984835;6046.183765191643;6060.78787242462;6075.1191077294425;6141.9078713818435;6155.446009859134;6193.925035113288;6227.470664993345;6246.625202487683;5778.508826749773;5959.642421454727;5975.25309336333;6011.368806275453;6020.157177994996 22:06:39 INFO - PROCESS | 7224 | |5;yui.html;839.2195258409677;841.4320333774705;893.5532233883058;915.4919019943931;948.554179535618;15811.13038782868;16680;16712.665746685074;16723.331066757346;16728.498145055637;16659.66680666386;16921.407750728726;16940.491785246464;16964.49106526803;16970.15149242536;718.2888939949438;850.3962186847345;875.2910142784333;879.7932485865816;882.849915514364;880.731376930036;880.7886107334226;882.3250213586612;889.3507739350279;889.4752096263203;943.8584212368131;948.4451595816456;948.4546385828141;950.653011650746;951.4196340232453;937.5780898595625;943.0239701908491;944.5796620503878;944.631593678466;945.5886689290112;696.8466937273791;697.8394969157072;697.9162500499922;698.0367093411061;698.8986596943465;15364;15401.845981540153;15420.614484637861;15525.611859703486;15549.455769048029;13770.24263665508;13840.515581954583;14334.211618360985;14371.496997605034;15105.848941510554;13923.373448194856;14153.787693184611;14172.220527870963;14484.710305793931;14775.335109920079;4719.339292499053;4743.691660042086;4759.333693282943;4926.852194434173;4934.925976110361;89008.55495722545;89416;89985.10014899833;92089;92500.53749731276;12199.207051541622;12258.614536658755;12354.814677779841;12361.320127393079;12570.371481425893;22573.88713056457;23066.308010759705;23209.419764506023;23457.41356466102;23505.764942350357;8042.879356809653;8161.918380816234;8227.170962692991;8423.663053477792;8468.957655211685;4760.238361862085;4964.851054468371;4989.675671081368;5026.472220416877;5114.670185751731;3575.0168703606196;3654.5979942206336;3659.70722342212;3664.578573464086;3672.1003354178397;659.874623821482;663.5918909870395;669.8459354348527;672.1598002496879;679.5718697220719;563.4309347558994;600.8618017855918;614.9938500615026;689.2865883810332;713.7715930902061;9736.318457707892;9796.510174491246;9948;9949.751256218653;10350.741231469274;632.253940350391;729.5075823818901;735.749845052674;753.9811504712426;774.5894675821824;4414.646828253732;4797.40032495938;4868.561829435368;4884.438289596671;4921.26181072842;136566;136991;137919;138611;138857;62013;77017.22982770213;93106;121817.39091304487;122163;59221.11168332479;62237;93489.99999999865;94891.52554237185;98891;9797.167240784329;9821.558029888529;10050.8492372613;10486.191599056829;10665.826759056343;54257.72871135619;54493.45506545043;55137.44862551323;55752.16371754346;56060.43939560715;73562;76037.61981190059;82440.17559824325;83892.58053709692;85055.14944850473;14037.298135093408;14328.211948342941;14444.638884027983;14790.852091478948;14858.331375088363;16305.409878561715;17143.65712685764;17146.742798858275;17312.220950057024;17357.39249126325;14409.207493587954;14669.853301466848;15297.31162097686;16130.354785808437;16587.336506539603;14084.859151408355;14189.787153192918;14718.116912985255;15073.321700523722;15723.135227562594;12993.805092923425;13343.532976345781;13427.932860335635;13652.180869147884;14125.788113178516;11623.941880290544;11823;11852.288862668267;11860.288382697066;12266.080043996557;11459.557432230406;11793.587224447103;13006.869931300565;13022.348882555645;13141.868581314064 22:06:39 INFO - PROCESS | 7224 | __end_tp_report 22:06:39 INFO - PROCESS | 7224 | __start_cc_report 22:06:39 INFO - PROCESS | 7224 | _x_x_mozilla_cycle_collect,317 22:06:39 INFO - PROCESS | 7224 | __end_cc_report 22:06:39 INFO - PROCESS | 7224 | __startTimestamp1471669599558__endTimestamp 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | ------- Summary: start ------- 22:06:39 INFO - PROCESS | 7224 | Number of tests: 6 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#0] dojo.html Cycles:165 Average:5682.02 Median:5899.09 stddev:650.89 (11.0%) stddev-sans-first:624.83 22:06:39 INFO - PROCESS | 7224 | Values: 3271.9 3294.5 3301.2 3303.0 3315.4 5700.6 5722.9 5744.9 5756.6 5762.3 5632.8 5649.1 5697.2 5701.9 5723.2 5141.9 5169.1 5173.2 5179.7 5242.0 5074.5 5090.7 5176.5 5178.5 5223.0 5166.5 5180.0 5202.4 5210.3 5273.3 5242.8 5253.1 5260.6 5260.8 5271.3 4992.3 5007.2 5153.3 5201.8 5229.4 5599.0 5670.8 5674.7 5689.3 5711.6 6105.7 6117.8 6119.9 6136.3 6164.8 5980.4 6059.6 6184.6 6186.3 6195.1 5675.3 5770.8 5774.5 5775.2 5783.8 6210.8 6247.0 6271.6 6287.3 6287.8 5865.8 5875.8 5999.2 6030.0 6093.9 5965.7 6084.5 6097.7 6100.7 6113.1 6016.0 6059.3 6067.3 6071.9 6083.9 5869.3 5970.2 6039.2 6055.0 6062.2 4610.0 4677.4 4688.1 4693.1 4695.9 4171.9 4173.0 4181.4 4193.9 4220.2 5681.3 5682.9 5884.5 5899.1 5909.4 6049.2 6113.7 6124.5 6127.0 6142.1 5838.2 5881.6 5886.1 5894.6 5911.8 5166.6 5199.7 5251.6 5261.3 5268.8 6301.4 6382.3 6384.7 6429.5 6456.2 6087.7 6109.7 6130.1 6148.1 6199.2 6125.9 6263.3 6263.7 6419.3 6420.6 6084.0 6086.7 6096.5 6116.1 6122.8 6144.7 6174.9 6192.0 6217.3 6221.0 5878.1 5920.7 5936.4 6090.4 6094.1 5992.4 6002.0 6014.8 6016.3 6037.7 5967.7 5981.0 6001.3 6017.9 6019.2 5810.2 5919.1 5930.2 6053.9 6099.5 5852.7 5856.0 5874.4 5885.5 5892.2 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#1] ext.html Cycles:180 Average:65112.99 Median:44538.28 stddev:133638.73 (300.1%) stddev-sans-first:133925.81 22:06:39 INFO - PROCESS | 7224 | Values: 588.9 617.2 626.2 652.4 674.1 41026.2 47011.3 47151.0 47226.8 47485.5 46932.3 47658.5 47692.0 47720.8 47863.3 9113.4 9262.3 9265.6 9278.3 9293.9 9247.1 9354.3 9358.3 9362.2 9382.7 9304.8 9316.5 9356.7 9371.0 9378.2 9203.1 9243.4 9264.6 9320.0 9341.4 9196.7 9300.1 9314.3 9331.9 9370.6 44247.6 44732.8 44863.6 44961.8 45151.3 3153.7 3650.8 3667.6 3688.1 3813.8 33988.5 34492.0 34507.8 34517.5 34592.5 4660.4 4669.6 4672.7 4673.0 4746.6 324601.0 325844.4 325927.0 327171.0 328361.0 39176.4 39227.4 39272.4 39354.8 39476.0 39493.6 40287.0 40495.8 40696.0 40780.0 19141.0 19416.0 19463.8 19572.6 19606.5 12534.1 12581.1 12596.9 12610.4 12679.1 21620.0 21647.2 21673.3 21873.5 22024.6 7658.1 7680.2 7701.6 7752.8 7824.7 1088.9 1091.9 1148.4 1171.3 1207.6 35056.0 35129.6 35409.5 35526.6 35757.6 1176.2 1213.4 1230.2 1272.9 1279.4 18925.9 18959.3 19076.0 19123.5 19170.3 607552.0 611398.0 613361.0 615065.0 616898.0 66485.7 67060.3 67096.3 67365.0 67394.3 1429.3 1446.9 1453.0 1489.5 1495.2 33619.0 34715.0 34893.0 34988.8 35405.5 58639.4 59151.4 59171.1 59350.7 59525.7 517126.0 523087.0 524777.0 524962.0 527804.0 30780.2 31336.0 31654.0 32020.4 32021.4 52417.7 52782.2 52961.5 53818.5 54872.0 42936.4 45490.5 46155.8 47008.0 47420.5 44085.8 45188.5 45238.5 45362.3 47348.0 39354.8 44343.8 45875.0 46027.5 47233.8 39068.7 40168.8 40787.0 40907.2 41344.4 30425.8 31568.4 31676.5 31694.8 32013.7 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#2] jquery.html Cycles:180 Average:30556.68 Median:11663.87 stddev:87586.19 (750.9%) stddev-sans-first:87802.66 22:06:39 INFO - PROCESS | 7224 | Values: 420.9 424.4 429.4 432.9 439.0 19436.5 20683.6 20828.7 21019.6 21135.6 19693.9 20894.3 20902.2 21299.8 21555.0 5894.8 6152.6 6159.9 6171.3 6175.4 5762.4 6010.2 6048.7 6067.1 6154.6 6129.1 6133.7 6135.7 6144.1 6144.4 5896.0 5942.6 5957.6 6001.5 6002.1 5598.2 5805.5 5815.9 5846.4 5865.8 14740.8 15045.5 15072.4 15091.7 15153.3 2304.5 2378.5 2504.0 2569.5 2755.8 10259.4 10643.8 11123.9 11350.6 11403.4 1990.0 2003.1 2049.7 2069.7 2101.5 85952.0 86162.0 86527.6 88452.6 90262.0 10517.9 10548.2 10752.0 10828.9 10847.1 14283.4 14335.1 14342.5 14362.2 14381.0 8483.8 8656.7 8705.3 8706.6 8909.9 8816.5 8897.4 8929.0 9068.6 9146.4 4333.3 4420.2 4445.0 4687.2 4925.1 2076.4 2078.4 2100.8 2112.7 2128.7 1007.2 1007.7 1025.9 1030.6 1052.9 20328.7 20853.3 21089.2 21199.0 21654.1 1035.7 1054.5 1074.4 1080.5 1155.4 11571.7 11582.8 11868.8 11948.7 12484.9 520664.4 525812.0 535704.0 537881.0 541471.0 63253.4 69451.7 78638.0 99311.0 100503.5 1079.7 1096.4 1126.0 1128.3 1129.5 18753.2 18991.6 19244.9 19330.7 19527.3 44463.6 45318.8 45449.3 45831.3 46090.0 64183.7 65706.3 65761.7 66429.7 67185.3 8942.7 8943.8 8990.3 9102.7 9248.1 10469.0 13428.8 14054.7 14312.4 14319.6 12369.4 12485.7 12625.5 12658.6 13376.3 11708.9 12401.1 12424.9 12484.8 12574.7 12181.6 12340.9 12408.8 12909.0 13035.6 7722.3 8172.4 8212.8 8710.5 9345.3 9457.4 11014.9 11178.0 12019.8 12543.1 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#3] mootools.html Cycles:180 Average:6452.23 Median:494.33 stddev:3768.99 (762.4%) stddev-sans-first:3761.87 22:06:39 INFO - PROCESS | 7224 | Values: 1592.4 1594.1 1773.5 1827.7 1880.2 8586.8 9270.8 9320.5 9965.4 9979.5 9605.1 9688.6 9737.9 9789.5 9799.2 2509.4 2520.0 2561.8 2562.7 2564.1 2509.8 2589.5 2590.9 2593.9 2599.3 2553.5 2563.4 2582.8 2585.0 2587.4 2438.8 2449.4 2457.6 2480.5 2495.7 2451.8 2453.2 2462.8 2480.9 2517.4 9725.9 9745.8 9763.7 9779.3 9802.7 5650.0 5746.1 5753.7 5798.2 5808.2 3484.5 3610.3 3681.8 3692.7 3707.4 1209.5 1224.6 1245.2 1287.4 1309.6 14173.4 14218.8 14272.6 14299.6 14334.9 4702.8 5150.5 5159.8 5171.1 5206.7 11713.4 11731.4 11751.1 11751.4 11814.2 5901.4 5905.4 5951.5 5956.5 5982.9 5234.9 5331.0 5332.9 5442.6 5458.6 4258.1 4321.7 4329.3 4370.7 4385.2 1242.9 1253.1 1264.6 1270.2 1273.9 933.9 964.5 1003.5 1022.4 1030.3 6564.4 6645.1 6720.1 6766.9 6954.9 483.5 493.2 495.5 507.5 507.5 4498.5 4684.8 4706.7 4731.6 4745.9 12208.8 12366.4 12525.1 12535.1 12561.4 11547.8 12093.8 12503.9 12537.7 12543.2 9104.5 9228.4 9305.2 9347.3 9347.7 6422.6 6600.3 6618.4 6684.1 6771.7 11114.1 11317.9 11340.9 11537.4 11715.1 10316.9 11141.4 11691.5 11701.4 11750.9 8406.5 8671.6 8793.9 8850.6 8862.3 9378.6 9419.2 9464.8 9505.7 9520.9 8299.3 8301.2 8420.3 8446.2 8612.6 8363.1 8407.3 8413.0 8416.5 8555.5 7580.0 7589.4 7682.2 7685.6 7724.1 5571.7 5647.0 5714.3 5760.6 5868.7 5677.4 5701.0 5761.5 5917.8 5951.3 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#4] prototype.html Cycles:180 Average:5053.06 Median:5661.98 stddev:1504.25 (26.6%) stddev-sans-first:1466.50 22:06:39 INFO - PROCESS | 7224 | Values: 352.1 358.0 362.0 362.8 363.7 5764.8 5769.9 5787.2 5832.5 5843.2 5633.0 5695.2 5700.3 5703.9 5731.0 4497.9 4534.3 4537.8 4552.1 4561.2 4536.6 4555.2 4561.9 4569.6 4569.6 4484.9 4521.7 4551.8 4569.1 4588.0 4309.4 4492.4 4493.7 4500.3 4505.5 3936.7 3940.1 3946.5 3952.5 3969.2 5631.9 5642.8 5656.2 5667.8 5690.5 4993.5 5118.4 5130.8 5147.8 5152.6 6007.1 6034.1 6062.0 6066.4 6121.8 4265.9 4331.6 4374.5 4376.1 4383.1 6089.9 6318.6 6327.4 6370.6 6384.8 4400.2 4400.9 4409.1 4413.5 4420.2 4407.8 4416.3 4419.6 4422.4 4466.2 5009.3 5096.1 5101.4 5115.6 5145.1 5961.6 6051.4 6058.7 6062.4 6088.6 1535.3 1553.3 1558.6 1560.3 1562.0 949.2 992.7 999.2 1011.2 1012.4 5264.2 5269.9 5279.6 5285.4 5333.8 5509.4 5529.5 5561.6 5565.4 5649.8 4990.3 5181.4 5256.6 5289.8 5362.8 2926.8 2946.3 2960.0 2963.7 2978.6 5716.3 5784.4 5795.2 5830.0 5853.2 5952.4 6447.8 6491.9 6492.2 6494.3 6578.7 6643.4 6644.6 6680.7 6697.4 6137.5 6247.4 6277.7 6284.5 6358.3 6239.2 6272.0 6281.9 6282.7 6297.3 6268.8 6422.0 6520.6 6538.6 6545.1 4492.6 4522.8 4528.5 4531.9 4536.3 6189.4 6255.5 6315.6 6326.4 6350.7 6094.6 6094.7 6103.8 6136.3 6145.2 5915.8 6073.2 6103.7 6151.3 6156.4 5967.6 6040.2 6046.2 6060.8 6075.1 6141.9 6155.4 6193.9 6227.5 6246.6 5778.5 5959.6 5975.3 6011.4 6020.2 22:06:39 INFO - PROCESS | 7224 | 22:06:39 INFO - PROCESS | 7224 | [#5] yui.html Cycles:180 Average:22419.40 Median:4778.82 stddev:32968.93 (689.9%) stddev-sans-first:33021.60 22:06:39 INFO - PROCESS | 7224 | Values: 839.2 841.4 893.6 915.5 948.6 15811.1 16680.0 16712.7 16723.3 16728.5 16659.7 16921.4 16940.5 16964.5 16970.2 718.3 850.4 875.3 879.8 882.8 880.7 880.8 882.3 889.4 889.5 943.9 948.4 948.5 950.7 951.4 937.6 943.0 944.6 944.6 945.6 696.8 697.8 697.9 698.0 698.9 15364.0 15401.8 15420.6 15525.6 15549.5 13770.2 13840.5 14334.2 14371.5 15105.8 13923.4 14153.8 14172.2 14484.7 14775.3 4719.3 4743.7 4759.3 4926.9 4934.9 89008.6 89416.0 89985.1 92089.0 92500.5 12199.2 12258.6 12354.8 12361.3 12570.4 22573.9 23066.3 23209.4 23457.4 23505.8 8042.9 8161.9 8227.2 8423.7 8469.0 4760.2 4964.9 4989.7 5026.5 5114.7 3575.0 3654.6 3659.7 3664.6 3672.1 659.9 663.6 669.8 672.2 679.6 563.4 600.9 615.0 689.3 713.8 9736.3 9796.5 9948.0 9949.8 10350.7 632.3 729.5 735.7 754.0 774.6 4414.6 4797.4 4868.6 4884.4 4921.3 136566.0 136991.0 137919.0 138611.0 138857.0 62013.0 77017.2 93106.0 121817.4 122163.0 59221.1 62237.0 93490.0 94891.5 98891.0 9797.2 9821.6 10050.8 10486.2 10665.8 54257.7 54493.5 55137.4 55752.2 56060.4 73562.0 76037.6 82440.2 83892.6 85055.1 14037.3 14328.2 14444.6 14790.9 14858.3 16305.4 17143.7 17146.7 17312.2 17357.4 14409.2 14669.9 15297.3 16130.4 16587.3 14084.9 14189.8 14718.1 15073.3 15723.1 12993.8 13343.5 13427.9 13652.2 14125.8 11623.9 11823.0 11852.3 11860.3 12266.1 11459.6 11793.6 13006.9 13022.3 13141.9 22:06:39 INFO - PROCESS | 7224 | -------- Summary: end -------- 22:06:39 INFO - PROCESS | 7224 | 22:06:40 INFO - TEST-INFO | 7224: exit 0 22:06:40 INFO - TEST-OK | dromaeo_css | took 1109887ms 22:06:40 INFO - TEST-START | kraken 22:06:40 INFO - operating with platform_type : linux_ 22:06:40 INFO - Initialising browser for kraken test... 22:06:40 INFO - TEST-INFO | started process 7482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7No357/profile http://localhost:43514/getInfo.html) 22:06:44 INFO - PROCESS | 7482 | __metrics Screen width/height:1600/1200 22:06:44 INFO - PROCESS | 7482 | colorDepth:24 22:06:44 INFO - PROCESS | 7482 | Browser inner width/height: 1024/697 22:06:44 INFO - PROCESS | 7482 | __metrics 22:06:44 ERROR - PROCESS | 7482 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:06:45 INFO - TEST-INFO | 7482: exit 0 22:06:45 INFO - Browser initialized. 22:06:45 INFO - Running cycle 1/1 for kraken test... 22:06:45 INFO - TEST-INFO | started process 7610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7No357/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:57 INFO - PROCESS | 7610 | RSS: Main: 152379392 22:06:57 INFO - PROCESS | 7610 | 22:06:59 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43514/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:00 INFO - PROCESS | 7610 | RSS: Main: 157659136 22:07:00 INFO - PROCESS | 7610 | 22:07:03 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43514/tests/kraken/driver.html?testName=audio-dft) 22:07:03 INFO - PROCESS | 7610 | RSS: Main: 156549120 22:07:03 INFO - PROCESS | 7610 | 22:07:06 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43514/tests/kraken/driver.html?testName=audio-fft) 22:07:07 INFO - PROCESS | 7610 | RSS: Main: 157413376 22:07:07 INFO - PROCESS | 7610 | 22:07:09 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43514/tests/kraken/driver.html?testName=audio-oscillator) 22:07:09 INFO - PROCESS | 7610 | RSS: Main: 157040640 22:07:09 INFO - PROCESS | 7610 | 22:07:12 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43514/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:12 INFO - PROCESS | 7610 | RSS: Main: 156229632 22:07:12 INFO - PROCESS | 7610 | 22:07:15 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43514/tests/kraken/driver.html?testName=imaging-darkroom) 22:07:16 INFO - PROCESS | 7610 | RSS: Main: 155836416 22:07:16 INFO - PROCESS | 7610 | 22:07:19 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43514/tests/kraken/driver.html?testName=imaging-desaturate) 22:07:20 INFO - PROCESS | 7610 | RSS: Main: 156364800 22:07:20 INFO - PROCESS | 7610 | 22:07:23 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43514/tests/kraken/driver.html?testName=json-parse-financial) 22:07:23 INFO - PROCESS | 7610 | RSS: Main: 157454336 22:07:23 INFO - PROCESS | 7610 | 22:07:25 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43514/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:07:26 INFO - PROCESS | 7610 | RSS: Main: 156651520 22:07:26 INFO - PROCESS | 7610 | 22:07:27 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:07:28 INFO - PROCESS | 7610 | RSS: Main: 157560832 22:07:28 INFO - PROCESS | 7610 | 22:07:30 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:07:31 INFO - PROCESS | 7610 | RSS: Main: 157798400 22:07:31 INFO - PROCESS | 7610 | 22:07:33 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:07:34 INFO - PROCESS | 7610 | RSS: Main: 158208000 22:07:34 INFO - PROCESS | 7610 | 22:07:36 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:07:37 INFO - PROCESS | 7610 | RSS: Main: 157962240 22:07:37 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | Cycle 1(1): loaded http://localhost:43514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43514/tests/kraken/driver.html?testName=ai-astar) 22:07:39 INFO - PROCESS | 7610 | RSS: Main: 158765056 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | __start_tp_report 22:07:39 INFO - PROCESS | 7610 | _x_x_mozilla_page_load 22:07:39 INFO - PROCESS | 7610 | _x_x_mozilla_page_load_details 22:07:39 INFO - PROCESS | 7610 | |i|pagename|runs| 22:07:39 INFO - PROCESS | 7610 | |0;ai-astar;102;112;104;110;111;111;110;111;109;111 22:07:39 INFO - PROCESS | 7610 | |1;audio-beat-detection;142;115;138;169;127;145;151;118;189;119 22:07:39 INFO - PROCESS | 7610 | |2;audio-dft;163;161;162;161;161;164;160;160;160;161 22:07:39 INFO - PROCESS | 7610 | |3;audio-fft;74;77;76;170;75;75;76;74;170;76 22:07:39 INFO - PROCESS | 7610 | |4;audio-oscillator;83;83;156;84;84;85;148;84;84;139 22:07:39 INFO - PROCESS | 7610 | |5;imaging-gaussian-blur;117;117;118;118;117;117;117;117;117;117 22:07:39 INFO - PROCESS | 7610 | |6;imaging-darkroom;150;148;149;147;147;147;148;147;147;148 22:07:39 INFO - PROCESS | 7610 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;96;96 22:07:39 INFO - PROCESS | 7610 | |8;json-parse-financial;50;52;50;51;52;52;51;52;52;53 22:07:39 INFO - PROCESS | 7610 | |9;json-stringify-tinderbox;59;59;58;58;59;59;59;58;58;59 22:07:39 INFO - PROCESS | 7610 | |10;stanford-crypto-aes;77;75;77;73;72;72;72;71;72;72 22:07:39 INFO - PROCESS | 7610 | |11;stanford-crypto-ccm;130;124;126;125;121;130;129;117;148;119 22:07:39 INFO - PROCESS | 7610 | |12;stanford-crypto-pbkdf2;154;150;152;151;154;156;157;154;149;152 22:07:39 INFO - PROCESS | 7610 | |13;stanford-crypto-sha256-iterative;61;61;65;64;61;60;60;61;60;63 22:07:39 INFO - PROCESS | 7610 | __end_tp_report 22:07:39 INFO - PROCESS | 7610 | __start_cc_report 22:07:39 INFO - PROCESS | 7610 | _x_x_mozilla_cycle_collect,906 22:07:39 INFO - PROCESS | 7610 | __end_cc_report 22:07:39 INFO - PROCESS | 7610 | __startTimestamp1471669659440__endTimestamp 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | ------- Summary: start ------- 22:07:39 INFO - PROCESS | 7610 | Number of tests: 14 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#0] ai-astar Cycles:10 Average:109.10 Median:111.00 stddev:3.35 (3.0%) stddev-sans-first:2.37 22:07:39 INFO - PROCESS | 7610 | Values: 102.0 112.0 104.0 110.0 111.0 111.0 110.0 111.0 109.0 111.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:143.50 stddev:23.80 (16.6%) stddev-sans-first:25.24 22:07:39 INFO - PROCESS | 7610 | Values: 142.0 115.0 138.0 169.0 127.0 145.0 151.0 118.0 189.0 119.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.34 (0.8%) stddev-sans-first:1.27 22:07:39 INFO - PROCESS | 7610 | Values: 163.0 161.0 162.0 161.0 161.0 164.0 160.0 160.0 160.0 161.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.50 stddev:39.91 (52.9%) stddev-sans-first:41.65 22:07:39 INFO - PROCESS | 7610 | Values: 74.0 77.0 76.0 170.0 75.0 75.0 76.0 74.0 170.0 76.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:84.00 stddev:31.09 (37.0%) stddev-sans-first:32.12 22:07:39 INFO - PROCESS | 7610 | Values: 83.0 83.0 156.0 84.0 84.0 85.0 148.0 84.0 84.0 139.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:07:39 INFO - PROCESS | 7610 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.03 (0.7%) stddev-sans-first:0.73 22:07:39 INFO - PROCESS | 7610 | Values: 150.0 148.0 149.0 147.0 147.0 147.0 148.0 147.0 147.0 148.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:07:39 INFO - PROCESS | 7610 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:52.00 stddev:0.97 (1.9%) stddev-sans-first:0.87 22:07:39 INFO - PROCESS | 7610 | Values: 50.0 52.0 50.0 51.0 52.0 52.0 51.0 52.0 52.0 53.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 22:07:39 INFO - PROCESS | 7610 | Values: 59.0 59.0 58.0 58.0 59.0 59.0 59.0 58.0 58.0 59.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:72.50 stddev:2.21 (3.1%) stddev-sans-first:1.90 22:07:39 INFO - PROCESS | 7610 | Values: 77.0 75.0 77.0 73.0 72.0 72.0 72.0 71.0 72.0 72.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:127.50 stddev:8.67 (6.8%) stddev-sans-first:9.13 22:07:39 INFO - PROCESS | 7610 | Values: 130.0 124.0 126.0 125.0 121.0 130.0 129.0 117.0 148.0 119.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:154.00 stddev:2.56 (1.7%) stddev-sans-first:2.68 22:07:39 INFO - PROCESS | 7610 | Values: 154.0 150.0 152.0 151.0 154.0 156.0 157.0 154.0 149.0 152.0 22:07:39 INFO - PROCESS | 7610 | 22:07:39 INFO - PROCESS | 7610 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.00 stddev:1.78 (2.9%) stddev-sans-first:1.87 22:07:39 INFO - PROCESS | 7610 | Values: 61.0 61.0 65.0 64.0 61.0 60.0 60.0 61.0 60.0 63.0 22:07:39 INFO - PROCESS | 7610 | -------- Summary: end -------- 22:07:39 INFO - PROCESS | 7610 | 22:07:40 INFO - TEST-INFO | 7610: exit 0 22:07:40 INFO - TEST-OK | kraken | took 60120ms 22:07:40 INFO - SUITE-END | took 1170s 22:07:40 INFO - Completed test suite (00:19:30) 22:07:40 INFO - javascript benchmark 22:07:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7736.173651638166, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.9018429447115, 3294.4893541501074, 3301.2407146356336, 3303.0421177858416, 3315.403227419065, 5700.629459085162, 5722.914156287648, 5744.885102297952, 5756.597038207322, 5762.279715035621, 5632.7746890124345, 5649.12438572021, 5697.202391665171, 5701.914471282934, 5723.227364305818, 5141.922871156927, 5169.09540830355, 5173.198154286091, 5179.663321884065, 5242.030224408478, 5074.517920797518, 5090.720010399426, 5176.50823171798, 5178.5339319461245, 5223.033738758323, 5166.535011848933, 5180.0, 5202.375714914216, 5210.348706411699, 5273.288106105665, 5242.81650142247, 5253.0544501989625, 5260.579153667697, 5260.842174734745, 5271.262023316739, 4992.276119962585, 5007.223880298559, 5153.304303918961, 5201.843944681666, 5229.398619158807, 5598.964191624522, 5670.773169073233, 5674.687892165929, 5689.260396148474, 5711.600187986843, 6105.694715264263, 6117.816465506042, 6119.908201376983, 6136.263648362181, 6164.7534098636, 5980.372060933626, 6059.6364218147055, 6184.598001129957, 6186.319504854462, 6195.132681424604, 5675.290588676416, 5770.798022069207, 5774.451427114459, 5775.249217601685, 5783.768649254067, 6210.813675589739, 6247.031710084944, 6271.592346497463, 6287.2769631492965, 6287.842803929847, 5865.794697185578, 5875.794347197828, 5999.220101386879, 6029.969850150721, 6093.908591371133, 5965.6718880461995, 6084.452399283997, 6097.695115244309, 6100.664463454464, 6113.052476866093, 6015.969920150371, 6059.303180134341, 6067.302260240041, 6071.908921366183, 6083.908741368883, 5869.266341707286, 5970.164177015221, 6039.245094363205, 6055.031195008777, 6062.242219722535, 4609.95390046102, 4677.438707355139, 4688.109259240733, 4693.084848454587, 4695.859124226278, 4171.91656166875, 4173.040200753843, 4181.351890456984, 4193.874183774492, 4220.155968806251, 5681.289838770153, 5682.914756278659, 5884.529237661062, 5899.085641725539, 5909.438603332676, 6049.243844519435, 6113.719560719409, 6124.545046617194, 6126.975740326312, 6142.109394137825, 5838.153467747153, 5881.647101173943, 5886.117082437669, 5894.587378883437, 5911.793087242012, 5166.638335316567, 5199.66202196856, 5251.553617942442, 5261.289725887033, 5268.762905669254, 6301.369863013753, 6382.329855365213, 6384.7126879290545, 6429.517786165963, 6456.192975878015, 6087.66517841523, 6109.6639684817765, 6130.111133885562, 6148.077788331786, 6199.16311297969, 6125.937554509019, 6263.27972283175, 6263.655498947602, 6419.26178489461, 6420.550561460653, 6084.0, 6086.695665216633, 6096.542759292982, 6116.052011938156, 6122.785702500391, 6144.679916766854, 6174.934920784954, 6191.9690401547705, 6217.285012223652, 6221.0, 5878.088896221093, 5920.703964801829, 5936.37668044849, 6090.390960903874, 6094.053247076387, 5992.400759923973, 6002.0, 6014.789482368096, 6016.278046634432, 6037.698115094316, 5967.671778052249, 5981.043033114681, 6001.339852616122, 6017.939820601738, 6019.247594050744, 5810.215620891295, 5919.112133179885, 5930.169776231417, 6053.878922421615, 6099.542534309856, 5852.736626851717, 5856.033754430641, 5874.412558744091, 5885.47030767233, 5892.175095486549], "value": 5637.691349011948, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.8851673923584, 617.1637431280615, 626.1609443345916, 652.4030512081445, 674.0933444517123, 41026.17947641049, 47011.29483057757, 47151.0, 47226.76386618066, 47485.52514474854, 46932.29601555971, 47658.52341476584, 47691.99999999991, 47720.76139619297, 47863.28205076927, 9113.407628504143, 9262.305327100494, 9265.629374825, 9278.257739380868, 9293.907060929405, 9247.121523455295, 9354.298427617921, 9358.251339892793, 9362.157405833474, 9382.718518444457, 9304.7673808155, 9316.487593182372, 9356.719298421025, 9371.0, 9378.249740020781, 9203.125703058198, 9243.352965292432, 9264.629414823432, 9320.02139775324, 9341.392809467363, 9196.678116265966, 9300.069993000714, 9314.254859611216, 9331.906680933173, 9370.625174992992, 44247.557524424665, 44732.77633611811, 44863.551364486266, 44961.77519112384, 45151.322730159074, 3153.731932785716, 3650.7627004244905, 3667.6149004354693, 3688.0779805048737, 3813.7521061130938, 33988.49017264743, 34492.0, 34507.82746086278, 34517.482237766206, 34592.48111278333, 4660.37084993525, 4669.579737823578, 4672.672912896099, 4672.953270467286, 4746.644001699852, 324601.0, 325844.37077814696, 325927.0, 327171.0, 328361.0, 39176.41235381472, 39227.41158882619, 39272.41091383631, 39354.803225983684, 39476.0, 39493.60506394899, 40287.0, 40495.79752101221, 40696.0, 40780.0, 19141.0, 19416.0, 19463.80536194648, 19572.608547828964, 19606.509837254183, 12534.059945504123, 12581.056420768478, 12596.937015314865, 12610.432530536147, 12679.112462127747, 21620.0, 21647.242346517796, 21673.34979950604, 21873.453163671034, 22024.559508810056, 7658.119316278699, 7680.155182929873, 7701.614919254015, 7752.7674169775, 7824.726134585376, 1088.9074428673493, 1091.9071878890384, 1148.4315263944234, 1171.308927732642, 1207.6497815633368, 35056.0, 35129.64870351264, 35409.46885796715, 35526.64473355233, 35757.64242357543, 1176.1825531255865, 1213.3751118174148, 1230.194222784078, 1272.9172603780726, 1279.4434421026883, 18925.905370473058, 18959.336423225122, 19076.0, 19123.521911952033, 19170.329038483585, 607552.0, 611398.0, 613361.0, 615065.0, 616898.0, 66485.6675716628, 67060.32939670638, 67096.32903670998, 67365.0, 67394.3260567388, 1429.349645911108, 1446.8987170897935, 1453.0, 1489.5233525272026, 1495.1851241073641, 33619.0, 34715.0, 34893.0, 34988.825055875575, 35405.46891796677, 58639.413605863396, 59151.40848591459, 59171.112433314396, 59350.70324648349, 59525.70237148787, 517126.0, 523087.0, 524777.0, 524962.0, 527804.0, 30780.23049423782, 31336.0, 31654.0, 32020.35959280848, 32021.35957280888, 52417.7379113102, 52782.2082668768, 52961.47038529565, 53818.46181538134, 54872.0, 42936.35595466133, 45490.54509454863, 46155.76922115368, 47008.0, 47420.52579474299, 44085.77957110194, 45188.54811451843, 45238.547614523435, 45362.319565205886, 47348.0, 39354.803225983684, 44343.77828110839, 45875.0, 46027.53972460367, 47233.763831182, 39068.666925149555, 40168.79915600403, 40787.0, 40907.15917783766, 41344.37983430311, 30425.84787076139, 31568.36863262676, 31676.524852126775, 31694.841525792224, 32013.67986320107], "value": 21291.162611252254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.8674267605706, 424.4015937528082, 429.39026582253206, 432.900432900433, 438.96927215094934, 19436.51408714783, 20683.586328273424, 20828.687569686466, 21019.57960840782, 21135.577288454242, 19693.916834574127, 20894.268700595483, 20902.163913443444, 21299.787002129975, 21555.0, 5894.764209431618, 6152.600080994721, 6159.90760138596, 6171.321154672983, 6175.413335733098, 5762.3949485304065, 6010.248718910137, 6048.727807248684, 6067.0899365095365, 6154.569180157391, 6129.111278864562, 6133.723982420802, 6135.693215339215, 6144.078388241777, 6144.44699977002, 5896.027155519334, 5942.554308426886, 5957.553183511232, 6001.549883758692, 6002.129691194796, 5598.24423702799, 5805.477507024346, 5815.912761308584, 5846.3861294563885, 5865.794697185621, 14740.778888316683, 15045.473408430762, 15072.397104115822, 15091.698166036727, 15153.318100685496, 2304.5275718477674, 2378.500514891875, 2504.0, 2569.4732579821093, 2755.8484283364405, 10259.43573103479, 10643.78712425755, 11123.944380278046, 11350.602728904449, 11403.3728144952, 1989.9602007959759, 2003.1086166655843, 2049.6515592349338, 2069.7205877206534, 2101.48513614164, 85952.0, 86162.0, 86527.56736216342, 88452.55773721155, 90262.0, 10517.894821051845, 10548.156147508182, 10752.0323170915, 10828.945855270673, 10847.132229421628, 14283.42866285337, 14335.06822056563, 14342.49801256951, 14362.210078445576, 14380.993330466767, 8483.83032339362, 8656.74029779095, 8705.303575713928, 8706.608202630898, 8909.866352004725, 8816.515091669891, 8897.421667591585, 8928.955355223183, 9068.591913363915, 9146.359754817233, 4333.328334108218, 4420.160169567772, 4444.955550444519, 4687.156311863897, 4925.113479573711, 2076.408223656281, 2078.3557097299886, 2100.842436817245, 2112.746470423559, 2128.7445506539316, 1007.2093406675724, 1007.7480629842539, 1025.917926565873, 1030.64957914308, 1052.8631277933819, 20328.69506957397, 20853.270135545183, 21089.156433742784, 21199.0, 21654.133834646436, 1035.7100011996654, 1054.520193312041, 1074.4412905289205, 1080.4813689429118, 1155.3760969076604, 11571.710707232218, 11582.826257606142, 11868.821967670492, 11948.701282467833, 12484.937575312248, 520664.3966780142, 525812.0, 535704.0, 537881.0, 541471.0, 63253.36746632567, 69451.65274173698, 78638.0, 99311.0, 100503.49748251359, 1079.7138758229075, 1096.3641088168843, 1125.9662210133708, 1128.3455595754442, 1129.5030186717818, 18753.1561079749, 18991.620167596848, 19244.903775481034, 19330.71003934914, 19527.316543921464, 44463.55536444724, 45318.77340613276, 45449.31826022546, 45831.31253031274, 46090.0, 64183.67908160429, 65706.34293657003, 65761.67119164373, 66429.66785166043, 67185.3281467179, 8942.73171804847, 8943.776405589912, 8990.280777537911, 9102.681406151018, 9248.121428464285, 10469.0, 13428.798568021683, 14054.718905622034, 14312.427502900184, 14319.642008949442, 12369.381530923238, 12485.687857803627, 12625.494980200689, 12658.556950506689, 13376.331183440594, 11708.94145529301, 12401.069919756235, 12424.875751242733, 12484.750304994031, 12574.685632859251, 12181.573644922386, 12340.938295308466, 12408.81386779217, 12909.0, 13035.608931732064, 7722.343600793995, 8172.38707096982, 8212.753617391489, 8710.520921349133, 9345.252379809735, 9457.43255404678, 11014.889851101387, 11178.0, 12019.75960480768, 12543.12198146121], "value": 9577.445172565036, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1592.3869310315529, 1594.0754362469768, 1773.4679596121168, 1827.6801559727044, 1880.1727240014393, 8586.828263434743, 9270.814583708321, 9320.533973301342, 9965.402075875452, 9979.451130187837, 9605.08751668592, 9688.564014619342, 9737.902620973788, 9789.510524473784, 9799.16707079899, 2509.4102885821817, 2520.0297885314194, 2561.7950563954837, 2562.705288891783, 2564.064116597438, 2509.849409035464, 2589.49504846555, 2590.870456477178, 2593.922182334533, 2599.3241757143182, 2553.4510080332725, 2563.4488584954233, 2582.7546383093577, 2585.0435338924626, 2587.3919628887197, 2438.76831700989, 2449.375409270642, 2457.5576396248666, 2480.5410998965067, 2495.700515938099, 2451.8038556915403, 2453.1904471524354, 2462.839915405511, 2480.937976550583, 2517.3706573356662, 9725.854112188323, 9745.756356091084, 9763.658271960518, 9779.266555008402, 9802.705918822447, 5649.971750141223, 5746.080627099685, 5753.712314384264, 5798.159266906275, 5808.1578171165365, 3484.494748261488, 3610.29599228148, 3681.7606855554304, 3692.667659910594, 3707.443883417482, 1209.5222387157023, 1224.6326102169314, 1245.1844042152313, 1287.427094942751, 1309.5547513845338, 14173.43306267748, 14218.786718199235, 14272.571822845228, 14299.57101286963, 14334.928325358307, 4702.811887524529, 5150.536451719363, 5159.819406320761, 5171.120909445403, 5206.661566998133, 11713.355765432812, 11731.413429328499, 11751.059915206763, 11751.353675547927, 11814.173007889538, 5901.3508514063415, 5905.409459054146, 5951.4941229995065, 5956.52347812174, 5982.880342393128, 5234.9476505235225, 5331.0, 5332.866678333073, 5442.619016668875, 5458.608054945963, 4258.1057977824385, 4321.6974811762875, 4329.328954012133, 4370.694051416434, 4385.232584297799, 1242.9067819913544, 1253.1415980053691, 1264.5700461843016, 1270.247059058512, 1273.9235645861093, 933.887933447977, 964.5418426247477, 1003.4832061488339, 1022.437659287389, 1030.3457304611625, 6564.3614739132, 6645.06969024337, 6720.092787473726, 6766.898496522556, 6954.895676564855, 483.5285596543412, 493.1837808427059, 495.4723219771004, 507.48490282362695, 507.5051824471111, 4498.527654596221, 4684.805374629448, 4706.741129237925, 4731.574158325767, 4745.881352966204, 12208.816867746813, 12366.44351004207, 12525.1232413732, 12535.059870509749, 12561.371931403393, 11547.769044619228, 12093.81859272094, 12503.874961250453, 12537.749245015231, 12543.24740515572, 9104.499252541042, 9228.400153990104, 9305.162535371714, 9347.252219822398, 9347.672831450867, 6422.550421470452, 6600.339966003361, 6618.437432818265, 6684.131062961784, 6771.661416929035, 11114.110871130128, 11317.943410282896, 11340.886591133984, 11537.365444900275, 11715.121365897763, 10316.896831031894, 11141.442927853737, 11691.473883675426, 11701.356425396345, 11750.882491174978, 8406.537640429591, 8671.6097775599, 8793.91206087931, 8850.557472126238, 8862.335324850534, 9378.624855005995, 9419.199368053518, 9464.763380915258, 9505.66730164441, 9520.904790952001, 8299.336053115858, 8301.169883011651, 8420.326373889951, 8446.239838414573, 8612.569371531523, 8363.121872203332, 8407.285380742705, 8413.032501262433, 8416.452930559493, 8555.529445880287, 7579.962100189464, 7589.392848572212, 7682.154962954182, 7685.615719213905, 7724.073111226464, 5571.693556854249, 5646.971765141148, 5714.285714285715, 5760.596758227051, 5868.677222752619, 5677.3754886963225, 5700.971495142498, 5761.547067284668, 5917.763289468545, 5951.256092988377], "value": 5023.750492298261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1442893768143, 357.95883473400556, 361.95475565554307, 362.76782858970245, 363.71993564955, 5764.769409223626, 5769.913451298234, 5787.1608616750655, 5832.533397328214, 5843.240378750762, 5633.0, 5695.202671625976, 5700.344460387057, 5703.942960570404, 5730.971345143268, 4497.887552811189, 4534.251848445003, 4537.750423726694, 4552.135094332083, 4561.1789877821975, 4536.614387777043, 4555.2256116460285, 4561.908761824761, 4569.611583015431, 4569.611583015448, 4484.865454036384, 4521.688710274017, 4551.840685576006, 4569.063342014879, 4587.954120458803, 4309.35359696045, 4492.3710680504755, 4493.6743660620195, 4500.347449619803, 4505.481869585007, 3936.685065194806, 3940.133170702441, 3946.5264168299987, 3952.505936757905, 3969.2458432897656, 5631.8592035199445, 5642.7742890284335, 5656.151577263402, 5667.801626943037, 5690.516306113986, 4993.475685053054, 5118.360204974379, 5130.820421285237, 5147.819826306099, 5152.587792976553, 6007.098935159717, 6034.125051867499, 6061.969690151565, 6066.423689749511, 6121.816345509642, 4265.872023839259, 4331.566843315674, 4374.49693285273, 4376.124775045004, 4383.123375324916, 6089.939100609025, 6318.62088274705, 6327.367263273682, 6370.617762934239, 6384.776532821375, 4400.185965596375, 4400.867973960722, 4409.11817636474, 4413.536578659258, 4420.182266280748, 4407.779611019436, 4416.343871048357, 4419.64642828573, 4422.402975598317, 4466.240739074366, 5009.273655320031, 5096.133657278272, 5101.438841727407, 5115.616328775357, 5145.125328694131, 5961.631678101859, 6051.425114614105, 6058.66709323948, 6062.42406971337, 6088.620815447381, 1535.278419143001, 1553.3087775939598, 1558.5713928669707, 1560.2744723703531, 1562.0, 949.1599934058409, 992.7220378294089, 999.2206079258191, 1011.1809434358194, 1012.4178597306578, 5264.184051472028, 5269.947300527022, 5279.624169123909, 5285.418603953562, 5333.759980800874, 5509.3664228613425, 5529.451753509024, 5561.610687251854, 5565.387807341189, 5649.796579333981, 4990.30135780991, 5181.378234611795, 5256.632035757537, 5289.841304760864, 5362.785488580413, 2926.8390238537086, 2946.291151132381, 2959.9704002959693, 2963.7184467475554, 2978.597889284962, 5716.314042314865, 5784.392638772868, 5795.217645617872, 5829.950608890355, 5853.2440267919455, 5952.375000624872, 6447.838804029843, 6491.902621460682, 6492.220933488012, 6494.3439422947795, 6578.736850525926, 6643.435307998778, 6644.60132392058, 6680.665966701646, 6697.363750443603, 6137.478314343331, 6247.406496382836, 6277.654728989949, 6284.4972402206795, 6358.300586935526, 6239.192363436381, 6271.96864015677, 6281.937180628135, 6282.717277722605, 6297.307296197323, 6268.811935641938, 6422.036694495676, 6520.609283172629, 6538.640374779433, 6545.050967609574, 4492.573205545468, 4522.819087236474, 4528.501864794804, 4531.912341038061, 4536.251518499411, 6189.412005859436, 6255.499560035095, 6315.621062736251, 6326.398992095744, 6350.68246587678, 6094.573379863545, 6094.695265236632, 6103.816885493442, 6136.2636483622255, 6145.170401995852, 5915.792947246826, 6073.180120683651, 6103.7253323601435, 6151.339138432688, 6156.415140561639, 5967.6419414835245, 6040.248327984835, 6046.183765191643, 6060.78787242462, 6075.1191077294425, 6141.9078713818435, 6155.446009859134, 6193.925035113288, 6227.470664993345, 6246.625202487683, 5778.508826749773, 5959.642421454727, 5975.25309336333, 6011.368806275453, 6020.157177994996], "value": 4572.345597955472, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.2195258409677, 841.4320333774705, 893.5532233883058, 915.4919019943931, 948.554179535618, 15811.13038782868, 16680.0, 16712.665746685074, 16723.331066757346, 16728.498145055637, 16659.66680666386, 16921.407750728726, 16940.491785246464, 16964.49106526803, 16970.15149242536, 718.2888939949438, 850.3962186847345, 875.2910142784333, 879.7932485865816, 882.849915514364, 880.731376930036, 880.7886107334226, 882.3250213586612, 889.3507739350279, 889.4752096263203, 943.8584212368131, 948.4451595816456, 948.4546385828141, 950.653011650746, 951.4196340232453, 937.5780898595625, 943.0239701908491, 944.5796620503878, 944.631593678466, 945.5886689290112, 696.8466937273791, 697.8394969157072, 697.9162500499922, 698.0367093411061, 698.8986596943465, 15364.0, 15401.845981540153, 15420.614484637861, 15525.611859703486, 15549.455769048029, 13770.24263665508, 13840.515581954583, 14334.211618360985, 14371.496997605034, 15105.848941510554, 13923.373448194856, 14153.787693184611, 14172.220527870963, 14484.710305793931, 14775.335109920079, 4719.339292499053, 4743.691660042086, 4759.333693282943, 4926.852194434173, 4934.925976110361, 89008.55495722545, 89416.0, 89985.10014899833, 92089.0, 92500.53749731276, 12199.207051541622, 12258.614536658755, 12354.814677779841, 12361.320127393079, 12570.371481425893, 22573.88713056457, 23066.308010759705, 23209.419764506023, 23457.41356466102, 23505.764942350357, 8042.879356809653, 8161.918380816234, 8227.170962692991, 8423.663053477792, 8468.957655211685, 4760.238361862085, 4964.851054468371, 4989.675671081368, 5026.472220416877, 5114.670185751731, 3575.0168703606196, 3654.5979942206336, 3659.70722342212, 3664.578573464086, 3672.1003354178397, 659.874623821482, 663.5918909870395, 669.8459354348527, 672.1598002496879, 679.5718697220719, 563.4309347558994, 600.8618017855918, 614.9938500615026, 689.2865883810332, 713.7715930902061, 9736.318457707892, 9796.510174491246, 9948.0, 9949.751256218653, 10350.741231469274, 632.253940350391, 729.5075823818901, 735.749845052674, 753.9811504712426, 774.5894675821824, 4414.646828253732, 4797.40032495938, 4868.561829435368, 4884.438289596671, 4921.26181072842, 136566.0, 136991.0, 137919.0, 138611.0, 138857.0, 62013.0, 77017.22982770213, 93106.0, 121817.39091304487, 122163.0, 59221.11168332479, 62237.0, 93489.99999999865, 94891.52554237185, 98891.0, 9797.167240784329, 9821.558029888529, 10050.8492372613, 10486.191599056829, 10665.826759056343, 54257.72871135619, 54493.45506545043, 55137.44862551323, 55752.16371754346, 56060.43939560715, 73562.0, 76037.61981190059, 82440.17559824325, 83892.58053709692, 85055.14944850473, 14037.298135093408, 14328.211948342941, 14444.638884027983, 14790.852091478948, 14858.331375088363, 16305.409878561715, 17143.65712685764, 17146.742798858275, 17312.220950057024, 17357.39249126325, 14409.207493587954, 14669.853301466848, 15297.31162097686, 16130.354785808437, 16587.336506539603, 14084.859151408355, 14189.787153192918, 14718.116912985255, 15073.321700523722, 15723.135227562594, 12993.805092923425, 13343.532976345781, 13427.932860335635, 13652.180869147884, 14125.788113178516, 11623.941880290544, 11823.0, 11852.288862668267, 11860.288382697066, 12266.080043996557, 11459.557432230406, 11793.587224447103, 13006.869931300565, 13022.348882555645, 13141.868581314064], "value": 8117.0631929110405, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 104.0, 110.0, 111.0, 111.0, 110.0, 111.0, 109.0, 111.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 138.0, 169.0, 127.0, 145.0, 151.0, 118.0, 189.0, 119.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 161.0, 161.0, 164.0, 160.0, 160.0, 160.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 76.0, 170.0, 75.0, 75.0, 76.0, 74.0, 170.0, 76.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 156.0, 84.0, 84.0, 85.0, 148.0, 84.0, 84.0, 139.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 149.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 50.0, 51.0, 52.0, 52.0, 51.0, 52.0, 52.0, 53.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0, 58.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 73.0, 72.0, 72.0, 72.0, 71.0, 72.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 124.0, 126.0, 125.0, 121.0, 130.0, 129.0, 117.0, 148.0, 119.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 150.0, 152.0, 151.0, 154.0, 156.0, 157.0, 154.0, 149.0, 152.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 65.0, 64.0, 61.0, 60.0, 60.0, 61.0, 60.0, 63.0], "value": 61.6, "unit": "score"}]}]} 22:07:40 INFO - Return code: 0 22:07:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:07:40 ERROR - # TBPL SUCCESS # 22:07:40 INFO - Running post-action listener: _resource_record_post_action 22:07:40 INFO - [mozharness: 2016-08-20 05:07:40.750077Z] Finished run-tests step (success) 22:07:40 INFO - Running post-run listener: _resource_record_post_run 22:07:40 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 622592; Write bytes: 330338304; Read time: 300; Write time: 224500 22:07:40 INFO - TinderboxPrint: CPU usage
13.2% 22:07:40 INFO - TinderboxPrint: I/O read bytes / time
622,592 / 300 22:07:40 INFO - TinderboxPrint: I/O write bytes / time
330,338,304 / 224,500 22:07:40 INFO - TinderboxPrint: CPU idle
8,215.8 (86.7%) 22:07:40 INFO - TinderboxPrint: CPU user
1,200.5 (12.7%) 22:07:40 INFO - TinderboxPrint: Swap in / out
0 / 0 22:07:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 8192; Write bytes: 77078528; Read time: 12; Write time: 68208 22:07:40 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 614400; Write bytes: 250888192; Read time: 288; Write time: 156040 22:07:41 INFO - Running post-run listener: _upload_blobber_files 22:07:41 INFO - Blob upload gear active. 22:07:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:07:41 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 22:07:41 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'] 22:07:41 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 22:07:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:07:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:07:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:07:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:42 INFO - (blobuploader) - INFO - Done attempting. 22:07:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:07:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:07:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:45 INFO - (blobuploader) - INFO - Done attempting. 22:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:07:46 INFO - (blobuploader) - INFO - Done attempting. 22:07:46 INFO - (blobuploader) - INFO - Iteration through files over. 22:07:46 INFO - Return code: 0 22:07:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:07:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:07:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b4ef427a7c1e32b99e67d60c692c0fc08912667843204d15f69fa00d62ba063d3924f01cc7854547fb4dfafc854887189928c04f3fd95b1c1199e85fe51997", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5bcf8f3e0b62d4b6dadb065004cab1a7472232ea5fb59558210e862fb8b56435e329fb3bb2d3f2a1aee91c3906ebbafbf06fa34307fff0009ca21ad2e1cadc3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7328884ee38904dd42541fc2359973fa9793b79fac9df1bfdd1f08ee679126ea9fe584068d0d22e82f7d047e1a90db0524bc1b99f092c0229c3e9608d53ba669"} 22:07:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:07:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:07:46 INFO - Contents: 22:07:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b4ef427a7c1e32b99e67d60c692c0fc08912667843204d15f69fa00d62ba063d3924f01cc7854547fb4dfafc854887189928c04f3fd95b1c1199e85fe51997", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5bcf8f3e0b62d4b6dadb065004cab1a7472232ea5fb59558210e862fb8b56435e329fb3bb2d3f2a1aee91c3906ebbafbf06fa34307fff0009ca21ad2e1cadc3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7328884ee38904dd42541fc2359973fa9793b79fac9df1bfdd1f08ee679126ea9fe584068d0d22e82f7d047e1a90db0524bc1b99f092c0229c3e9608d53ba669"} 22:07:46 INFO - Running post-run listener: copy_logs_to_upload_dir 22:07:46 INFO - Copying logs to upload dir... 22:07:46 INFO - mkdir: /builds/slave/test/build/upload/logs 22:07:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.768894 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-08-19 22:07:46.623392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:46.627517) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 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/35b4ef427a7c1e32b99e67d60c692c0fc08912667843204d15f69fa00d62ba063d3924f01cc7854547fb4dfafc854887189928c04f3fd95b1c1199e85fe51997", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5bcf8f3e0b62d4b6dadb065004cab1a7472232ea5fb59558210e862fb8b56435e329fb3bb2d3f2a1aee91c3906ebbafbf06fa34307fff0009ca21ad2e1cadc3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7328884ee38904dd42541fc2359973fa9793b79fac9df1bfdd1f08ee679126ea9fe584068d0d22e82f7d047e1a90db0524bc1b99f092c0229c3e9608d53ba669"} build_url:https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/cbhpT2i2QyCCG44TUvBuFw/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/35b4ef427a7c1e32b99e67d60c692c0fc08912667843204d15f69fa00d62ba063d3924f01cc7854547fb4dfafc854887189928c04f3fd95b1c1199e85fe51997", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5bcf8f3e0b62d4b6dadb065004cab1a7472232ea5fb59558210e862fb8b56435e329fb3bb2d3f2a1aee91c3906ebbafbf06fa34307fff0009ca21ad2e1cadc3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7328884ee38904dd42541fc2359973fa9793b79fac9df1bfdd1f08ee679126ea9fe584068d0d22e82f7d047e1a90db0524bc1b99f092c0229c3e9608d53ba669"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:46.653242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:46.653599) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1471667937.520035-1127702983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 22:07:46.737237) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 22:07:46.737570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 22:07:46.737873) ========= ========= Total master_lag: 0.17 =========