builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1474596454.07 results: success (0) buildid: 20160922172259 builduid: 873c728b0d074ca38f3fc1586affa776 revision: 0764a290e487d47d05cdb290f70b4e503a40bb63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.070203) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.070727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.071012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.085783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.086054) ========= 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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 19:07:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 31.9M=0s 2016-09-22 19:07:34 (31.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.237086) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.237372) ========= 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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018598 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.287281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.287580) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0764a290e487d47d05cdb290f70b4e503a40bb63 --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-beta --rev 0764a290e487d47d05cdb290f70b4e503a40bb63 --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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 19:07:34,527 truncating revision to first 12 chars 2016-09-22 19:07:34,527 Setting DEBUG logging. 2016-09-22 19:07:34,527 attempt 1/10 2016-09-22 19:07:34,527 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0764a290e487?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 19:07:34,823 unpacking tar archive at: mozilla-beta-0764a290e487/testing/mozharness/ program finished with exit code 0 elapsedTime=0.616743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.920957) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.921237) ========= script_repo_revision: 0764a290e487d47d05cdb290f70b4e503a40bb63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.921662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.921933) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:34.931914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 30 mins, 41 secs) (at 2016-09-22 19:07:34.932186) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:07:35 INFO - MultiFileLogger online at 20160922 19:07:35 in /builds/slave/test 19:07:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:07:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:35 INFO - {'append_to_log': False, 19:07:35 INFO - 'base_work_dir': '/builds/slave/test', 19:07:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:07:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:35 INFO - 'branch': 'Mozilla-Beta', 19:07:35 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:35 INFO - 'config_files': ('talos/linux_config.py',), 19:07:35 INFO - 'default_actions': ('clobber', 19:07:35 INFO - 'read-buildbot-config', 19:07:35 INFO - 'download-and-extract', 19:07:35 INFO - 'populate-webroot', 19:07:35 INFO - 'create-virtualenv', 19:07:35 INFO - 'install', 19:07:35 INFO - 'run-tests'), 19:07:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:35 INFO - 'download_minidump_stackwalk': True, 19:07:35 INFO - 'download_symbols': 'ondemand', 19:07:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:35 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:35 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:35 INFO - 'installer_path': 'installer.exe', 19:07:35 INFO - 'log_level': 'info', 19:07:35 INFO - 'log_name': 'talos', 19:07:35 INFO - 'log_to_console': True, 19:07:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:07:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:07:35 INFO - 'opt_config_files': (), 19:07:35 INFO - 'pip_index': False, 19:07:35 INFO - 'sps_profile': False, 19:07:35 INFO - 'sps_profile_interval': 0, 19:07:35 INFO - 'suite': 'dromaeojs', 19:07:35 INFO - 'system_bits': '32', 19:07:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:35 INFO - 'title': 'talos-linux64-ix-010', 19:07:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:35 INFO - 'use_talos_json': True, 19:07:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:35 INFO - 'work_dir': 'build'} 19:07:35 INFO - [mozharness: 2016-09-23 02:07:35.271362Z] Running clobber step. 19:07:35 INFO - Running pre-action listener: _resource_record_pre_action 19:07:35 INFO - Running main action method: clobber 19:07:35 INFO - rmtree: /builds/slave/test/build 19:07:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:35 INFO - Running post-action listener: _resource_record_post_action 19:07:35 INFO - [mozharness: 2016-09-23 02:07:35.730397Z] Finished clobber step (success) 19:07:35 INFO - [mozharness: 2016-09-23 02:07:35.730499Z] Running read-buildbot-config step. 19:07:35 INFO - Running pre-action listener: _resource_record_pre_action 19:07:35 INFO - Running main action method: read_buildbot_config 19:07:35 INFO - Using buildbot properties: 19:07:35 INFO - { 19:07:35 INFO - "project": "", 19:07:35 INFO - "product": "firefox", 19:07:35 INFO - "script_repo_revision": "production", 19:07:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 19:07:35 INFO - "repository": "", 19:07:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 19:07:35 INFO - "buildid": "20160922172259", 19:07:35 INFO - "pgo_build": "True", 19:07:35 INFO - "basedir": "/builds/slave/test", 19:07:35 INFO - "buildnumber": 169, 19:07:35 INFO - "slavename": "talos-linux64-ix-010", 19:07:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:07:35 INFO - "platform": "ubuntu64_hw", 19:07:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 19:07:35 INFO - "branch": "mozilla-beta", 19:07:35 INFO - "repo_path": "releases/mozilla-beta", 19:07:35 INFO - "revision": "0764a290e487d47d05cdb290f70b4e503a40bb63", 19:07:35 INFO - "stage_platform": "linux64-pgo", 19:07:35 INFO - "builduid": "873c728b0d074ca38f3fc1586affa776", 19:07:35 INFO - "slavebuilddir": "test" 19:07:35 INFO - } 19:07:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 19:07:35 INFO - Running post-action listener: _resource_record_post_action 19:07:35 INFO - [mozharness: 2016-09-23 02:07:35.732948Z] Finished read-buildbot-config step (success) 19:07:35 INFO - [mozharness: 2016-09-23 02:07:35.733036Z] Running download-and-extract step. 19:07:35 INFO - Running pre-action listener: _resource_record_pre_action 19:07:35 INFO - Running main action method: download_and_extract 19:07:35 INFO - mkdir: /builds/slave/test/build/tests 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:35 INFO - https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:07:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:07:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:07:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:07:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:07:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:07:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:07:35 INFO - retry: Failed, sleeping 30 seconds before retrying 19:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 19:08:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:08:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:08:05 INFO - retry: Failed, sleeping 60 seconds before retrying 19:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 19:09:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:09:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:09:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 19:09:05 INFO - Caught exception: HTTP Error 404: Not Found 19:09:05 INFO - Caught exception: HTTP Error 404: Not Found 19:09:05 INFO - Caught exception: HTTP Error 404: Not Found 19:09:05 INFO - trying https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:09:06 INFO - Downloaded 1327 bytes. 19:09:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:09:06 INFO - Using the following test package requirements: 19:09:06 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 19:09:06 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 19:09:06 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 19:09:06 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'jsshell-linux-x86_64.zip'], 19:09:06 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 19:09:06 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 19:09:06 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 19:09:06 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 19:09:06 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 19:09:06 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:09:06 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 19:09:06 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:06 INFO - https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:09:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:09:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:09:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:09:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:09:06 INFO - retry: Failed, sleeping 30 seconds before retrying 19:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 19:09:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:09:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:09:36 INFO - retry: Failed, sleeping 60 seconds before retrying 19:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 19:10:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:10:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 19:10:36 INFO - Caught exception: HTTP Error 404: Not Found 19:10:36 INFO - Caught exception: HTTP Error 404: Not Found 19:10:36 INFO - Caught exception: HTTP Error 404: Not Found 19:10:36 INFO - trying https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:36 INFO - Downloading https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:10:37 INFO - Downloaded 21771211 bytes. 19:10:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:10:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:10:38 INFO - Return code: 0 19:10:38 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:10:38 INFO - https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:10:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:10:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:10:38 INFO - retry: Failed, sleeping 30 seconds before retrying 19:11:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 19:11:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:11:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:11:08 INFO - retry: Failed, sleeping 60 seconds before retrying 19:12:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 19:12:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:12:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:12:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 19:12:08 INFO - Caught exception: HTTP Error 404: Not Found 19:12:08 INFO - Caught exception: HTTP Error 404: Not Found 19:12:08 INFO - Caught exception: HTTP Error 404: Not Found 19:12:08 INFO - trying https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:12:08 INFO - Downloading https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:12:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:12:09 INFO - Downloaded 21771211 bytes. 19:12:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:12:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:12:10 INFO - Return code: 0 19:12: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')]} 19:12:10 INFO - https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:12:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:12:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:12:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:12:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:12:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:12:10 INFO - retry: Failed, sleeping 30 seconds before retrying 19:12:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 19:12:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:12:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:12:41 INFO - retry: Failed, sleeping 60 seconds before retrying 19:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 19:13:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:13:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:13:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 19:13:41 INFO - Caught exception: HTTP Error 404: Not Found 19:13:41 INFO - Caught exception: HTTP Error 404: Not Found 19:13:41 INFO - Caught exception: HTTP Error 404: Not Found 19:13:41 INFO - trying https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:13:41 INFO - Downloading https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:13:42 INFO - Downloaded 14413740 bytes. 19:13:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:13:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:13:43 INFO - Return code: 0 19:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:13:43 INFO - https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:13:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:13:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:13:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:13:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:13:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:13:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:13:43 INFO - retry: Failed, sleeping 30 seconds before retrying 19:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 19:14:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:14:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:14:13 INFO - retry: Failed, sleeping 60 seconds before retrying 19:15:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 19:15:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:15:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:15:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 19:15:13 INFO - Caught exception: HTTP Error 404: Not Found 19:15:13 INFO - Caught exception: HTTP Error 404: Not Found 19:15:13 INFO - Caught exception: HTTP Error 404: Not Found 19:15:13 INFO - trying https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:15:13 INFO - Downloading https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:15:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:15:14 INFO - Downloaded 56774505 bytes. 19:15:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:15:14 INFO - mkdir: /builds/slave/test/properties 19:15:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:15:14 INFO - Writing to file /builds/slave/test/properties/build_url 19:15:14 INFO - Contents: 19:15:14 INFO - build_url:https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:15:15 INFO - Running post-action listener: _resource_record_post_action 19:15:15 INFO - Running post-action listener: set_extra_try_arguments 19:15:15 INFO - [mozharness: 2016-09-23 02:15:15.187590Z] Finished download-and-extract step (success) 19:15:15 INFO - [mozharness: 2016-09-23 02:15:15.187825Z] Running populate-webroot step. 19:15:15 INFO - Running pre-action listener: _resource_record_pre_action 19:15:15 INFO - Running main action method: populate_webroot 19:15:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:15:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:15:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:15:15 INFO - '--tppagecycles', 19:15:15 INFO - '7'], 19:15:15 INFO - 'tests': ['tsvgm']}}, 19:15:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:15:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:15:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:15:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:15:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['damp', 'tps']}, 19:15:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'tests': ['damp', 'tps']}, 19:15:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['dromaeo_dom']}, 19:15:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:15:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['basic_compositor_video']}, 19:15:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:15:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'other-e10s': {'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['a11yr', 19:15:15 INFO - 'ts_paint', 19:15:15 INFO - 'tpaint', 19:15:15 INFO - 'sessionrestore', 19:15:15 INFO - 'sessionrestore_no_auto_restore', 19:15:15 INFO - 'tabpaint']}, 19:15:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['tsvgx', 19:15:15 INFO - 'tsvgr_opacity', 19:15:15 INFO - 'tart', 19:15:15 INFO - 'tscrollx', 19:15:15 INFO - 'cart']}, 19:15:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:15:15 INFO - 'tsvgr_opacity', 19:15:15 INFO - 'tart', 19:15:15 INFO - 'tscrollx', 19:15:15 INFO - 'cart']}, 19:15:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'talos_options': ['--disable-e10s'], 19:15:15 INFO - 'tests': ['tp5o']}, 19:15:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'tests': ['tp5o']}, 19:15:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'talos_options': ['--disable-e10s', 19:15:15 INFO - '--xperf_path', 19:15:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:15 INFO - 'tests': ['tp5n']}, 19:15:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:15:15 INFO - 'talos_options': ['--xperf_path', 19:15:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:15 INFO - 'tests': ['tp5n']}}, 19:15:15 INFO - 'talos.zip': {'path': '', 19:15:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:15:15 INFO - Running post-action listener: _resource_record_post_action 19:15:15 INFO - [mozharness: 2016-09-23 02:15:15.205642Z] Finished populate-webroot step (success) 19:15:15 INFO - [mozharness: 2016-09-23 02:15:15.205733Z] Running create-virtualenv step. 19:15:15 INFO - Running pre-action listener: _resource_record_pre_action 19:15:15 INFO - Running main action method: create_virtualenv 19:15:15 INFO - Creating virtualenv /builds/slave/test/build/venv 19:15:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:15:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:15:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:15:15 INFO - Using real prefix '/usr' 19:15:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:15:15 INFO - Installing distribute.............................................................................................................................................................................................done. 19:15:17 INFO - Installing pip.................done. 19:15:17 INFO - Return code: 0 19:15:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:15:17 INFO - Using env: {'DISPLAY': ':0', 19:15:17 INFO - 'HOME': '/home/cltbld', 19:15:17 INFO - 'LANG': 'en_US.UTF-8', 19:15:17 INFO - 'LANGUAGE': 'en_US:en', 19:15:17 INFO - 'LOGNAME': 'cltbld', 19:15:17 INFO - 'MAIL': '/var/mail/cltbld', 19:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:17 INFO - 'MOZ_NO_REMOTE': '1', 19:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:17 INFO - 'NO_EM_RESTART': '1', 19:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:17 INFO - 'PWD': '/builds/slave/test', 19:15:17 INFO - 'SHELL': '/bin/bash', 19:15:17 INFO - 'SHLVL': '1', 19:15:17 INFO - 'TERM': 'linux', 19:15:17 INFO - 'TMOUT': '86400', 19:15:17 INFO - 'USER': 'cltbld', 19:15:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:17 INFO - '_': '/tools/buildbot/bin/python'} 19:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:15:17 INFO - Cleaning up... 19:15:18 INFO - Return code: 0 19:15:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:15:18 INFO - Using env: {'DISPLAY': ':0', 19:15:18 INFO - 'HOME': '/home/cltbld', 19:15:18 INFO - 'LANG': 'en_US.UTF-8', 19:15:18 INFO - 'LANGUAGE': 'en_US:en', 19:15:18 INFO - 'LOGNAME': 'cltbld', 19:15:18 INFO - 'MAIL': '/var/mail/cltbld', 19:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:18 INFO - 'MOZ_NO_REMOTE': '1', 19:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:18 INFO - 'NO_EM_RESTART': '1', 19:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:18 INFO - 'PWD': '/builds/slave/test', 19:15:18 INFO - 'SHELL': '/bin/bash', 19:15:18 INFO - 'SHLVL': '1', 19:15:18 INFO - 'TERM': 'linux', 19:15:18 INFO - 'TMOUT': '86400', 19:15:18 INFO - 'USER': 'cltbld', 19:15:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:18 INFO - '_': '/tools/buildbot/bin/python'} 19:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:18 INFO - Downloading/unpacking psutil>=3.1.1 19:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:20 INFO - Installing collected packages: psutil 19:15:20 INFO - Running setup.py install for psutil 19:15:20 INFO - building 'psutil._psutil_linux' extension 19:15:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:15:20 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 19:15:20 INFO - building 'psutil._psutil_posix' extension 19:15:20 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 19:15:20 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 19:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:20 INFO - Successfully installed psutil 19:15:20 INFO - Cleaning up... 19:15:20 INFO - Return code: 0 19:15:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:15:20 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')]} 19:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:20 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')]} 19:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:20 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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:20 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 19:15:20 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 19:15:20 INFO - Using env: {'DISPLAY': ':0', 19:15:20 INFO - 'HOME': '/home/cltbld', 19:15:20 INFO - 'LANG': 'en_US.UTF-8', 19:15:20 INFO - 'LANGUAGE': 'en_US:en', 19:15:20 INFO - 'LOGNAME': 'cltbld', 19:15:20 INFO - 'MAIL': '/var/mail/cltbld', 19:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:20 INFO - 'MOZ_NO_REMOTE': '1', 19:15:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:20 INFO - 'NO_EM_RESTART': '1', 19:15:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:20 INFO - 'PWD': '/builds/slave/test', 19:15:20 INFO - 'SHELL': '/bin/bash', 19:15:20 INFO - 'SHLVL': '1', 19:15:20 INFO - 'TERM': 'linux', 19:15:20 INFO - 'TMOUT': '86400', 19:15:20 INFO - 'USER': 'cltbld', 19:15:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:20 INFO - '_': '/tools/buildbot/bin/python'} 19:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:15:22 INFO - Installing collected packages: mozsystemmonitor 19:15:22 INFO - Running setup.py install for mozsystemmonitor 19:15:23 INFO - Successfully installed mozsystemmonitor 19:15:23 INFO - Cleaning up... 19:15:23 INFO - Return code: 0 19:15:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15: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')]} 19:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:15:23 INFO - Using env: {'DISPLAY': ':0', 19:15:23 INFO - 'HOME': '/home/cltbld', 19:15:23 INFO - 'LANG': 'en_US.UTF-8', 19:15:23 INFO - 'LANGUAGE': 'en_US:en', 19:15:23 INFO - 'LOGNAME': 'cltbld', 19:15:23 INFO - 'MAIL': '/var/mail/cltbld', 19:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:23 INFO - 'MOZ_NO_REMOTE': '1', 19:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:23 INFO - 'NO_EM_RESTART': '1', 19:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:23 INFO - 'PWD': '/builds/slave/test', 19:15:23 INFO - 'SHELL': '/bin/bash', 19:15:23 INFO - 'SHLVL': '1', 19:15:23 INFO - 'TERM': 'linux', 19:15:23 INFO - 'TMOUT': '86400', 19:15:23 INFO - 'USER': 'cltbld', 19:15:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:23 INFO - '_': '/tools/buildbot/bin/python'} 19:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:23 INFO - Downloading/unpacking jsonschema==2.5.1 19:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:25 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:25 INFO - warning: no files found matching '*.txt' 19:15:25 INFO - no previously-included directories found matching 'build' 19:15:25 INFO - no previously-included directories found matching 'dist' 19:15:25 INFO - no previously-included directories found matching '.git*' 19:15:25 INFO - Installing collected packages: jsonschema, functools32 19:15:25 INFO - Running setup.py install for functools32 19:15:25 INFO - warning: no files found matching '*.txt' 19:15:25 INFO - no previously-included directories found matching 'build' 19:15:25 INFO - no previously-included directories found matching 'dist' 19:15:25 INFO - no previously-included directories found matching '.git*' 19:15:25 INFO - Successfully installed jsonschema functools32 19:15:25 INFO - Cleaning up... 19:15:25 INFO - Return code: 0 19:15:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:15:25 INFO - Using env: {'DISPLAY': ':0', 19:15:25 INFO - 'HOME': '/home/cltbld', 19:15:25 INFO - 'LANG': 'en_US.UTF-8', 19:15:25 INFO - 'LANGUAGE': 'en_US:en', 19:15:25 INFO - 'LOGNAME': 'cltbld', 19:15:25 INFO - 'MAIL': '/var/mail/cltbld', 19:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:25 INFO - 'MOZ_NO_REMOTE': '1', 19:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:25 INFO - 'NO_EM_RESTART': '1', 19:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:25 INFO - 'PWD': '/builds/slave/test', 19:15:25 INFO - 'SHELL': '/bin/bash', 19:15:25 INFO - 'SHLVL': '1', 19:15:25 INFO - 'TERM': 'linux', 19:15:25 INFO - 'TMOUT': '86400', 19:15:25 INFO - 'USER': 'cltbld', 19:15:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:25 INFO - '_': '/tools/buildbot/bin/python'} 19:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:15:25 INFO - Cleaning up... 19:15:25 INFO - Return code: 0 19:15:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:15:25 INFO - Using env: {'DISPLAY': ':0', 19:15:25 INFO - 'HOME': '/home/cltbld', 19:15:25 INFO - 'LANG': 'en_US.UTF-8', 19:15:25 INFO - 'LANGUAGE': 'en_US:en', 19:15:25 INFO - 'LOGNAME': 'cltbld', 19:15:25 INFO - 'MAIL': '/var/mail/cltbld', 19:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:25 INFO - 'MOZ_NO_REMOTE': '1', 19:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:25 INFO - 'NO_EM_RESTART': '1', 19:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:25 INFO - 'PWD': '/builds/slave/test', 19:15:25 INFO - 'SHELL': '/bin/bash', 19:15:25 INFO - 'SHLVL': '1', 19:15:25 INFO - 'TERM': 'linux', 19:15:25 INFO - 'TMOUT': '86400', 19:15:25 INFO - 'USER': 'cltbld', 19:15:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:25 INFO - '_': '/tools/buildbot/bin/python'} 19:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:26 INFO - Downloading/unpacking blobuploader==1.2.4 19:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:27 INFO - Downloading blobuploader-1.2.4.tar.gz 19:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:15:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:15:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:28 INFO - Downloading docopt-0.6.1.tar.gz 19:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:15:28 INFO - Installing collected packages: blobuploader, requests, docopt 19:15:28 INFO - Running setup.py install for blobuploader 19:15:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:15:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:15:28 INFO - Running setup.py install for requests 19:15:29 INFO - Running setup.py install for docopt 19:15:29 INFO - Successfully installed blobuploader requests docopt 19:15:29 INFO - Cleaning up... 19:15:29 INFO - Return code: 0 19:15:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:29 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')]} 19:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:29 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')]} 19:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:29 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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:29 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 19:15:29 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 19:15:29 INFO - Using env: {'DISPLAY': ':0', 19:15:29 INFO - 'HOME': '/home/cltbld', 19:15:29 INFO - 'LANG': 'en_US.UTF-8', 19:15:29 INFO - 'LANGUAGE': 'en_US:en', 19:15:29 INFO - 'LOGNAME': 'cltbld', 19:15:29 INFO - 'MAIL': '/var/mail/cltbld', 19:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:29 INFO - 'MOZ_NO_REMOTE': '1', 19:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:29 INFO - 'NO_EM_RESTART': '1', 19:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:29 INFO - 'PWD': '/builds/slave/test', 19:15:29 INFO - 'SHELL': '/bin/bash', 19:15:29 INFO - 'SHLVL': '1', 19:15:29 INFO - 'TERM': 'linux', 19:15:29 INFO - 'TMOUT': '86400', 19:15:29 INFO - 'USER': 'cltbld', 19:15:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:29 INFO - '_': '/tools/buildbot/bin/python'} 19:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:29 INFO - Running setup.py (path:/tmp/pip-dV3qmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:29 INFO - Running setup.py (path:/tmp/pip-VICFAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:29 INFO - Running setup.py (path:/tmp/pip-GqbsRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:29 INFO - Running setup.py (path:/tmp/pip-NAdFXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:29 INFO - Running setup.py (path:/tmp/pip-NmwOVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:29 INFO - Running setup.py (path:/tmp/pip-aJ6J3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:29 INFO - Running setup.py (path:/tmp/pip-ZNX9hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:30 INFO - Running setup.py (path:/tmp/pip-BsoCKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:30 INFO - Running setup.py (path:/tmp/pip-fdMfFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:30 INFO - Running setup.py (path:/tmp/pip-LWs0jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:30 INFO - Running setup.py (path:/tmp/pip-Wi6yVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:30 INFO - Running setup.py (path:/tmp/pip-czqK24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:30 INFO - Running setup.py (path:/tmp/pip-usRz27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:30 INFO - Running setup.py (path:/tmp/pip-8TCnHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:30 INFO - Running setup.py (path:/tmp/pip-6dbYl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:30 INFO - Running setup.py (path:/tmp/pip-rh44n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:30 INFO - Running setup.py (path:/tmp/pip-oEd_7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:15:30 INFO - Running setup.py install for manifestparser 19:15:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:15:30 INFO - Running setup.py install for mozcrash 19:15:30 INFO - Running setup.py install for mozdebug 19:15:31 INFO - Running setup.py install for mozdevice 19:15:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozfile 19:15:31 INFO - Running setup.py install for mozhttpd 19:15:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozinfo 19:15:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozInstall 19:15:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozleak 19:15:31 INFO - Running setup.py install for mozlog 19:15:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for moznetwork 19:15:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:15:31 INFO - Running setup.py install for mozprocess 19:15:31 INFO - Running setup.py install for mozprofile 19:15:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Running setup.py install for mozrunner 19:15:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Running setup.py install for mozscreenshot 19:15:32 INFO - Running setup.py install for moztest 19:15:32 INFO - Running setup.py install for mozversion 19:15:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:15:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:15:32 INFO - Cleaning up... 19:15:32 INFO - Return code: 0 19:15:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:32 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')]} 19:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:32 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')]} 19:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:32 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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:32 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 19:15:32 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 19:15:32 INFO - Using env: {'DISPLAY': ':0', 19:15:32 INFO - 'HOME': '/home/cltbld', 19:15:32 INFO - 'LANG': 'en_US.UTF-8', 19:15:32 INFO - 'LANGUAGE': 'en_US:en', 19:15:32 INFO - 'LOGNAME': 'cltbld', 19:15:32 INFO - 'MAIL': '/var/mail/cltbld', 19:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:32 INFO - 'MOZ_NO_REMOTE': '1', 19:15:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:32 INFO - 'NO_EM_RESTART': '1', 19:15:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:32 INFO - 'PWD': '/builds/slave/test', 19:15:32 INFO - 'SHELL': '/bin/bash', 19:15:32 INFO - 'SHLVL': '1', 19:15:32 INFO - 'TERM': 'linux', 19:15:32 INFO - 'TMOUT': '86400', 19:15:32 INFO - 'USER': 'cltbld', 19:15:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:32 INFO - '_': '/tools/buildbot/bin/python'} 19:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:32 INFO - Running setup.py (path:/tmp/pip-4NVJtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:32 INFO - Running setup.py (path:/tmp/pip-Om9e2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:32 INFO - Running setup.py (path:/tmp/pip-LMiJxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:32 INFO - Running setup.py (path:/tmp/pip-BknUEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:33 INFO - Running setup.py (path:/tmp/pip-bBMv2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 INFO - Running setup.py (path:/tmp/pip-hbbxt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 INFO - Running setup.py (path:/tmp/pip-LXyxov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 INFO - Running setup.py (path:/tmp/pip-phN0Qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:33 INFO - Running setup.py (path:/tmp/pip-uhNpy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:33 INFO - Running setup.py (path:/tmp/pip-qjWZgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:33 INFO - Running setup.py (path:/tmp/pip-YFFWQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:33 INFO - Running setup.py (path:/tmp/pip-pHFjFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:33 INFO - Running setup.py (path:/tmp/pip-KTGheF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:33 INFO - Running setup.py (path:/tmp/pip-5OOsBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:33 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)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:33 INFO - Running setup.py (path:/tmp/pip-4cpUE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:33 INFO - Running setup.py (path:/tmp/pip-OkpS_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:15:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:33 INFO - Running setup.py (path:/tmp/pip-T6Wag9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:15:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:35 INFO - Downloading blessings-1.6.tar.gz 19:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:36 INFO - Installing collected packages: blessings 19:15:36 INFO - Running setup.py install for blessings 19:15:36 INFO - Successfully installed blessings 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:36 INFO - Reading from file tmpfile_stdout 19:15:36 INFO - Current package versions: 19:15:36 INFO - argparse == 1.2.1 19:15:36 INFO - blessings == 1.6 19:15:36 INFO - blobuploader == 1.2.4 19:15:36 INFO - docopt == 0.6.1 19:15:36 INFO - functools32 == 3.2.3-2 19:15:36 INFO - jsonschema == 2.5.1 19:15:36 INFO - manifestparser == 1.1 19:15:36 INFO - mozInstall == 1.12 19:15:36 INFO - mozcrash == 0.17 19:15:36 INFO - mozdebug == 0.1 19:15:36 INFO - mozdevice == 0.48 19:15:36 INFO - mozfile == 1.2 19:15:36 INFO - mozhttpd == 0.7 19:15:36 INFO - mozinfo == 0.9 19:15:36 INFO - mozleak == 0.1 19:15:36 INFO - mozlog == 3.3 19:15:36 INFO - moznetwork == 0.27 19:15:36 INFO - mozprocess == 0.23 19:15:36 INFO - mozprofile == 0.28 19:15:36 INFO - mozrunner == 6.12 19:15:36 INFO - mozscreenshot == 0.1 19:15:36 INFO - mozsystemmonitor == 0.3 19:15:36 INFO - moztest == 0.7 19:15:36 INFO - mozversion == 1.4 19:15:36 INFO - psutil == 3.1.1 19:15:36 INFO - requests == 1.2.3 19:15:36 INFO - wsgiref == 0.1.2 19:15:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:36 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 19:15:36 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 19:15:36 INFO - Using env: {'DISPLAY': ':0', 19:15:36 INFO - 'HOME': '/home/cltbld', 19:15:36 INFO - 'LANG': 'en_US.UTF-8', 19:15:36 INFO - 'LANGUAGE': 'en_US:en', 19:15:36 INFO - 'LOGNAME': 'cltbld', 19:15:36 INFO - 'MAIL': '/var/mail/cltbld', 19:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:36 INFO - 'MOZ_NO_REMOTE': '1', 19:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:36 INFO - 'NO_EM_RESTART': '1', 19:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:36 INFO - 'PWD': '/builds/slave/test', 19:15:36 INFO - 'SHELL': '/bin/bash', 19:15:36 INFO - 'SHLVL': '1', 19:15:36 INFO - 'TERM': 'linux', 19:15:36 INFO - 'TMOUT': '86400', 19:15:36 INFO - 'USER': 'cltbld', 19:15:36 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:36 INFO - '_': '/tools/buildbot/bin/python'} 19:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 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)) 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:15:36 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 0x10d9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, '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': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', '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 19:15:36 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 19:15:36 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 19:15:36 INFO - Using env: {'DISPLAY': ':0', 19:15:36 INFO - 'HOME': '/home/cltbld', 19:15:36 INFO - 'LANG': 'en_US.UTF-8', 19:15:36 INFO - 'LANGUAGE': 'en_US:en', 19:15:36 INFO - 'LOGNAME': 'cltbld', 19:15:36 INFO - 'MAIL': '/var/mail/cltbld', 19:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:36 INFO - 'MOZ_NO_REMOTE': '1', 19:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:36 INFO - 'NO_EM_RESTART': '1', 19:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:36 INFO - 'PWD': '/builds/slave/test', 19:15:36 INFO - 'SHELL': '/bin/bash', 19:15:36 INFO - 'SHLVL': '1', 19:15:36 INFO - 'TERM': 'linux', 19:15:36 INFO - 'TMOUT': '86400', 19:15:36 INFO - 'USER': 'cltbld', 19:15:36 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:36 INFO - '_': '/tools/buildbot/bin/python'} 19:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:36 INFO - Cleaning up... 19:15:36 INFO - Return code: 0 19:15:36 INFO - Running post-action listener: _resource_record_post_action 19:15:36 INFO - Running post-action listener: _start_resource_monitoring 19:15:36 INFO - Starting resource monitoring. 19:15:36 INFO - [mozharness: 2016-09-23 02:15:36.845615Z] Finished create-virtualenv step (success) 19:15:36 INFO - [mozharness: 2016-09-23 02:15:36.846040Z] Running install step. 19:15:36 INFO - Running pre-action listener: _resource_record_pre_action 19:15:36 INFO - Running main action method: install 19:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:37 INFO - Reading from file tmpfile_stdout 19:15:37 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:37 INFO - Reading from file tmpfile_stdout 19:15:37 INFO - Output received: 19:15:37 INFO - Usage: mozinstall [options] installer 19:15:37 INFO - Options: 19:15:37 INFO - -h, --help show this help message and exit 19:15:37 INFO - -d DEST, --destination=DEST 19:15:37 INFO - Directory to install application into. [default: 19:15:37 INFO - "/builds/slave/test"] 19:15:37 INFO - --app=APP Application being installed. [default: firefox] 19:15:37 INFO - mkdir: /builds/slave/test/build/application 19:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:15:51 INFO - Reading from file tmpfile_stdout 19:15:51 INFO - Output received: 19:15:51 INFO - /builds/slave/test/build/application/firefox/firefox 19:15:51 INFO - Running post-action listener: _resource_record_post_action 19:15:51 INFO - [mozharness: 2016-09-23 02:15:51.367909Z] Finished install step (success) 19:15:51 INFO - [mozharness: 2016-09-23 02:15:51.368187Z] Running run-tests step. 19:15:51 INFO - Running pre-action listener: _resource_record_pre_action 19:15:51 INFO - Running main action method: run_tests 19:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:51 INFO - Python 2.7.3 19:15:51 INFO - Return code: 0 19:15:51 INFO - grabbing minidump binary from tooltool 19:15:51 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')]} 19:15:51 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 0x13983b0>, '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 0x13992b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1399740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:51 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 19:15:51 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 19:15:51 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 19:15:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:51 INFO - Return code: 0 19:15:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:15:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:15:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:15:51 INFO - Using env: {'DISPLAY': ':0', 19:15:51 INFO - 'HOME': '/home/cltbld', 19:15:51 INFO - 'LANG': 'en_US.UTF-8', 19:15:51 INFO - 'LANGUAGE': 'en_US:en', 19:15:51 INFO - 'LOGNAME': 'cltbld', 19:15:51 INFO - 'MAIL': '/var/mail/cltbld', 19:15:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:51 INFO - 'MOZ_NO_REMOTE': '1', 19:15:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:15:51 INFO - 'NO_EM_RESTART': '1', 19:15:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:51 INFO - 'PWD': '/builds/slave/test', 19:15:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:51 INFO - 'SHELL': '/bin/bash', 19:15:51 INFO - 'SHLVL': '1', 19:15:51 INFO - 'TERM': 'linux', 19:15:51 INFO - 'TMOUT': '86400', 19:15:51 INFO - 'USER': 'cltbld', 19:15:51 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482', 19:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:51 INFO - '_': '/tools/buildbot/bin/python'} 19:15:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:15:51 INFO - mozversion application_buildid: 20160922172259 19:15:51 INFO - mozversion application_changeset: 0764a290e487d47d05cdb290f70b4e503a40bb63 19:15:51 INFO - mozversion application_display_name: Firefox 19:15:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:51 INFO - mozversion application_name: Firefox 19:15:51 INFO - mozversion application_remotingname: firefox 19:15:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:15:51 INFO - mozversion application_vendor: Mozilla 19:15:51 INFO - mozversion application_version: 50.0 19:15:51 INFO - mozversion platform_buildid: 20160922172259 19:15:51 INFO - mozversion platform_changeset: 0764a290e487d47d05cdb290f70b4e503a40bb63 19:15:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:15:51 INFO - mozversion platform_version: 50.0 19:15:51 INFO - using testdate: 1474596951 19:15:51 INFO - actual date: 1474596951 19:15:51 INFO - starting webserver on 'localhost:44216' 19:15:51 INFO - SUITE-START | Running 2 tests 19:15:51 INFO - TEST-START | dromaeo_css 19:15:51 INFO - operating with platform_type : linux_ 19:15:51 INFO - Initialising browser for dromaeo_css test... 19:15:51 INFO - TEST-INFO | started process 18651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTW5KSV/profile http://localhost:44216/getInfo.html) 19:15:56 INFO - PROCESS | 18651 | __metrics Screen width/height:1600/1200 19:15:56 INFO - PROCESS | 18651 | colorDepth:24 19:15:56 INFO - PROCESS | 18651 | Browser inner width/height: 1024/697 19:15:56 INFO - PROCESS | 18651 | __metrics 19:15:57 INFO - PROCESS | 18651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:15:57 INFO - PROCESS | 18651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:57 INFO - PROCESS | 18651 | console.error: PushService: 19:15:57 INFO - PROCESS | 18651 | stateChangeProcessEnqueue: Error transitioning state 19:15:57 INFO - PROCESS | 18651 | UnknownError 19:15:58 INFO - TEST-INFO | 18651: exit 0 19:15:58 INFO - Browser initialized. 19:15:58 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:58 INFO - TEST-INFO | started process 18738 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTW5KSV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:09 INFO - PROCESS | 18738 | RSS: Main: 143687680 19:16:09 INFO - PROCESS | 18738 | 19:17:00 INFO - PROCESS | 18738 | 1474597020553 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] 19:19:09 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44216/tests/dromaeo/cssquery-ext.html) 19:19:10 INFO - PROCESS | 18738 | RSS: Main: 224346112 19:19:10 INFO - PROCESS | 18738 | 19:22:12 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-ext.html (next: http://localhost:44216/tests/dromaeo/cssquery-jquery.html) 19:22:13 INFO - PROCESS | 18738 | RSS: Main: 241569792 19:22:13 INFO - PROCESS | 18738 | 19:25:15 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44216/tests/dromaeo/cssquery-mootools.html) 19:25:16 INFO - PROCESS | 18738 | RSS: Main: 177991680 19:25:16 INFO - PROCESS | 18738 | 19:28:17 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44216/tests/dromaeo/cssquery-prototype.html) 19:28:18 INFO - PROCESS | 18738 | RSS: Main: 176877568 19:28:18 INFO - PROCESS | 18738 | 19:31:21 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44216/tests/dromaeo/cssquery-yui.html) 19:31:21 INFO - PROCESS | 18738 | RSS: Main: 177025024 19:31:21 INFO - PROCESS | 18738 | 19:34:23 INFO - PROCESS | 18738 | Cycle 1(1): loaded http://localhost:44216/tests/dromaeo/cssquery-yui.html (next: http://localhost:44216/tests/dromaeo/cssquery-dojo.html) 19:34:24 INFO - PROCESS | 18738 | RSS: Main: 255549440 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | __start_tp_report 19:34:24 INFO - PROCESS | 18738 | _x_x_mozilla_page_load 19:34:24 INFO - PROCESS | 18738 | _x_x_mozilla_page_load_details 19:34:24 INFO - PROCESS | 18738 | |i|pagename|runs| 19:34:24 INFO - PROCESS | 18738 | |0;dojo.html;3228.5802845630064;3244.172735952331;3255.690709382608;3259.3155437358155;3296.6538513456085;5606.663600183987;5693.686847223401;5693.886122277552;5698.715064246791;5734.770609175628;5684.459976302255;5743.712814359275;5744.569157313197;5750.3962083981205;5751.079827227644;4968.378952630921;5029.195328747401;5176.5341119299255;5180.04169228692;5235.607329450288;5123.5901127909865;5172.896542069157;5175.353080864892;5176.715280659581;5185.144451165553;5046.697198168104;5089.567386772111;5098.878246785701;5117.360329958755;5130.974345128269;5194.662346947436;5208.140656791625;5208.9739551302;5210;5230.398504172012;4924.704517728947;4995.750212489397;5073.8731531712;5112.130937740593;5115.079285728568;5527.53015993641;5540.168974653794;5543.501084902378;5556.194351818964;5564.165375193713;5987.580869339148;5999.790007349722;6005.399460054004;6009.729562169669;6014.3985601439945;5959.28488581373;6039.516838652898;6048.39516048396;6048.455638992512;6118.479929205978;5575.665460072368;5627.774889004476;5635.070213414781;5664.235328230719;5719.771209151629;5847.298324201123;6146.6004709693725;6212.875742485125;6216.943119669577;6231.781887633911;5902.439268269508;5981.850453738692;5986.880262394728;6067.993770803335;6073.574849760563;6131.84670383244;6146.969265153645;6149.538784591174;6151.846203844851;6153.600015998945;5769.307683078057;6053.818385448356;6056.697165141725;6107.083937409336;6113.32753397126;5869.178315035899;5919.260092488439;5948.375420580864;5999.160117583542;6035.336113027563;4588.49526552079;4598.724076555417;4605.723656580549;4606.332081848181;4634.58288754007;3949.3681011038097;4074.959250407517;4097.405876147942;4115.526714427879;4127.33962565994;5596.048671725816;5748.223989761413;5759.942400576025;5818.272715910512;5855.912161317584;6089.695515224221;6121;6126.785562505291;6128.356522565066;6137.294211165684;5503.669779813144;5785.595008349459;5793.942060579425;5795.739191736382;5805.94194058054;5057.974710126499;5114.616403769658;5121.8719532012;5125.077486052472;5127.025865085622;6278.183836101278;6284.057391391328;6290.056491526308;6297.937020629826;6298.590591611531;5864.736086876015;6066.848328791639;6073.240844894388;6086.847828804315;6092.939070609237;6170.845728856814;6229.781957631642;6272.435480806566;6331.810045698637;6363.809085727436;5966.373530779206;6011.068284415923;6024.728887199999;6031.336552979256;6067.453929146398;5871.882562348815;6147.754089836357;6148.661823599745;6157.168782214344;6158.753649853956;5817.76728930838;5870.324912634931;5892;5939.851503712442;5989.221401217988;5912.70436478183;5923.111533270044;5927.762889484545;5930.140129681258;5930.525557955267;5694.971525142348;5839.124131380327;5875.794347197828;5887.0580707086665;5889.64662120257;5924.022536281465;5980.252468441445;5988.520918326436;6055.091736239599;6060.727267272895;5909.054551271776;5912.852178695567;5913.379095195115;5913.733881975236;5914.940850591439 19:34:24 INFO - PROCESS | 18738 | |1;ext.html;571.225988785196;583.314605338727;622.4211483320513;622.8816524860276;651.6285717141226;34771.478427823604;46012.53987460124;46866;46900.999999999956;46910;49915;50368.748156259164;50454.243186352236;50497.495025049735;50534;8582.184692454206;9225.215856652176;9390.389624674408;9392.530373481333;10867.619633312836;10780.40707761073;11223.438828058604;11229.32624042556;11235.21353505255;11237.269577477478;11175.21773475853;11241.494132764044;11252.54989800411;11285.943570282177;11288.548458061668;11092.889071109306;11162.441877906112;11245.943770281096;11249.775004499865;11278.605248816335;10874.45627718611;10922.672319830417;10941.726456838644;10951.780964380747;10962.725931851688;46375.76812115952;46875;46947.765261173816;46949.29576056362;46987;3618.565772107364;3768.962310376916;4295.8496452624;4371.23503386906;4388.670849686255;34965.650343496745;35251;35313.293734125175;35412;35598.11004724877;4767.165745994437;4810.615150787964;4812.109759694433;4851.199552073904;4888.755562221874;374201;374906;376924;377519.99999999726;379734;35712.46431303533;65238.673806630664;65527.34472655308;65658.34341656617;65663.34336656572;40110.59889401068;40149.799251003555;40161;40173.799131004154;40182.397264041065;17144.314227430765;17369.652606948042;17379.217935192948;17380.913095434695;17386;13526.661833454242;14436.566902992927;14594.270286485846;14687.339069741887;14727.55817325482;9321.766955826159;9398.953005235067;9440.10319019692;9446.244300455946;9477;3537.7523573349617;3574.428091505347;3587.94618080729;3594.137407022296;3677.669009789132;1024.2011231239644;1106.2201148190668;1116.542217690743;1125.4204084896285;1165.0795871261778;35557.64442355543;36054.459183112274;36077.27845443129;36335.4549681755;36428.63571364305;1144.9141314401454;1146.2492067695673;1163.1857699610307;1176.805827038529;1224.7550489902055;17784.3775467858;18670.346537871108;18774.436766897015;18861.52846178829;18985.715214271797;598040;612232;621608;625004;1038668;68463;69072;69121.30878691249;69183.3081669187;69214.65392673004;1340.6983428728458;1427.0581416265215;1481.4740767027765;1486.8810495160362;1496.0948626081065;32290.192745181557;35933.64066359407;36255;36384;36562.45156322711;55687.16469252884;56591;56614.7169264151;56649.43350566441;56749;362147;363226;363324;363729;364862;27933.16200513988;28192.85903570469;28207.71792282051;30891;30951.226219344695;42864.571354287305;44762.55237447584;52474.73762631315;53663.19505207347;57773.71113144408;44676;46645.53354466548;47026;47945;48263.09582456331;44396.55603444053;45221.095578087596;45446.31830522611;45997.77001114973;46950;44964.738226152906;45039;45301.09397812091;45393.09213815771;45768.54231457643;37872.43191352077;40352.805956724274;40616.79691601523;40870.386944196456;41457.170856582095;29664.851675741484;30280.84859575688;30485;30516.542251866686;30741.692583073884 19:34:24 INFO - PROCESS | 18738 | |2;jquery.html;401.12954887893267;408.17955908623657;414.40325930659856;421.24807219113876;429.3002406078092;18978.335758248464;20252.291169809043;20708.585828283463;21058;21076.789232107676;19000.714989275173;19353.709694354562;19577.21691132353;20304.39086827394;20502.794972050313;5946.554008449362;6155.476784473302;6157.90763138551;6159.38406159385;6173.660448675343;5622.634528755638;5822.214001109859;5842.766289348442;6101.78643747469;6160.938390616104;5866.706664666771;5916.4970977466755;5920.792772252972;5933.13969474426;5950.494207992304;5906.793262235823;6010.699465026753;6158.476529494998;6159.4456498915315;6162.568620196588;5926.436988486087;6098.451139397475;6107.511399088063;6148.907766383508;6155;14639.63400914975;14778.926105369512;14799.778003329959;20386.49033774153;24590.877045614838;2436.439618887648;2476.826622136451;2499.787518060967;2613.3074735195;2980.5976193214074;10453.738656533647;10630.946845265724;10950.890491095146;11767.646970590897;15470.845291547053;2028.8579799414047;2046.0894901768718;2117.862338947979;2128.3508529898377;2164.404788683109;62161;66960;80628.79056814153;81875;82702.17297827004;11156.609518666808;11192.272502287326;11302.321860688386;11333.150013749002;11492.195546311677;15308.846911530965;15423.922880385526;15559.222038898237;15719.68560628781;15768.369265229263;8647.481151130953;8720.258778003941;8740.082291359317;8876.733697989072;9113.134252246025;8732.126787321216;8741.475511469205;8742.256908162877;8844.867326990101;8975.50634715097;4809.927851082237;4830.879228019257;4853.247746599282;4894.021195760862;4904.583110435581;2034.8372130229545;2050.507878109243;2069.5860827834194;2080.0951586060114;2129.8189653879285;977.2231076294363;998.32612986234;1027.4965267019106;1031.4945676618404;1062.0706881478707;20139.899300503403;20589.176432942513;21111.155553777677;21193.576128477343;21217.363479095653;1004.7538353103537;1010.1010101010107;1054.3779170289565;1056.6513050693252;1072.222638587018;10775.299605525615;11691.298522088702;11740.412979350998;11872.643820685393;11941.820872686916;522879;553649;571839;588341;590953;74192;87816.560917195;92284.53857730802;99445;100311.49844250732;980.4215512847454;1025.29254814178;1028.804527139841;1049.58541376156;1050.7320633238476;16685.332586696397;18780.248790048245;18987;19283.421497355102;19402.61194776125;41907.7904610475;43761.56238437575;43790.34314485222;44159.11681766283;46557.76721116508;64013.67993160005;64030.67984660047;64069.679651601444;64445;64548.677256615294;8554.144585541397;8556.18716221958;8730.738077857675;8823.647054117764;8956.776080598038;9700.805983880424;12929.030322726021;13010.21938683682;13351.866481335452;13424.261665608095;11004.39475828837;11179.944100279447;11257.71855703614;11285.322880627189;11386.430678465878;11612.361320127475;11641.59254426091;11744.530218791153;11797.17419780607;12139.696507587381;10897.782044359226;11042.227044107156;11092.33445993243;11237.269577477438;11491.942540287246;8482.321414286966;8504.702335418231;8514.659413623633;8826.514541700268;9007.234385077341;10665.36007839532;11130.499127539442;11144.832827507757;11293.322400655987;11368.829467557827 19:34:24 INFO - PROCESS | 18738 | |3;mootools.html;1497.7154340675274;1519.483375652278;1679.1352453486456;1821.8724689271755;1823.717323814809;8639.308855291578;9291.117343852324;9292.907070929288;9294.442333459989;9310.581023853925;7348.706051757937;8580.742577722678;8918.197362237397;8964.955175224115;9138.954305228464;2336.4392545789015;2363.161077817376;2378.120095564635;2378.7978021868075;2382.95234095318;2349.694539709835;2378.357843382286;2386.2721869829693;2393.8444001139956;2394.126143957452;2397.4725560376687;2407.9036838526526;2421.7820396164343;2468.950620987579;2469.259222233332;2323.488832456857;2340.0990618611786;2341.309313752439;2344.097522453859;2348.213348528245;2338.8128949683983;2346.4133966508375;2354.1289722802503;2357.9882100589557;2359.728631207399;9146.359754817166;9197;9270.72187834366;9289.53552322388;9295.628174873065;5073.594112470993;5176.275321454999;5183.144781111112;5198.454162312978;5203.557697595671;3328.8169150696704;3331.0839519132433;3381.881634142818;3383.678550537695;3399.861046549409;1152.2337645465755;1157.8668453127914;1163.7846998305256;1197.9520819167224;1206.7465832175253;12640.936795315965;12658.683532911658;12677.492900283978;12682.429290681941;12700.809487857598;4331.740095594275;4491.124230775033;4570.565796249351;4662.323963025342;4673.322368256584;10591.947040264904;10602.787944241072;10609.416482093558;10614.734631634117;10621.893781062245;5290.285811415487;5334.67991920486;5337.839864804062;5339.7063161526485;5341.359036915517;4110.979445102756;4753.429588449409;4923.507649235119;4927.975360123248;4965.205567109318;4082.1283024000027;4123.257813593522;4171.0615111599645;4176.979115104405;4181.60274773896;1308.2281453942219;1314.907956443059;1315.6447759105179;1327.230206480239;1331.4940322677514;955.0031388560728;962.5860879821744;1018.180364806338;1021.1881554164308;1045.513836066233;6051.3828667228445;6135.877282454326;6186.597871138361;6227.688615569204;6309.684515774285;470.70110479845545;473.7820602522809;482.60667555941797;483.6735203737463;485.6673178872413;4206.747595144301;4224.007358270804;4269.743815371087;4291.442112525414;4295.226859165317;10410.583576656849;10874.45627718611;10888.29226100301;10896.61861834832;10902.890971090188;10528.894711052792;10791.190660700322;10800.621978230723;10805.297655652357;10813.83779243312;8042.597870106471;8390.874136887951;8406.453580517247;8408.621612027597;8424.073351931283;6277.74889004453;6288.213973253343;6440.742370305135;6582.440492558187;6749.392554670103;10436.791264174522;10463.529141188512;10536.367817930948;10592.947035265082;10662.093722033715;10406.947965260126;10526.894731052591;10720.839187412352;10805.405702686428;10810;7100.7869763907165;7765.844683106419;7973;7974.122846486998;8208.58957052133;9048.095190480899;9114.726558203265;9142.680006199751;9144.222741067084;9159.267258619428;8076.515409075474;8082.2725954664365;8163.061247956561;8287.171282871665;8322.08457069734;7904.446688731734;7925.28672419485;8028.397870159644;8070;8117.756467305991;7170.677319520739;7183.281671832984;7293.927060729325;7316.158641756266;7322.926770732438;5508.20131080999;5522.6134170609275;5551.639143455663;5588.720563971867;5644.012297847942;5571.303587051619;5580.693061881636;5656.915146272727;5717.342505611741;5762.30852297727 19:34:24 INFO - PROCESS | 18738 | |4;prototype.html;346.9531613232214;351.729168540224;352.10389558573434;352.30891719745216;353.64458718987413;5535.806246781363;5606.159076138581;5611.158326251064;5612.232146873732;5671.602987790856;5582.49757521823;5587.664740115596;5589.944100558993;5591.944080559193;5603.562450334098;3931.2727145478034;4140.689448291375;4199.748015119103;4331.371951067109;4345.674074444413;4314.935275970863;4315.4389929309145;4317.633001194902;4318.201132790444;4346.8913277168;4296.8281268749215;4322.308430651096;4335.393044973706;4340.305551111823;4350.6301964332915;4069.9389509157386;4273.978630106861;4343.500497442803;4343.739375637456;4349.760763158025;3764.6800021997888;3767.6044015378266;3780.4140358244517;3780.8487660493547;3792.867249646277;5356.812511562116;5364.222187782789;5369.892602147936;5384.380796208447;5402.432744561804;4856.15017371962;4940.382452193476;4955.488575984324;4969.329140566014;4994.675346102492;5618.073017952033;5699.487046165824;5717.71411429431;5725.227094342252;5764.74058667361;4158.750474971481;4166.604172603597;4172.332426811726;4173.24881521326;4190.099128687347;5874.765009399619;5961.2846458425265;6009.197240827778;6010.218671572711;6034.698265086772;4312.116016216668;4312.202242585132;4318.784060796947;4318.913621727548;4322.265214913472;4346.00041990344;4364.781760911942;4365.148795984816;4372.213001659732;4378.759168245777;4775.761211939389;4939.5554400104165;5001.174806156943;5021.949780502148;5029.119904016856;5786.07422812348;5791.247137872134;5806.838632273562;5817.418258174149;5890.823275301748;1479.2307999840023;1493.2533733133432;1508.9849101508844;1513.9697206055816;1533.3330001449406;925.9860452804169;966.7051549277397;975.5707488704948;978.515634760798;1001.3541265883464;4987.551120399182;5010.298558201855;5026.623003274769;5069.417017043087;5070.340855688737;5238.580913526908;5278.313819203479;5290.497402746733;5310.60170487215;5312.150055991023;5055.893547517035;5065.6200784941275;5104.9744751276;5290.523852853262;5769.134629805562;3215.951760723591;3217.951730724041;3221.5650887130405;3224.4196044712176;3243.6700952609317;6079.209702738615;6233.812985610439;6251.874962500816;6259.311475737665;6269.749210031548;6099.359567245464;6115.541334399938;6125.571210015345;6141.969290153521;6145.863015342176;5878.559108066912;6020.518358531394;6172.22847144107;6191.501801482148;6197.597156184834;5965.373635768356;5971.8354920790025;5991.221141251609;5994.490468310242;6001.90997135052;5766.769329226951;5895.764169433174;5910.7340169693325;5921.911171332347;5939.16851640762;6159.414855588712;6176.3514830943905;6182.443580077815;6183.690815459119;6216.7824126155365;4290.95709042918;4324.956750432581;4329.848455304049;4348.804303806273;4351.477822661301;5775.537956963517;6058.848528786815;6071.5446341523675;6076.270847498328;6089.66027473955;5917.970410147922;5948.40515948419;5949.44174005182;5950.256217972754;5957.7319020645045;5622.662640241599;5912.290525137011;5919.31927828288;5928.436798504135;5978.461938425388;5874.676892770939;5884.35272120075;5887.764489420375;5889.852753681193;5898.882022359615;5777.537796976148;5934.347221805684;5972.25346831646;5982.850428739316;5995.400459953969;5784.438201685605;5793.697592372308;5805.332386775575;5811.82564523065;5821.534277257724 19:34:24 INFO - PROCESS | 18738 | |5;yui.html;759.2938567132564;788.3456730913342;853.1511146409324;868.5743985447131;898.7663207566032;16671.666566668675;17499.12504374781;18107.728384074217;18304.63390732188;18376.540586485327;16023.599410014756;16235.26941287642;16387.83612163878;16402.17989100546;16419.261133249;864.6022829498438;864.6498168241872;870.3689824876977;872.0320444306815;872.4242000279817;887.8357503861774;888.2272422992006;888.2361169394316;888.4269646078276;888.9644414223424;909.7361765088117;914.5701520285455;916.8212198621271;917.3991035871514;917.5045475443253;910.2717825739383;910.4719262827577;910.9362344635878;913.6254135804321;916.3127654259306;665.5474277491304;666.0508774995612;666.6966530228783;667.2426795586995;667.6695035957184;15964.121973291465;16105.677886442205;16134.919325403298;16135.596610084724;16194.595135121715;14171.362288697033;14208.786868196985;14232.501862434765;14262.144271343648;14283.571492855232;12634.965536475567;12942.158759680591;13003.804942925864;13126.278054706987;13165.934170329183;4026.4966879140106;4042.696797740152;4082.9183416331803;4129;4192.140611174732;57652.42347576554;68107.31892681059;77978;79424;86225;11392.430378481044;11555.133364997491;11625.825612615818;11784.292942423483;12566;22061.448463788212;22211.777882221293;22256.666150007764;22344.329670109924;22349.21777737804;8841.381103322705;8870.911290887008;8913.866292005625;8981.910180898238;9288;4869.975650121727;4975.850724478271;4998.100341938413;5008.048470790538;5010.448850626428;3535.1869070114017;3674.889753307405;3699.648533389324;3701.4262789267705;3702.5186725725484;627.1251604012377;645.6158585641535;649.76283656466;657.5594351784316;707.426984142846;691.9792406227821;739.9741009064657;762.2529920677656;770.2605498721178;785.5914924239363;9309.627614895464;9401.905980940102;9476.620935162655;9790.265730070274;10133.543990520444;585.3911931591183;651.6220592056595;657.5430076097163;674.4199988010307;686.1628812848317;4540.954590454119;4570.223062079454;4590.104929538775;4604.40142781443;4635.466921304026;76352;77516;78011.21988780153;120694.3965280168;135893;56638.7168064157;109200.45399773109;110115;116170.41914790373;119962;93126.53436732772;95449.99999999862;96056;98518.5074074625;99864.50067749615;9676.854847177157;10135.695929122137;10223.488825558543;10281.845772313272;10400.895991040295;52234.73882630563;52831.735841320544;53111.46888531065;53316.200256996955;53947.46052539424;70562;71461.14155433932;76796;81115;81147.59426202832;12736.554220602235;12754;13137.080404371602;13170.341482926007;13456.596302110953;15187.772183417479;15329.693406132037;15529.068255904682;15858.365665373256;16334.346626134822;15005.699886002001;15170.089794612359;15343.925925185129;15408.537743867702;15701.979371340827;13524.323783810967;13993.230372329619;14672.3397447113;14781.704365912407;15177.696446071237;14670;14686.55940321792;14983.625409364853;15304;15733.44932927342;12190.817137742764;12256.877431225574;12259.816102758645;12678.492860285485;13045.152065115737;12080;12082.75834483323;12360.38198090074;12637.304948227567;12926.289054102115 19:34:24 INFO - PROCESS | 18738 | __end_tp_report 19:34:24 INFO - PROCESS | 18738 | __start_cc_report 19:34:24 INFO - PROCESS | 18738 | _x_x_mozilla_cycle_collect,1033 19:34:24 INFO - PROCESS | 18738 | __end_cc_report 19:34:24 INFO - PROCESS | 18738 | __startTimestamp1474598064236__endTimestamp 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | ------- Summary: start ------- 19:34:24 INFO - PROCESS | 18738 | Number of tests: 6 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#0] dojo.html Cycles:165 Average:5621.32 Median:5870.32 stddev:655.82 (11.2%) stddev-sans-first:630.40 19:34:24 INFO - PROCESS | 18738 | Values: 3228.6 3244.2 3255.7 3259.3 3296.7 5606.7 5693.7 5693.9 5698.7 5734.8 5684.5 5743.7 5744.6 5750.4 5751.1 4968.4 5029.2 5176.5 5180.0 5235.6 5123.6 5172.9 5175.4 5176.7 5185.1 5046.7 5089.6 5098.9 5117.4 5131.0 5194.7 5208.1 5209.0 5210.0 5230.4 4924.7 4995.8 5073.9 5112.1 5115.1 5527.5 5540.2 5543.5 5556.2 5564.2 5987.6 5999.8 6005.4 6009.7 6014.4 5959.3 6039.5 6048.4 6048.5 6118.5 5575.7 5627.8 5635.1 5664.2 5719.8 5847.3 6146.6 6212.9 6216.9 6231.8 5902.4 5981.9 5986.9 6068.0 6073.6 6131.8 6147.0 6149.5 6151.8 6153.6 5769.3 6053.8 6056.7 6107.1 6113.3 5869.2 5919.3 5948.4 5999.2 6035.3 4588.5 4598.7 4605.7 4606.3 4634.6 3949.4 4075.0 4097.4 4115.5 4127.3 5596.0 5748.2 5759.9 5818.3 5855.9 6089.7 6121.0 6126.8 6128.4 6137.3 5503.7 5785.6 5793.9 5795.7 5805.9 5058.0 5114.6 5121.9 5125.1 5127.0 6278.2 6284.1 6290.1 6297.9 6298.6 5864.7 6066.8 6073.2 6086.8 6092.9 6170.8 6229.8 6272.4 6331.8 6363.8 5966.4 6011.1 6024.7 6031.3 6067.5 5871.9 6147.8 6148.7 6157.2 6158.8 5817.8 5870.3 5892.0 5939.9 5989.2 5912.7 5923.1 5927.8 5930.1 5930.5 5695.0 5839.1 5875.8 5887.1 5889.6 5924.0 5980.3 5988.5 6055.1 6060.7 5909.1 5912.9 5913.4 5913.7 5914.9 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#1] ext.html Cycles:180 Average:64758.58 Median:374553.50 stddev:137650.87 (36.8%) stddev-sans-first:137952.65 19:34:24 INFO - PROCESS | 18738 | Values: 571.2 583.3 622.4 622.9 651.6 34771.5 46012.5 46866.0 46901.0 46910.0 49915.0 50368.7 50454.2 50497.5 50534.0 8582.2 9225.2 9390.4 9392.5 10867.6 10780.4 11223.4 11229.3 11235.2 11237.3 11175.2 11241.5 11252.5 11285.9 11288.5 11092.9 11162.4 11245.9 11249.8 11278.6 10874.5 10922.7 10941.7 10951.8 10962.7 46375.8 46875.0 46947.8 46949.3 46987.0 3618.6 3769.0 4295.8 4371.2 4388.7 34965.7 35251.0 35313.3 35412.0 35598.1 4767.2 4810.6 4812.1 4851.2 4888.8 374201.0 374906.0 376924.0 377520.0 379734.0 35712.5 65238.7 65527.3 65658.3 65663.3 40110.6 40149.8 40161.0 40173.8 40182.4 17144.3 17369.7 17379.2 17380.9 17386.0 13526.7 14436.6 14594.3 14687.3 14727.6 9321.8 9399.0 9440.1 9446.2 9477.0 3537.8 3574.4 3587.9 3594.1 3677.7 1024.2 1106.2 1116.5 1125.4 1165.1 35557.6 36054.5 36077.3 36335.5 36428.6 1144.9 1146.2 1163.2 1176.8 1224.8 17784.4 18670.3 18774.4 18861.5 18985.7 598040.0 612232.0 621608.0 625004.0 1038668.0 68463.0 69072.0 69121.3 69183.3 69214.7 1340.7 1427.1 1481.5 1486.9 1496.1 32290.2 35933.6 36255.0 36384.0 36562.5 55687.2 56591.0 56614.7 56649.4 56749.0 362147.0 363226.0 363324.0 363729.0 364862.0 27933.2 28192.9 28207.7 30891.0 30951.2 42864.6 44762.6 52474.7 53663.2 57773.7 44676.0 46645.5 47026.0 47945.0 48263.1 44396.6 45221.1 45446.3 45997.8 46950.0 44964.7 45039.0 45301.1 45393.1 45768.5 37872.4 40352.8 40616.8 40870.4 41457.2 29664.9 30280.8 30485.0 30516.5 30741.7 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#2] jquery.html Cycles:180 Average:31257.84 Median:2300.42 stddev:92926.06 (4039.5%) stddev-sans-first:93157.86 19:34:24 INFO - PROCESS | 18738 | Values: 401.1 408.2 414.4 421.2 429.3 18978.3 20252.3 20708.6 21058.0 21076.8 19000.7 19353.7 19577.2 20304.4 20502.8 5946.6 6155.5 6157.9 6159.4 6173.7 5622.6 5822.2 5842.8 6101.8 6160.9 5866.7 5916.5 5920.8 5933.1 5950.5 5906.8 6010.7 6158.5 6159.4 6162.6 5926.4 6098.5 6107.5 6148.9 6155.0 14639.6 14778.9 14799.8 20386.5 24590.9 2436.4 2476.8 2499.8 2613.3 2980.6 10453.7 10630.9 10950.9 11767.6 15470.8 2028.9 2046.1 2117.9 2128.4 2164.4 62161.0 66960.0 80628.8 81875.0 82702.2 11156.6 11192.3 11302.3 11333.2 11492.2 15308.8 15423.9 15559.2 15719.7 15768.4 8647.5 8720.3 8740.1 8876.7 9113.1 8732.1 8741.5 8742.3 8844.9 8975.5 4809.9 4830.9 4853.2 4894.0 4904.6 2034.8 2050.5 2069.6 2080.1 2129.8 977.2 998.3 1027.5 1031.5 1062.1 20139.9 20589.2 21111.2 21193.6 21217.4 1004.8 1010.1 1054.4 1056.7 1072.2 10775.3 11691.3 11740.4 11872.6 11941.8 522879.0 553649.0 571839.0 588341.0 590953.0 74192.0 87816.6 92284.5 99445.0 100311.5 980.4 1025.3 1028.8 1049.6 1050.7 16685.3 18780.2 18987.0 19283.4 19402.6 41907.8 43761.6 43790.3 44159.1 46557.8 64013.7 64030.7 64069.7 64445.0 64548.7 8554.1 8556.2 8730.7 8823.6 8956.8 9700.8 12929.0 13010.2 13351.9 13424.3 11004.4 11179.9 11257.7 11285.3 11386.4 11612.4 11641.6 11744.5 11797.2 12139.7 10897.8 11042.2 11092.3 11237.3 11491.9 8482.3 8504.7 8514.7 8826.5 9007.2 10665.4 11130.5 11144.8 11293.3 11368.8 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#3] mootools.html Cycles:180 Average:5962.73 Median:4530.85 stddev:3402.67 (75.1%) stddev-sans-first:3395.67 19:34:24 INFO - PROCESS | 18738 | Values: 1497.7 1519.5 1679.1 1821.9 1823.7 8639.3 9291.1 9292.9 9294.4 9310.6 7348.7 8580.7 8918.2 8965.0 9139.0 2336.4 2363.2 2378.1 2378.8 2383.0 2349.7 2378.4 2386.3 2393.8 2394.1 2397.5 2407.9 2421.8 2469.0 2469.3 2323.5 2340.1 2341.3 2344.1 2348.2 2338.8 2346.4 2354.1 2358.0 2359.7 9146.4 9197.0 9270.7 9289.5 9295.6 5073.6 5176.3 5183.1 5198.5 5203.6 3328.8 3331.1 3381.9 3383.7 3399.9 1152.2 1157.9 1163.8 1198.0 1206.7 12640.9 12658.7 12677.5 12682.4 12700.8 4331.7 4491.1 4570.6 4662.3 4673.3 10591.9 10602.8 10609.4 10614.7 10621.9 5290.3 5334.7 5337.8 5339.7 5341.4 4111.0 4753.4 4923.5 4928.0 4965.2 4082.1 4123.3 4171.1 4177.0 4181.6 1308.2 1314.9 1315.6 1327.2 1331.5 955.0 962.6 1018.2 1021.2 1045.5 6051.4 6135.9 6186.6 6227.7 6309.7 470.7 473.8 482.6 483.7 485.7 4206.7 4224.0 4269.7 4291.4 4295.2 10410.6 10874.5 10888.3 10896.6 10902.9 10528.9 10791.2 10800.6 10805.3 10813.8 8042.6 8390.9 8406.5 8408.6 8424.1 6277.7 6288.2 6440.7 6582.4 6749.4 10436.8 10463.5 10536.4 10592.9 10662.1 10406.9 10526.9 10720.8 10805.4 10810.0 7100.8 7765.8 7973.0 7974.1 8208.6 9048.1 9114.7 9142.7 9144.2 9159.3 8076.5 8082.3 8163.1 8287.2 8322.1 7904.4 7925.3 8028.4 8070.0 8117.8 7170.7 7183.3 7293.9 7316.2 7322.9 5508.2 5522.6 5551.6 5588.7 5644.0 5571.3 5580.7 5656.9 5717.3 5762.3 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#4] prototype.html Cycles:180 Average:4878.47 Median:5559.15 stddev:1434.72 (25.8%) stddev-sans-first:1397.84 19:34:24 INFO - PROCESS | 18738 | Values: 347.0 351.7 352.1 352.3 353.6 5535.8 5606.2 5611.2 5612.2 5671.6 5582.5 5587.7 5589.9 5591.9 5603.6 3931.3 4140.7 4199.7 4331.4 4345.7 4314.9 4315.4 4317.6 4318.2 4346.9 4296.8 4322.3 4335.4 4340.3 4350.6 4069.9 4274.0 4343.5 4343.7 4349.8 3764.7 3767.6 3780.4 3780.8 3792.9 5356.8 5364.2 5369.9 5384.4 5402.4 4856.2 4940.4 4955.5 4969.3 4994.7 5618.1 5699.5 5717.7 5725.2 5764.7 4158.8 4166.6 4172.3 4173.2 4190.1 5874.8 5961.3 6009.2 6010.2 6034.7 4312.1 4312.2 4318.8 4318.9 4322.3 4346.0 4364.8 4365.1 4372.2 4378.8 4775.8 4939.6 5001.2 5021.9 5029.1 5786.1 5791.2 5806.8 5817.4 5890.8 1479.2 1493.3 1509.0 1514.0 1533.3 926.0 966.7 975.6 978.5 1001.4 4987.6 5010.3 5026.6 5069.4 5070.3 5238.6 5278.3 5290.5 5310.6 5312.2 5055.9 5065.6 5105.0 5290.5 5769.1 3216.0 3218.0 3221.6 3224.4 3243.7 6079.2 6233.8 6251.9 6259.3 6269.7 6099.4 6115.5 6125.6 6142.0 6145.9 5878.6 6020.5 6172.2 6191.5 6197.6 5965.4 5971.8 5991.2 5994.5 6001.9 5766.8 5895.8 5910.7 5921.9 5939.2 6159.4 6176.4 6182.4 6183.7 6216.8 4291.0 4325.0 4329.8 4348.8 4351.5 5775.5 6058.8 6071.5 6076.3 6089.7 5918.0 5948.4 5949.4 5950.3 5957.7 5622.7 5912.3 5919.3 5928.4 5978.5 5874.7 5884.4 5887.8 5889.9 5898.9 5777.5 5934.3 5972.3 5982.9 5995.4 5784.4 5793.7 5805.3 5811.8 5821.5 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - PROCESS | 18738 | [#5] yui.html Cycles:180 Average:21193.47 Median:4366.55 stddev:29988.50 (686.8%) stddev-sans-first:30033.37 19:34:24 INFO - PROCESS | 18738 | Values: 759.3 788.3 853.2 868.6 898.8 16671.7 17499.1 18107.7 18304.6 18376.5 16023.6 16235.3 16387.8 16402.2 16419.3 864.6 864.6 870.4 872.0 872.4 887.8 888.2 888.2 888.4 889.0 909.7 914.6 916.8 917.4 917.5 910.3 910.5 910.9 913.6 916.3 665.5 666.1 666.7 667.2 667.7 15964.1 16105.7 16134.9 16135.6 16194.6 14171.4 14208.8 14232.5 14262.1 14283.6 12635.0 12942.2 13003.8 13126.3 13165.9 4026.5 4042.7 4082.9 4129.0 4192.1 57652.4 68107.3 77978.0 79424.0 86225.0 11392.4 11555.1 11625.8 11784.3 12566.0 22061.4 22211.8 22256.7 22344.3 22349.2 8841.4 8870.9 8913.9 8981.9 9288.0 4870.0 4975.9 4998.1 5008.0 5010.4 3535.2 3674.9 3699.6 3701.4 3702.5 627.1 645.6 649.8 657.6 707.4 692.0 740.0 762.3 770.3 785.6 9309.6 9401.9 9476.6 9790.3 10133.5 585.4 651.6 657.5 674.4 686.2 4541.0 4570.2 4590.1 4604.4 4635.5 76352.0 77516.0 78011.2 120694.4 135893.0 56638.7 109200.5 110115.0 116170.4 119962.0 93126.5 95450.0 96056.0 98518.5 99864.5 9676.9 10135.7 10223.5 10281.8 10400.9 52234.7 52831.7 53111.5 53316.2 53947.5 70562.0 71461.1 76796.0 81115.0 81147.6 12736.6 12754.0 13137.1 13170.3 13456.6 15187.8 15329.7 15529.1 15858.4 16334.3 15005.7 15170.1 15343.9 15408.5 15702.0 13524.3 13993.2 14672.3 14781.7 15177.7 14670.0 14686.6 14983.6 15304.0 15733.4 12190.8 12256.9 12259.8 12678.5 13045.2 12080.0 12082.8 12360.4 12637.3 12926.3 19:34:24 INFO - PROCESS | 18738 | -------- Summary: end -------- 19:34:24 INFO - PROCESS | 18738 | 19:34:24 INFO - TEST-INFO | 18738: exit 0 19:34:24 INFO - TEST-OK | dromaeo_css | took 1113341ms 19:34:24 INFO - TEST-START | kraken 19:34:24 INFO - operating with platform_type : linux_ 19:34:24 INFO - Initialising browser for kraken test... 19:34:24 INFO - TEST-INFO | started process 18939 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeWslX7/profile http://localhost:44216/getInfo.html) 19:34:29 INFO - PROCESS | 18939 | __metrics Screen width/height:1600/1200 19:34:29 INFO - PROCESS | 18939 | colorDepth:24 19:34:29 INFO - PROCESS | 18939 | Browser inner width/height: 1024/697 19:34:29 INFO - PROCESS | 18939 | __metrics 19:34:30 INFO - PROCESS | 18939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:34:30 INFO - PROCESS | 18939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:30 INFO - PROCESS | 18939 | console.error: PushService: 19:34:30 INFO - PROCESS | 18939 | stateChangeProcessEnqueue: Error transitioning state 19:34:30 INFO - PROCESS | 18939 | UnknownError 19:34:31 INFO - TEST-INFO | 18939: exit 0 19:34:31 INFO - Browser initialized. 19:34:31 INFO - Running cycle 1/1 for kraken test... 19:34:31 INFO - TEST-INFO | started process 19025 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeWslX7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:42 INFO - PROCESS | 19025 | RSS: Main: 143581184 19:34:42 INFO - PROCESS | 19025 | 19:34:45 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44216/tests/kraken/driver.html?testName=audio-beat-detection) 19:34:46 INFO - PROCESS | 19025 | RSS: Main: 198864896 19:34:46 INFO - PROCESS | 19025 | 19:34:48 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44216/tests/kraken/driver.html?testName=audio-dft) 19:34:49 INFO - PROCESS | 19025 | RSS: Main: 183218176 19:34:49 INFO - PROCESS | 19025 | 19:34:52 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44216/tests/kraken/driver.html?testName=audio-fft) 19:34:52 INFO - PROCESS | 19025 | RSS: Main: 274108416 19:34:52 INFO - PROCESS | 19025 | 19:34:54 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44216/tests/kraken/driver.html?testName=audio-oscillator) 19:34:55 INFO - PROCESS | 19025 | RSS: Main: 179691520 19:34:55 INFO - PROCESS | 19025 | 19:34:57 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44216/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:58 INFO - PROCESS | 19025 | RSS: Main: 178716672 19:34:58 INFO - PROCESS | 19025 | 19:35:01 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44216/tests/kraken/driver.html?testName=imaging-darkroom) 19:35:01 INFO - PROCESS | 19025 | RSS: Main: 215187456 19:35:01 INFO - PROCESS | 19025 | 19:35:05 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44216/tests/kraken/driver.html?testName=imaging-desaturate) 19:35:05 INFO - PROCESS | 19025 | RSS: Main: 213499904 19:35:05 INFO - PROCESS | 19025 | 19:35:08 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44216/tests/kraken/driver.html?testName=json-parse-financial) 19:35:09 INFO - PROCESS | 19025 | RSS: Main: 213307392 19:35:09 INFO - PROCESS | 19025 | 19:35:10 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44216/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:35:11 INFO - PROCESS | 19025 | RSS: Main: 175403008 19:35:11 INFO - PROCESS | 19025 | 19:35:12 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:35:13 INFO - PROCESS | 19025 | RSS: Main: 183185408 19:35:13 INFO - PROCESS | 19025 | 19:35:15 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:35:16 INFO - PROCESS | 19025 | RSS: Main: 183861248 19:35:16 INFO - PROCESS | 19025 | 19:35:18 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:35:19 INFO - PROCESS | 19025 | RSS: Main: 185577472 19:35:19 INFO - PROCESS | 19025 | 19:35:21 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:35:22 INFO - PROCESS | 19025 | RSS: Main: 183050240 19:35:22 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | Cycle 1(1): loaded http://localhost:44216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44216/tests/kraken/driver.html?testName=ai-astar) 19:35:24 INFO - PROCESS | 19025 | RSS: Main: 234582016 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | __start_tp_report 19:35:24 INFO - PROCESS | 19025 | _x_x_mozilla_page_load 19:35:24 INFO - PROCESS | 19025 | _x_x_mozilla_page_load_details 19:35:24 INFO - PROCESS | 19025 | |i|pagename|runs| 19:35:24 INFO - PROCESS | 19025 | |0;ai-astar;112;112;117;109;109;110;109;111;110;111 19:35:24 INFO - PROCESS | 19025 | |1;audio-beat-detection;151;115;147;170;116;145;144;118;167;116 19:35:24 INFO - PROCESS | 19025 | |2;audio-dft;162;165;161;160;160;160;159;162;162;160 19:35:24 INFO - PROCESS | 19025 | |3;audio-fft;75;76;76;181;78;74;74;74;166;76 19:35:24 INFO - PROCESS | 19025 | |4;audio-oscillator;83;83;161;84;84;84;149;83;84;153 19:35:24 INFO - PROCESS | 19025 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;117;117;117 19:35:24 INFO - PROCESS | 19025 | |6;imaging-darkroom;145;146;145;146;147;147;147;147;146;146 19:35:24 INFO - PROCESS | 19025 | |7;imaging-desaturate;95;94;95;95;95;95;95;94;95;95 19:35:24 INFO - PROCESS | 19025 | |8;json-parse-financial;49;51;58;50;52;50;49;50;50;50 19:35:24 INFO - PROCESS | 19025 | |9;json-stringify-tinderbox;59;60;60;61;60;61;60;60;60;60 19:35:24 INFO - PROCESS | 19025 | |10;stanford-crypto-aes;74;73;73;72;82;73;78;73;73;72 19:35:24 INFO - PROCESS | 19025 | |11;stanford-crypto-ccm;120;125;128;124;119;118;116;116;157;125 19:35:24 INFO - PROCESS | 19025 | |12;stanford-crypto-pbkdf2;153;156;152;154;154;153;149;163;157;148 19:35:24 INFO - PROCESS | 19025 | |13;stanford-crypto-sha256-iterative;64;62;61;62;62;65;70;61;62;61 19:35:24 INFO - PROCESS | 19025 | __end_tp_report 19:35:24 INFO - PROCESS | 19025 | __start_cc_report 19:35:24 INFO - PROCESS | 19025 | _x_x_mozilla_cycle_collect,1664 19:35:24 INFO - PROCESS | 19025 | __end_cc_report 19:35:24 INFO - PROCESS | 19025 | __startTimestamp1474598124348__endTimestamp 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | ------- Summary: start ------- 19:35:24 INFO - PROCESS | 19025 | Number of tests: 14 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:2.40 (2.2%) stddev-sans-first:2.52 19:35:24 INFO - PROCESS | 19025 | Values: 112.0 112.0 117.0 109.0 109.0 110.0 109.0 111.0 110.0 111.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:146.00 stddev:21.31 (14.6%) stddev-sans-first:22.15 19:35:24 INFO - PROCESS | 19025 | Values: 151.0 115.0 147.0 170.0 116.0 145.0 144.0 118.0 167.0 116.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.73 (1.1%) stddev-sans-first:1.80 19:35:24 INFO - PROCESS | 19025 | Values: 162.0 165.0 161.0 160.0 160.0 160.0 159.0 162.0 162.0 160.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.50 stddev:41.54 (55.0%) stddev-sans-first:43.43 19:35:24 INFO - PROCESS | 19025 | Values: 75.0 76.0 76.0 181.0 78.0 74.0 74.0 74.0 166.0 76.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:83.50 stddev:34.31 (41.1%) stddev-sans-first:35.47 19:35:24 INFO - PROCESS | 19025 | Values: 83.0 83.0 161.0 84.0 84.0 84.0 149.0 83.0 84.0 153.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:35:24 INFO - PROCESS | 19025 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.50 stddev:0.79 (0.5%) stddev-sans-first:0.71 19:35:24 INFO - PROCESS | 19025 | Values: 145.0 146.0 145.0 146.0 147.0 147.0 147.0 147.0 146.0 146.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:35:24 INFO - PROCESS | 19025 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:50.00 stddev:2.64 (5.3%) stddev-sans-first:2.71 19:35:24 INFO - PROCESS | 19025 | Values: 49.0 51.0 58.0 50.0 52.0 50.0 49.0 50.0 50.0 50.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 19:35:24 INFO - PROCESS | 19025 | Values: 59.0 60.0 60.0 61.0 60.0 61.0 60.0 60.0 60.0 60.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.00 stddev:3.20 (4.4%) stddev-sans-first:3.39 19:35:24 INFO - PROCESS | 19025 | Values: 74.0 73.0 73.0 72.0 82.0 73.0 78.0 73.0 73.0 72.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:124.50 stddev:12.04 (9.7%) stddev-sans-first:12.65 19:35:24 INFO - PROCESS | 19025 | Values: 120.0 125.0 128.0 124.0 119.0 118.0 116.0 116.0 157.0 125.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:154.00 stddev:4.23 (2.7%) stddev-sans-first:4.47 19:35:24 INFO - PROCESS | 19025 | Values: 153.0 156.0 152.0 154.0 154.0 153.0 149.0 163.0 157.0 148.0 19:35:24 INFO - PROCESS | 19025 | 19:35:24 INFO - PROCESS | 19025 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:62.00 stddev:2.79 (4.5%) stddev-sans-first:2.93 19:35:24 INFO - PROCESS | 19025 | Values: 64.0 62.0 61.0 62.0 62.0 65.0 70.0 61.0 62.0 61.0 19:35:24 INFO - PROCESS | 19025 | -------- Summary: end -------- 19:35:24 INFO - PROCESS | 19025 | 19:35:25 INFO - TEST-INFO | 19025: exit 0 19:35:25 INFO - TEST-OK | kraken | took 60371ms 19:35:25 INFO - SUITE-END | took 1173s 19:35:25 INFO - Completed test suite (00:19:33) 19:35:25 INFO - javascript benchmark 19:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3228.5802845630064, 3244.172735952331, 3255.690709382608, 3259.3155437358155, 3296.6538513456085, 5606.663600183987, 5693.686847223401, 5693.886122277552, 5698.715064246791, 5734.770609175628, 5684.459976302255, 5743.712814359275, 5744.569157313197, 5750.3962083981205, 5751.079827227644, 4968.378952630921, 5029.195328747401, 5176.5341119299255, 5180.04169228692, 5235.607329450288, 5123.5901127909865, 5172.896542069157, 5175.353080864892, 5176.715280659581, 5185.144451165553, 5046.697198168104, 5089.567386772111, 5098.878246785701, 5117.360329958755, 5130.974345128269, 5194.662346947436, 5208.140656791625, 5208.9739551302, 5210.0, 5230.398504172012, 4924.704517728947, 4995.750212489397, 5073.8731531712, 5112.130937740593, 5115.079285728568, 5527.53015993641, 5540.168974653794, 5543.501084902378, 5556.194351818964, 5564.165375193713, 5987.580869339148, 5999.790007349722, 6005.399460054004, 6009.729562169669, 6014.3985601439945, 5959.28488581373, 6039.516838652898, 6048.39516048396, 6048.455638992512, 6118.479929205978, 5575.665460072368, 5627.774889004476, 5635.070213414781, 5664.235328230719, 5719.771209151629, 5847.298324201123, 6146.6004709693725, 6212.875742485125, 6216.943119669577, 6231.781887633911, 5902.439268269508, 5981.850453738692, 5986.880262394728, 6067.993770803335, 6073.574849760563, 6131.84670383244, 6146.969265153645, 6149.538784591174, 6151.846203844851, 6153.600015998945, 5769.307683078057, 6053.818385448356, 6056.697165141725, 6107.083937409336, 6113.32753397126, 5869.178315035899, 5919.260092488439, 5948.375420580864, 5999.160117583542, 6035.336113027563, 4588.49526552079, 4598.724076555417, 4605.723656580549, 4606.332081848181, 4634.58288754007, 3949.3681011038097, 4074.959250407517, 4097.405876147942, 4115.526714427879, 4127.33962565994, 5596.048671725816, 5748.223989761413, 5759.942400576025, 5818.272715910512, 5855.912161317584, 6089.695515224221, 6121.0, 6126.785562505291, 6128.356522565066, 6137.294211165684, 5503.669779813144, 5785.595008349459, 5793.942060579425, 5795.739191736382, 5805.94194058054, 5057.974710126499, 5114.616403769658, 5121.8719532012, 5125.077486052472, 5127.025865085622, 6278.183836101278, 6284.057391391328, 6290.056491526308, 6297.937020629826, 6298.590591611531, 5864.736086876015, 6066.848328791639, 6073.240844894388, 6086.847828804315, 6092.939070609237, 6170.845728856814, 6229.781957631642, 6272.435480806566, 6331.810045698637, 6363.809085727436, 5966.373530779206, 6011.068284415923, 6024.728887199999, 6031.336552979256, 6067.453929146398, 5871.882562348815, 6147.754089836357, 6148.661823599745, 6157.168782214344, 6158.753649853956, 5817.76728930838, 5870.324912634931, 5892.0, 5939.851503712442, 5989.221401217988, 5912.70436478183, 5923.111533270044, 5927.762889484545, 5930.140129681258, 5930.525557955267, 5694.971525142348, 5839.124131380327, 5875.794347197828, 5887.0580707086665, 5889.64662120257, 5924.022536281465, 5980.252468441445, 5988.520918326436, 6055.091736239599, 6060.727267272895, 5909.054551271776, 5912.852178695567, 5913.379095195115, 5913.733881975236, 5914.940850591439], "value": 5575.937773260859, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.225988785196, 583.314605338727, 622.4211483320513, 622.8816524860276, 651.6285717141226, 34771.478427823604, 46012.53987460124, 46866.0, 46900.999999999956, 46910.0, 49915.0, 50368.748156259164, 50454.243186352236, 50497.495025049735, 50534.0, 8582.184692454206, 9225.215856652176, 9390.389624674408, 9392.530373481333, 10867.619633312836, 10780.40707761073, 11223.438828058604, 11229.32624042556, 11235.21353505255, 11237.269577477478, 11175.21773475853, 11241.494132764044, 11252.54989800411, 11285.943570282177, 11288.548458061668, 11092.889071109306, 11162.441877906112, 11245.943770281096, 11249.775004499865, 11278.605248816335, 10874.45627718611, 10922.672319830417, 10941.726456838644, 10951.780964380747, 10962.725931851688, 46375.76812115952, 46875.0, 46947.765261173816, 46949.29576056362, 46987.0, 3618.565772107364, 3768.962310376916, 4295.8496452624, 4371.23503386906, 4388.670849686255, 34965.650343496745, 35251.0, 35313.293734125175, 35412.0, 35598.11004724877, 4767.165745994437, 4810.615150787964, 4812.109759694433, 4851.199552073904, 4888.755562221874, 374201.0, 374906.0, 376924.0, 377519.99999999726, 379734.0, 35712.46431303533, 65238.673806630664, 65527.34472655308, 65658.34341656617, 65663.34336656572, 40110.59889401068, 40149.799251003555, 40161.0, 40173.799131004154, 40182.397264041065, 17144.314227430765, 17369.652606948042, 17379.217935192948, 17380.913095434695, 17386.0, 13526.661833454242, 14436.566902992927, 14594.270286485846, 14687.339069741887, 14727.55817325482, 9321.766955826159, 9398.953005235067, 9440.10319019692, 9446.244300455946, 9477.0, 3537.7523573349617, 3574.428091505347, 3587.94618080729, 3594.137407022296, 3677.669009789132, 1024.2011231239644, 1106.2201148190668, 1116.542217690743, 1125.4204084896285, 1165.0795871261778, 35557.64442355543, 36054.459183112274, 36077.27845443129, 36335.4549681755, 36428.63571364305, 1144.9141314401454, 1146.2492067695673, 1163.1857699610307, 1176.805827038529, 1224.7550489902055, 17784.3775467858, 18670.346537871108, 18774.436766897015, 18861.52846178829, 18985.715214271797, 598040.0, 612232.0, 621608.0, 625004.0, 1038668.0, 68463.0, 69072.0, 69121.30878691249, 69183.3081669187, 69214.65392673004, 1340.6983428728458, 1427.0581416265215, 1481.4740767027765, 1486.8810495160362, 1496.0948626081065, 32290.192745181557, 35933.64066359407, 36255.0, 36384.0, 36562.45156322711, 55687.16469252884, 56591.0, 56614.7169264151, 56649.43350566441, 56749.0, 362147.0, 363226.0, 363324.0, 363729.0, 364862.0, 27933.16200513988, 28192.85903570469, 28207.71792282051, 30891.0, 30951.226219344695, 42864.571354287305, 44762.55237447584, 52474.73762631315, 53663.19505207347, 57773.71113144408, 44676.0, 46645.53354466548, 47026.0, 47945.0, 48263.09582456331, 44396.55603444053, 45221.095578087596, 45446.31830522611, 45997.77001114973, 46950.0, 44964.738226152906, 45039.0, 45301.09397812091, 45393.09213815771, 45768.54231457643, 37872.43191352077, 40352.805956724274, 40616.79691601523, 40870.386944196456, 41457.170856582095, 29664.851675741484, 30280.84859575688, 30485.0, 30516.542251866686, 30741.692583073884], "value": 20974.10628716663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.12954887893267, 408.17955908623657, 414.40325930659856, 421.24807219113876, 429.3002406078092, 18978.335758248464, 20252.291169809043, 20708.585828283463, 21058.0, 21076.789232107676, 19000.714989275173, 19353.709694354562, 19577.21691132353, 20304.39086827394, 20502.794972050313, 5946.554008449362, 6155.476784473302, 6157.90763138551, 6159.38406159385, 6173.660448675343, 5622.634528755638, 5822.214001109859, 5842.766289348442, 6101.78643747469, 6160.938390616104, 5866.706664666771, 5916.4970977466755, 5920.792772252972, 5933.13969474426, 5950.494207992304, 5906.793262235823, 6010.699465026753, 6158.476529494998, 6159.4456498915315, 6162.568620196588, 5926.436988486087, 6098.451139397475, 6107.511399088063, 6148.907766383508, 6155.0, 14639.63400914975, 14778.926105369512, 14799.778003329959, 20386.49033774153, 24590.877045614838, 2436.439618887648, 2476.826622136451, 2499.787518060967, 2613.3074735195, 2980.5976193214074, 10453.738656533647, 10630.946845265724, 10950.890491095146, 11767.646970590897, 15470.845291547053, 2028.8579799414047, 2046.0894901768718, 2117.862338947979, 2128.3508529898377, 2164.404788683109, 62161.0, 66960.0, 80628.79056814153, 81875.0, 82702.17297827004, 11156.609518666808, 11192.272502287326, 11302.321860688386, 11333.150013749002, 11492.195546311677, 15308.846911530965, 15423.922880385526, 15559.222038898237, 15719.68560628781, 15768.369265229263, 8647.481151130953, 8720.258778003941, 8740.082291359317, 8876.733697989072, 9113.134252246025, 8732.126787321216, 8741.475511469205, 8742.256908162877, 8844.867326990101, 8975.50634715097, 4809.927851082237, 4830.879228019257, 4853.247746599282, 4894.021195760862, 4904.583110435581, 2034.8372130229545, 2050.507878109243, 2069.5860827834194, 2080.0951586060114, 2129.8189653879285, 977.2231076294363, 998.32612986234, 1027.4965267019106, 1031.4945676618404, 1062.0706881478707, 20139.899300503403, 20589.176432942513, 21111.155553777677, 21193.576128477343, 21217.363479095653, 1004.7538353103537, 1010.1010101010107, 1054.3779170289565, 1056.6513050693252, 1072.222638587018, 10775.299605525615, 11691.298522088702, 11740.412979350998, 11872.643820685393, 11941.820872686916, 522879.0, 553649.0, 571839.0, 588341.0, 590953.0, 74192.0, 87816.560917195, 92284.53857730802, 99445.0, 100311.49844250732, 980.4215512847454, 1025.29254814178, 1028.804527139841, 1049.58541376156, 1050.7320633238476, 16685.332586696397, 18780.248790048245, 18987.0, 19283.421497355102, 19402.61194776125, 41907.7904610475, 43761.56238437575, 43790.34314485222, 44159.11681766283, 46557.76721116508, 64013.67993160005, 64030.67984660047, 64069.679651601444, 64445.0, 64548.677256615294, 8554.144585541397, 8556.18716221958, 8730.738077857675, 8823.647054117764, 8956.776080598038, 9700.805983880424, 12929.030322726021, 13010.21938683682, 13351.866481335452, 13424.261665608095, 11004.39475828837, 11179.944100279447, 11257.71855703614, 11285.322880627189, 11386.430678465878, 11612.361320127475, 11641.59254426091, 11744.530218791153, 11797.17419780607, 12139.696507587381, 10897.782044359226, 11042.227044107156, 11092.33445993243, 11237.269577477438, 11491.942540287246, 8482.321414286966, 8504.702335418231, 8514.659413623633, 8826.514541700268, 9007.234385077341, 10665.36007839532, 11130.499127539442, 11144.832827507757, 11293.322400655987, 11368.829467557827], "value": 9526.498992053144, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.7154340675274, 1519.483375652278, 1679.1352453486456, 1821.8724689271755, 1823.717323814809, 8639.308855291578, 9291.117343852324, 9292.907070929288, 9294.442333459989, 9310.581023853925, 7348.706051757937, 8580.742577722678, 8918.197362237397, 8964.955175224115, 9138.954305228464, 2336.4392545789015, 2363.161077817376, 2378.120095564635, 2378.7978021868075, 2382.95234095318, 2349.694539709835, 2378.357843382286, 2386.2721869829693, 2393.8444001139956, 2394.126143957452, 2397.4725560376687, 2407.9036838526526, 2421.7820396164343, 2468.950620987579, 2469.259222233332, 2323.488832456857, 2340.0990618611786, 2341.309313752439, 2344.097522453859, 2348.213348528245, 2338.8128949683983, 2346.4133966508375, 2354.1289722802503, 2357.9882100589557, 2359.728631207399, 9146.359754817166, 9197.0, 9270.72187834366, 9289.53552322388, 9295.628174873065, 5073.594112470993, 5176.275321454999, 5183.144781111112, 5198.454162312978, 5203.557697595671, 3328.8169150696704, 3331.0839519132433, 3381.881634142818, 3383.678550537695, 3399.861046549409, 1152.2337645465755, 1157.8668453127914, 1163.7846998305256, 1197.9520819167224, 1206.7465832175253, 12640.936795315965, 12658.683532911658, 12677.492900283978, 12682.429290681941, 12700.809487857598, 4331.740095594275, 4491.124230775033, 4570.565796249351, 4662.323963025342, 4673.322368256584, 10591.947040264904, 10602.787944241072, 10609.416482093558, 10614.734631634117, 10621.893781062245, 5290.285811415487, 5334.67991920486, 5337.839864804062, 5339.7063161526485, 5341.359036915517, 4110.979445102756, 4753.429588449409, 4923.507649235119, 4927.975360123248, 4965.205567109318, 4082.1283024000027, 4123.257813593522, 4171.0615111599645, 4176.979115104405, 4181.60274773896, 1308.2281453942219, 1314.907956443059, 1315.6447759105179, 1327.230206480239, 1331.4940322677514, 955.0031388560728, 962.5860879821744, 1018.180364806338, 1021.1881554164308, 1045.513836066233, 6051.3828667228445, 6135.877282454326, 6186.597871138361, 6227.688615569204, 6309.684515774285, 470.70110479845545, 473.7820602522809, 482.60667555941797, 483.6735203737463, 485.6673178872413, 4206.747595144301, 4224.007358270804, 4269.743815371087, 4291.442112525414, 4295.226859165317, 10410.583576656849, 10874.45627718611, 10888.29226100301, 10896.61861834832, 10902.890971090188, 10528.894711052792, 10791.190660700322, 10800.621978230723, 10805.297655652357, 10813.83779243312, 8042.597870106471, 8390.874136887951, 8406.453580517247, 8408.621612027597, 8424.073351931283, 6277.74889004453, 6288.213973253343, 6440.742370305135, 6582.440492558187, 6749.392554670103, 10436.791264174522, 10463.529141188512, 10536.367817930948, 10592.947035265082, 10662.093722033715, 10406.947965260126, 10526.894731052591, 10720.839187412352, 10805.405702686428, 10810.0, 7100.7869763907165, 7765.844683106419, 7973.0, 7974.122846486998, 8208.58957052133, 9048.095190480899, 9114.726558203265, 9142.680006199751, 9144.222741067084, 9159.267258619428, 8076.515409075474, 8082.2725954664365, 8163.061247956561, 8287.171282871665, 8322.08457069734, 7904.446688731734, 7925.28672419485, 8028.397870159644, 8070.0, 8117.756467305991, 7170.677319520739, 7183.281671832984, 7293.927060729325, 7316.158641756266, 7322.926770732438, 5508.20131080999, 5522.6134170609275, 5551.639143455663, 5588.720563971867, 5644.012297847942, 5571.303587051619, 5580.693061881636, 5656.915146272727, 5717.342505611741, 5762.30852297727], "value": 4693.9967801739795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9531613232214, 351.729168540224, 352.10389558573434, 352.30891719745216, 353.64458718987413, 5535.806246781363, 5606.159076138581, 5611.158326251064, 5612.232146873732, 5671.602987790856, 5582.49757521823, 5587.664740115596, 5589.944100558993, 5591.944080559193, 5603.562450334098, 3931.2727145478034, 4140.689448291375, 4199.748015119103, 4331.371951067109, 4345.674074444413, 4314.935275970863, 4315.4389929309145, 4317.633001194902, 4318.201132790444, 4346.8913277168, 4296.8281268749215, 4322.308430651096, 4335.393044973706, 4340.305551111823, 4350.6301964332915, 4069.9389509157386, 4273.978630106861, 4343.500497442803, 4343.739375637456, 4349.760763158025, 3764.6800021997888, 3767.6044015378266, 3780.4140358244517, 3780.8487660493547, 3792.867249646277, 5356.812511562116, 5364.222187782789, 5369.892602147936, 5384.380796208447, 5402.432744561804, 4856.15017371962, 4940.382452193476, 4955.488575984324, 4969.329140566014, 4994.675346102492, 5618.073017952033, 5699.487046165824, 5717.71411429431, 5725.227094342252, 5764.74058667361, 4158.750474971481, 4166.604172603597, 4172.332426811726, 4173.24881521326, 4190.099128687347, 5874.765009399619, 5961.2846458425265, 6009.197240827778, 6010.218671572711, 6034.698265086772, 4312.116016216668, 4312.202242585132, 4318.784060796947, 4318.913621727548, 4322.265214913472, 4346.00041990344, 4364.781760911942, 4365.148795984816, 4372.213001659732, 4378.759168245777, 4775.761211939389, 4939.5554400104165, 5001.174806156943, 5021.949780502148, 5029.119904016856, 5786.07422812348, 5791.247137872134, 5806.838632273562, 5817.418258174149, 5890.823275301748, 1479.2307999840023, 1493.2533733133432, 1508.9849101508844, 1513.9697206055816, 1533.3330001449406, 925.9860452804169, 966.7051549277397, 975.5707488704948, 978.515634760798, 1001.3541265883464, 4987.551120399182, 5010.298558201855, 5026.623003274769, 5069.417017043087, 5070.340855688737, 5238.580913526908, 5278.313819203479, 5290.497402746733, 5310.60170487215, 5312.150055991023, 5055.893547517035, 5065.6200784941275, 5104.9744751276, 5290.523852853262, 5769.134629805562, 3215.951760723591, 3217.951730724041, 3221.5650887130405, 3224.4196044712176, 3243.6700952609317, 6079.209702738615, 6233.812985610439, 6251.874962500816, 6259.311475737665, 6269.749210031548, 6099.359567245464, 6115.541334399938, 6125.571210015345, 6141.969290153521, 6145.863015342176, 5878.559108066912, 6020.518358531394, 6172.22847144107, 6191.501801482148, 6197.597156184834, 5965.373635768356, 5971.8354920790025, 5991.221141251609, 5994.490468310242, 6001.90997135052, 5766.769329226951, 5895.764169433174, 5910.7340169693325, 5921.911171332347, 5939.16851640762, 6159.414855588712, 6176.3514830943905, 6182.443580077815, 6183.690815459119, 6216.7824126155365, 4290.95709042918, 4324.956750432581, 4329.848455304049, 4348.804303806273, 4351.477822661301, 5775.537956963517, 6058.848528786815, 6071.5446341523675, 6076.270847498328, 6089.66027473955, 5917.970410147922, 5948.40515948419, 5949.44174005182, 5950.256217972754, 5957.7319020645045, 5622.662640241599, 5912.290525137011, 5919.31927828288, 5928.436798504135, 5978.461938425388, 5874.676892770939, 5884.35272120075, 5887.764489420375, 5889.852753681193, 5898.882022359615, 5777.537796976148, 5934.347221805684, 5972.25346831646, 5982.850428739316, 5995.400459953969, 5784.438201685605, 5793.697592372308, 5805.332386775575, 5811.82564523065, 5821.534277257724], "value": 4423.073577896124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.2938567132564, 788.3456730913342, 853.1511146409324, 868.5743985447131, 898.7663207566032, 16671.666566668675, 17499.12504374781, 18107.728384074217, 18304.63390732188, 18376.540586485327, 16023.599410014756, 16235.26941287642, 16387.83612163878, 16402.17989100546, 16419.261133249, 864.6022829498438, 864.6498168241872, 870.3689824876977, 872.0320444306815, 872.4242000279817, 887.8357503861774, 888.2272422992006, 888.2361169394316, 888.4269646078276, 888.9644414223424, 909.7361765088117, 914.5701520285455, 916.8212198621271, 917.3991035871514, 917.5045475443253, 910.2717825739383, 910.4719262827577, 910.9362344635878, 913.6254135804321, 916.3127654259306, 665.5474277491304, 666.0508774995612, 666.6966530228783, 667.2426795586995, 667.6695035957184, 15964.121973291465, 16105.677886442205, 16134.919325403298, 16135.596610084724, 16194.595135121715, 14171.362288697033, 14208.786868196985, 14232.501862434765, 14262.144271343648, 14283.571492855232, 12634.965536475567, 12942.158759680591, 13003.804942925864, 13126.278054706987, 13165.934170329183, 4026.4966879140106, 4042.696797740152, 4082.9183416331803, 4129.0, 4192.140611174732, 57652.42347576554, 68107.31892681059, 77978.0, 79424.0, 86225.0, 11392.430378481044, 11555.133364997491, 11625.825612615818, 11784.292942423483, 12566.0, 22061.448463788212, 22211.777882221293, 22256.666150007764, 22344.329670109924, 22349.21777737804, 8841.381103322705, 8870.911290887008, 8913.866292005625, 8981.910180898238, 9288.0, 4869.975650121727, 4975.850724478271, 4998.100341938413, 5008.048470790538, 5010.448850626428, 3535.1869070114017, 3674.889753307405, 3699.648533389324, 3701.4262789267705, 3702.5186725725484, 627.1251604012377, 645.6158585641535, 649.76283656466, 657.5594351784316, 707.426984142846, 691.9792406227821, 739.9741009064657, 762.2529920677656, 770.2605498721178, 785.5914924239363, 9309.627614895464, 9401.905980940102, 9476.620935162655, 9790.265730070274, 10133.543990520444, 585.3911931591183, 651.6220592056595, 657.5430076097163, 674.4199988010307, 686.1628812848317, 4540.954590454119, 4570.223062079454, 4590.104929538775, 4604.40142781443, 4635.466921304026, 76352.0, 77516.0, 78011.21988780153, 120694.3965280168, 135893.0, 56638.7168064157, 109200.45399773109, 110115.0, 116170.41914790373, 119962.0, 93126.53436732772, 95449.99999999862, 96056.0, 98518.5074074625, 99864.50067749615, 9676.854847177157, 10135.695929122137, 10223.488825558543, 10281.845772313272, 10400.895991040295, 52234.73882630563, 52831.735841320544, 53111.46888531065, 53316.200256996955, 53947.46052539424, 70562.0, 71461.14155433932, 76796.0, 81115.0, 81147.59426202832, 12736.554220602235, 12754.0, 13137.080404371602, 13170.341482926007, 13456.596302110953, 15187.772183417479, 15329.693406132037, 15529.068255904682, 15858.365665373256, 16334.346626134822, 15005.699886002001, 15170.089794612359, 15343.925925185129, 15408.537743867702, 15701.979371340827, 13524.323783810967, 13993.230372329619, 14672.3397447113, 14781.704365912407, 15177.696446071237, 14670.0, 14686.55940321792, 14983.625409364853, 15304.0, 15733.44932927342, 12190.817137742764, 12256.877431225574, 12259.816102758645, 12678.492860285485, 13045.152065115737, 12080.0, 12082.75834483323, 12360.38198090074, 12637.304948227567, 12926.289054102115], "value": 7933.1570818435985, "unit": "score"}], "name": "dromaeo_css", "value": 7538.448039499482, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 117.0, 109.0, 109.0, 110.0, 109.0, 111.0, 110.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 147.0, 170.0, 116.0, 145.0, 144.0, 118.0, 167.0, 116.0], "value": 138.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 161.0, 160.0, 160.0, 160.0, 159.0, 162.0, 162.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 181.0, 78.0, 74.0, 74.0, 74.0, 166.0, 76.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 161.0, 84.0, 84.0, 84.0, 149.0, 83.0, 84.0, 153.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 58.0, 50.0, 52.0, 50.0, 49.0, 50.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 72.0, 82.0, 73.0, 78.0, 73.0, 73.0, 72.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 128.0, 124.0, 119.0, 118.0, 116.0, 116.0, 157.0, 125.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 152.0, 154.0, 154.0, 153.0, 149.0, 163.0, 157.0, 148.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 62.0, 62.0, 65.0, 70.0, 61.0, 62.0, 61.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1495.5, "alertThreshold": 2.0}]} 19:35:25 INFO - Return code: 0 19:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:35:25 INFO - # TBPL SUCCESS # 19:35:25 INFO - Running post-action listener: _resource_record_post_action 19:35:25 INFO - [mozharness: 2016-09-23 02:35:25.655598Z] Finished run-tests step (success) 19:35:25 INFO - Running post-run listener: _resource_record_post_run 19:35:25 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287723520; Read time: 16; Write time: 288268 19:35:25 INFO - TinderboxPrint: CPU usage
12.9% 19:35:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:35:25 INFO - TinderboxPrint: I/O write bytes / time
287,723,520 / 288,268 19:35:25 INFO - TinderboxPrint: CPU idle
8,256.1 (87.0%) 19:35:25 INFO - TinderboxPrint: CPU user
1,179.9 (12.4%) 19:35:25 INFO - TinderboxPrint: Swap in / out
0 / 0 19:35:25 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 70094848; Read time: 0; Write time: 57752 19:35:25 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 217628672; Read time: 0; Write time: 230516 19:35:26 INFO - Running post-run listener: _upload_blobber_files 19:35:26 INFO - Blob upload gear active. 19:35:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:35:26 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 19:35:26 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:35:26 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:35:26 INFO - (blobuploader) - INFO - Open directory for files ... 19:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:35:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:27 INFO - (blobuploader) - INFO - Done attempting. 19:35:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:35:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:31 INFO - (blobuploader) - INFO - Done attempting. 19:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:35:32 INFO - (blobuploader) - INFO - Done attempting. 19:35:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:35:32 INFO - Return code: 0 19:35:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:35:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:35:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6a8239ddd01754ca84fe59048aaabe93654381ef99d2dbe743af6ff795c19dfe920f085232fa1f1d70e065426e03470de794a8f23cf878cba1d7acf412cfd11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/512fda23e256c4a22e5d19a8d5b47a305f3b431461ecd6a692f6efb779c4cc81b13070b939f50d8843c15ccaeddb7ecdab3b7668fe9a6fa100acefbe036a4274", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca4c56f05ec399b65c893694ca1bb948f01f31a32a176eab841d0bca11a4c36edca7080427ee4f6e5503ab11edfe961b6ca7d1322d7beb600c72c2b3e45cca0c"} 19:35:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:35:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:35:32 INFO - Contents: 19:35:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6a8239ddd01754ca84fe59048aaabe93654381ef99d2dbe743af6ff795c19dfe920f085232fa1f1d70e065426e03470de794a8f23cf878cba1d7acf412cfd11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/512fda23e256c4a22e5d19a8d5b47a305f3b431461ecd6a692f6efb779c4cc81b13070b939f50d8843c15ccaeddb7ecdab3b7668fe9a6fa100acefbe036a4274", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca4c56f05ec399b65c893694ca1bb948f01f31a32a176eab841d0bca11a4c36edca7080427ee4f6e5503ab11edfe961b6ca7d1322d7beb600c72c2b3e45cca0c"} 19:35:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:35:32 INFO - Copying logs to upload dir... 19:35:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:35:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.087268 ========= master_lag: 163.98 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 30 mins, 41 secs) (at 2016-09-22 19:38:15.997002) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:38:16.000549) ========= 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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6a8239ddd01754ca84fe59048aaabe93654381ef99d2dbe743af6ff795c19dfe920f085232fa1f1d70e065426e03470de794a8f23cf878cba1d7acf412cfd11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/512fda23e256c4a22e5d19a8d5b47a305f3b431461ecd6a692f6efb779c4cc81b13070b939f50d8843c15ccaeddb7ecdab3b7668fe9a6fa100acefbe036a4274", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca4c56f05ec399b65c893694ca1bb948f01f31a32a176eab841d0bca11a4c36edca7080427ee4f6e5503ab11edfe961b6ca7d1322d7beb600c72c2b3e45cca0c"} build_url:https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/O0sF4LJHTm-nHoPeE8NnuA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b6a8239ddd01754ca84fe59048aaabe93654381ef99d2dbe743af6ff795c19dfe920f085232fa1f1d70e065426e03470de794a8f23cf878cba1d7acf412cfd11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/512fda23e256c4a22e5d19a8d5b47a305f3b431461ecd6a692f6efb779c4cc81b13070b939f50d8843c15ccaeddb7ecdab3b7668fe9a6fa100acefbe036a4274", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca4c56f05ec399b65c893694ca1bb948f01f31a32a176eab841d0bca11a4c36edca7080427ee4f6e5503ab11edfe961b6ca7d1322d7beb600c72c2b3e45cca0c"}' ========= master_lag: 0.27 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:38:16.284285) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:38:16.284587) ========= 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=eca2ab57546de7b41f256b19000001ef-1474595004.975547-1671106482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004590 ========= master_lag: 0.49 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:38:16.780248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 19:38:16.780566) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 19:38:16.798820) ========= ========= Total master_lag: 164.81 =========