builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1471900520.08 results: success (0) buildid: 20160822111416 builduid: 3922994bc8444012abb7d2a159a4ed99 revision: 2a0981531355dc77614e72c72fda7498bb074ffe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.077479) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.077922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.078217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.093116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.093497) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 14:15:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-08-22 14:15:20 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113628 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.220856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.221206) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017694 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.270900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.271259) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2a0981531355dc77614e72c72fda7498bb074ffe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2a0981531355dc77614e72c72fda7498bb074ffe --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=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 14:15:20,474 truncating revision to first 12 chars 2016-08-22 14:15:20,474 Setting DEBUG logging. 2016-08-22 14:15:20,474 attempt 1/10 2016-08-22 14:15:20,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2a0981531355?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 14:15:20,766 unpacking tar archive at: mozilla-aurora-2a0981531355/testing/mozharness/ program finished with exit code 0 elapsedTime=0.604705 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.889546) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.889933) ========= script_repo_revision: 2a0981531355dc77614e72c72fda7498bb074ffe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.890367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.890644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:15:20.900109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 14:15:20.900497) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:15:21 INFO - MultiFileLogger online at 20160822 14:15:21 in /builds/slave/test 14:15:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:15:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:15:21 INFO - {'append_to_log': False, 14:15:21 INFO - 'base_work_dir': '/builds/slave/test', 14:15:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:15:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:15:21 INFO - 'branch': 'Mozilla-Aurora', 14:15:21 INFO - 'buildbot_json_path': 'buildprops.json', 14:15:21 INFO - 'config_files': ('talos/linux_config.py',), 14:15:21 INFO - 'default_actions': ('clobber', 14:15:21 INFO - 'read-buildbot-config', 14:15:21 INFO - 'download-and-extract', 14:15:21 INFO - 'populate-webroot', 14:15:21 INFO - 'create-virtualenv', 14:15:21 INFO - 'install', 14:15:21 INFO - 'run-tests'), 14:15:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:15:21 INFO - 'download_minidump_stackwalk': True, 14:15:21 INFO - 'download_symbols': 'ondemand', 14:15:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:15:21 INFO - 'tooltool.py': '/tools/tooltool.py', 14:15:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:15:21 INFO - '/tools/misc-python/virtualenv.py')}, 14:15:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:15:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:15:21 INFO - 'installer_path': 'installer.exe', 14:15:21 INFO - 'log_level': 'info', 14:15:21 INFO - 'log_name': 'talos', 14:15:21 INFO - 'log_to_console': True, 14:15:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:15:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:15:21 INFO - 'opt_config_files': (), 14:15:21 INFO - 'pip_index': False, 14:15:21 INFO - 'sps_profile': False, 14:15:21 INFO - 'sps_profile_interval': 0, 14:15:21 INFO - 'suite': 'dromaeojs', 14:15:21 INFO - 'system_bits': '32', 14:15:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:15:21 INFO - 'title': 'talos-linux64-ix-034', 14:15:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:15:21 INFO - 'use_talos_json': True, 14:15:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:15:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:15:21 INFO - 'work_dir': 'build'} 14:15:21 INFO - [mozharness: 2016-08-22 21:15:21.199968Z] Running clobber step. 14:15:21 INFO - Running pre-action listener: _resource_record_pre_action 14:15:21 INFO - Running main action method: clobber 14:15:21 INFO - rmtree: /builds/slave/test/build 14:15:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:15:22 INFO - Running post-action listener: _resource_record_post_action 14:15:22 INFO - [mozharness: 2016-08-22 21:15:22.173983Z] Finished clobber step (success) 14:15:22 INFO - [mozharness: 2016-08-22 21:15:22.174085Z] Running read-buildbot-config step. 14:15:22 INFO - Running pre-action listener: _resource_record_pre_action 14:15:22 INFO - Running main action method: read_buildbot_config 14:15:22 INFO - Using buildbot properties: 14:15:22 INFO - { 14:15:22 INFO - "project": "", 14:15:22 INFO - "product": "firefox", 14:15:22 INFO - "script_repo_revision": "production", 14:15:22 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:15:22 INFO - "repository": "", 14:15:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:15:22 INFO - "buildid": "20160822111416", 14:15:22 INFO - "pgo_build": "True", 14:15:22 INFO - "basedir": "/builds/slave/test", 14:15:22 INFO - "buildnumber": 482, 14:15:22 INFO - "slavename": "talos-linux64-ix-034", 14:15:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:15:22 INFO - "platform": "ubuntu64_hw", 14:15:22 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:15:22 INFO - "branch": "mozilla-aurora", 14:15:22 INFO - "repo_path": "releases/mozilla-aurora", 14:15:22 INFO - "revision": "2a0981531355dc77614e72c72fda7498bb074ffe", 14:15:22 INFO - "stage_platform": "linux64-pgo", 14:15:22 INFO - "builduid": "3922994bc8444012abb7d2a159a4ed99", 14:15:22 INFO - "slavebuilddir": "test" 14:15:22 INFO - } 14:15:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:15:22 INFO - Running post-action listener: _resource_record_post_action 14:15:22 INFO - [mozharness: 2016-08-22 21:15:22.176529Z] Finished read-buildbot-config step (success) 14:15:22 INFO - [mozharness: 2016-08-22 21:15:22.176618Z] Running download-and-extract step. 14:15:22 INFO - Running pre-action listener: _resource_record_pre_action 14:15:22 INFO - Running main action method: download_and_extract 14:15:22 INFO - mkdir: /builds/slave/test/build/tests 14:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:22 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:15:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:15:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:15:22 INFO - retry: Failed, sleeping 30 seconds before retrying 14:15:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 14:15:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:15:52 INFO - retry: Failed, sleeping 60 seconds before retrying 14:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 14:16:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:16:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 14:16:52 INFO - Caught exception: HTTP Error 404: Not Found 14:16:52 INFO - Caught exception: HTTP Error 404: Not Found 14:16:52 INFO - Caught exception: HTTP Error 404: Not Found 14:16:52 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:16:52 INFO - Downloaded 1361 bytes. 14:16:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:52 INFO - Using the following test package requirements: 14:16:52 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:16:52 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:16:52 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:16:52 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'jsshell-linux-x86_64.zip'], 14:16:52 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:16:52 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:16:52 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:16:52 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:16:52 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:16:52 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:16:52 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:52 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:16:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:16:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:16:52 INFO - retry: Failed, sleeping 30 seconds before retrying 14:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:17:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:17:22 INFO - retry: Failed, sleeping 60 seconds before retrying 14:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:18:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:18:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:18:22 INFO - Caught exception: HTTP Error 404: Not Found 14:18:22 INFO - Caught exception: HTTP Error 404: Not Found 14:18:22 INFO - Caught exception: HTTP Error 404: Not Found 14:18:22 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:22 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:18:23 INFO - Downloaded 21767213 bytes. 14:18:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:18:24 INFO - Return code: 0 14:18:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:24 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:18:24 INFO - retry: Failed, sleeping 30 seconds before retrying 14:18:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:18:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:18:54 INFO - retry: Failed, sleeping 60 seconds before retrying 14:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:19:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:19:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:19:55 INFO - Caught exception: HTTP Error 404: Not Found 14:19:55 INFO - Caught exception: HTTP Error 404: Not Found 14:19:55 INFO - Caught exception: HTTP Error 404: Not Found 14:19:55 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:55 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:19:55 INFO - Downloaded 21767213 bytes. 14:19:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:19:57 INFO - Return code: 0 14:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:57 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:19:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:19:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:19:57 INFO - retry: Failed, sleeping 30 seconds before retrying 14:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:20:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:20:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:20:27 INFO - retry: Failed, sleeping 60 seconds before retrying 14:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:21:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:21:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:21:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:21:27 INFO - Caught exception: HTTP Error 404: Not Found 14:21:27 INFO - Caught exception: HTTP Error 404: Not Found 14:21:27 INFO - Caught exception: HTTP Error 404: Not Found 14:21:27 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:21:27 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:21:28 INFO - Downloaded 14413723 bytes. 14:21:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:21:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:21:28 INFO - Return code: 0 14:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:21:28 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:21:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:21:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:21:28 INFO - retry: Failed, sleeping 30 seconds before retrying 14:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:21:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:21:58 INFO - retry: Failed, sleeping 60 seconds before retrying 14:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:22:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:22:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:22:58 INFO - Caught exception: HTTP Error 404: Not Found 14:22:58 INFO - Caught exception: HTTP Error 404: Not Found 14:22:58 INFO - Caught exception: HTTP Error 404: Not Found 14:22:58 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:22:59 INFO - Downloaded 63764314 bytes. 14:22:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:59 INFO - mkdir: /builds/slave/test/properties 14:22:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:59 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:59 INFO - Contents: 14:22:59 INFO - build_url:https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:23:00 INFO - Running post-action listener: _resource_record_post_action 14:23:00 INFO - Running post-action listener: set_extra_try_arguments 14:23:00 INFO - [mozharness: 2016-08-22 21:23:00.212337Z] Finished download-and-extract step (success) 14:23:00 INFO - [mozharness: 2016-08-22 21:23:00.212570Z] Running populate-webroot step. 14:23:00 INFO - Running pre-action listener: _resource_record_pre_action 14:23:00 INFO - Running main action method: populate_webroot 14:23:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:23:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:23:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:23:00 INFO - '--tppagecycles', 14:23:00 INFO - '7'], 14:23:00 INFO - 'tests': ['tsvgm']}}, 14:23:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:23:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:23:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:23:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:23:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['damp', 'tps']}, 14:23:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'tests': ['damp', 'tps']}, 14:23:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['dromaeo_dom']}, 14:23:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:23:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['basic_compositor_video']}, 14:23:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:23:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'other-e10s': {'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['a11yr', 14:23:00 INFO - 'ts_paint', 14:23:00 INFO - 'tpaint', 14:23:00 INFO - 'sessionrestore', 14:23:00 INFO - 'sessionrestore_no_auto_restore', 14:23:00 INFO - 'tabpaint']}, 14:23:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['tsvgx', 14:23:00 INFO - 'tsvgr_opacity', 14:23:00 INFO - 'tart', 14:23:00 INFO - 'tscrollx', 14:23:00 INFO - 'cart']}, 14:23:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:23:00 INFO - 'tsvgr_opacity', 14:23:00 INFO - 'tart', 14:23:00 INFO - 'tscrollx', 14:23:00 INFO - 'cart']}, 14:23:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'talos_options': ['--disable-e10s'], 14:23:00 INFO - 'tests': ['tp5o']}, 14:23:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'tests': ['tp5o']}, 14:23:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'talos_options': ['--disable-e10s', 14:23:00 INFO - '--xperf_path', 14:23:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:00 INFO - 'tests': ['tp5n']}, 14:23:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:00 INFO - 'talos_options': ['--xperf_path', 14:23:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:00 INFO - 'tests': ['tp5n']}}, 14:23:00 INFO - 'talos.zip': {'path': '', 14:23:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:00 INFO - Running post-action listener: _resource_record_post_action 14:23:00 INFO - [mozharness: 2016-08-22 21:23:00.229022Z] Finished populate-webroot step (success) 14:23:00 INFO - [mozharness: 2016-08-22 21:23:00.229112Z] Running create-virtualenv step. 14:23:00 INFO - Running pre-action listener: _resource_record_pre_action 14:23:00 INFO - Running main action method: create_virtualenv 14:23:00 INFO - Creating virtualenv /builds/slave/test/build/venv 14:23:00 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 14:23:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:23:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:23:00 INFO - Using real prefix '/usr' 14:23:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:23:00 INFO - Installing distribute.............................................................................................................................................................................................done. 14:23:02 INFO - Installing pip.................done. 14:23:02 INFO - Return code: 0 14:23:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:23:02 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')]} 14:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:02 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')]} 14:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:02 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:02 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 14:23:02 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 14:23:02 INFO - Using env: {'DISPLAY': ':0', 14:23:02 INFO - 'HOME': '/home/cltbld', 14:23:02 INFO - 'LANG': 'en_US.UTF-8', 14:23:02 INFO - 'LANGUAGE': 'en_US:en', 14:23:02 INFO - 'LOGNAME': 'cltbld', 14:23:02 INFO - 'MAIL': '/var/mail/cltbld', 14:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:02 INFO - 'MOZ_NO_REMOTE': '1', 14:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:02 INFO - 'NO_EM_RESTART': '1', 14:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:02 INFO - 'PWD': '/builds/slave/test', 14:23:02 INFO - 'SHELL': '/bin/bash', 14:23:02 INFO - 'SHLVL': '1', 14:23:02 INFO - 'TERM': 'linux', 14:23:02 INFO - 'TMOUT': '86400', 14:23:02 INFO - 'USER': 'cltbld', 14:23:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:02 INFO - '_': '/tools/buildbot/bin/python'} 14:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:02 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 14:23:02 INFO - Cleaning up... 14:23:03 INFO - Return code: 0 14:23:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:23:03 INFO - Using env: {'DISPLAY': ':0', 14:23:03 INFO - 'HOME': '/home/cltbld', 14:23:03 INFO - 'LANG': 'en_US.UTF-8', 14:23:03 INFO - 'LANGUAGE': 'en_US:en', 14:23:03 INFO - 'LOGNAME': 'cltbld', 14:23:03 INFO - 'MAIL': '/var/mail/cltbld', 14:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:03 INFO - 'MOZ_NO_REMOTE': '1', 14:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:03 INFO - 'NO_EM_RESTART': '1', 14:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:03 INFO - 'PWD': '/builds/slave/test', 14:23:03 INFO - 'SHELL': '/bin/bash', 14:23:03 INFO - 'SHLVL': '1', 14:23:03 INFO - 'TERM': 'linux', 14:23:03 INFO - 'TMOUT': '86400', 14:23:03 INFO - 'USER': 'cltbld', 14:23:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:03 INFO - '_': '/tools/buildbot/bin/python'} 14:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:03 INFO - Downloading/unpacking psutil>=3.1.1 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:23:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:05 INFO - Installing collected packages: psutil 14:23:05 INFO - Running setup.py install for psutil 14:23:05 INFO - building 'psutil._psutil_linux' extension 14:23:05 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 14:23:05 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 14:23:05 INFO - building 'psutil._psutil_posix' extension 14:23:05 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 14:23:05 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 14:23:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:05 INFO - Successfully installed psutil 14:23:05 INFO - Cleaning up... 14:23:05 INFO - Return code: 0 14:23:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:23:05 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')]} 14:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:05 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')]} 14:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:05 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:05 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 14:23:05 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 14:23:05 INFO - Using env: {'DISPLAY': ':0', 14:23:05 INFO - 'HOME': '/home/cltbld', 14:23:05 INFO - 'LANG': 'en_US.UTF-8', 14:23:05 INFO - 'LANGUAGE': 'en_US:en', 14:23:05 INFO - 'LOGNAME': 'cltbld', 14:23:05 INFO - 'MAIL': '/var/mail/cltbld', 14:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:05 INFO - 'MOZ_NO_REMOTE': '1', 14:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:05 INFO - 'NO_EM_RESTART': '1', 14:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:05 INFO - 'PWD': '/builds/slave/test', 14:23:05 INFO - 'SHELL': '/bin/bash', 14:23:05 INFO - 'SHLVL': '1', 14:23:05 INFO - 'TERM': 'linux', 14:23:05 INFO - 'TMOUT': '86400', 14:23:05 INFO - 'USER': 'cltbld', 14:23:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:05 INFO - '_': '/tools/buildbot/bin/python'} 14:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23:05 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 14:23:05 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 14:23:05 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 14:23:05 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 14:23:05 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 14:23:05 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 14:23:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:23:08 INFO - Installing collected packages: mozsystemmonitor 14:23:08 INFO - Running setup.py install for mozsystemmonitor 14:23:08 INFO - Successfully installed mozsystemmonitor 14:23:08 INFO - Cleaning up... 14:23:08 INFO - Return code: 0 14:23:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:23:08 INFO - Using env: {'DISPLAY': ':0', 14:23:08 INFO - 'HOME': '/home/cltbld', 14:23:08 INFO - 'LANG': 'en_US.UTF-8', 14:23:08 INFO - 'LANGUAGE': 'en_US:en', 14:23:08 INFO - 'LOGNAME': 'cltbld', 14:23:08 INFO - 'MAIL': '/var/mail/cltbld', 14:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:08 INFO - 'MOZ_NO_REMOTE': '1', 14:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:08 INFO - 'NO_EM_RESTART': '1', 14:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:08 INFO - 'PWD': '/builds/slave/test', 14:23:08 INFO - 'SHELL': '/bin/bash', 14:23:08 INFO - 'SHLVL': '1', 14:23:08 INFO - 'TERM': 'linux', 14:23:08 INFO - 'TMOUT': '86400', 14:23:08 INFO - 'USER': 'cltbld', 14:23:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:08 INFO - '_': '/tools/buildbot/bin/python'} 14:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:08 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:08 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 14:23:08 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 14:23:08 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 14:23:08 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 14:23:08 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 14:23:08 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 14:23:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:10 INFO - warning: no files found matching '*.txt' 14:23:10 INFO - no previously-included directories found matching 'build' 14:23:10 INFO - no previously-included directories found matching 'dist' 14:23:10 INFO - no previously-included directories found matching '.git*' 14:23:10 INFO - Installing collected packages: jsonschema, functools32 14:23:10 INFO - Running setup.py install for functools32 14:23:10 INFO - warning: no files found matching '*.txt' 14:23:10 INFO - no previously-included directories found matching 'build' 14:23:10 INFO - no previously-included directories found matching 'dist' 14:23:10 INFO - no previously-included directories found matching '.git*' 14:23:10 INFO - Successfully installed jsonschema functools32 14:23:10 INFO - Cleaning up... 14:23:10 INFO - Return code: 0 14:23:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:10 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:10 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 14:23:10 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 14:23:10 INFO - Using env: {'DISPLAY': ':0', 14:23:10 INFO - 'HOME': '/home/cltbld', 14:23:10 INFO - 'LANG': 'en_US.UTF-8', 14:23:10 INFO - 'LANGUAGE': 'en_US:en', 14:23:10 INFO - 'LOGNAME': 'cltbld', 14:23:10 INFO - 'MAIL': '/var/mail/cltbld', 14:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:10 INFO - 'MOZ_NO_REMOTE': '1', 14:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:10 INFO - 'NO_EM_RESTART': '1', 14:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:10 INFO - 'PWD': '/builds/slave/test', 14:23:10 INFO - 'SHELL': '/bin/bash', 14:23:10 INFO - 'SHLVL': '1', 14:23:10 INFO - 'TERM': 'linux', 14:23:10 INFO - 'TMOUT': '86400', 14:23:10 INFO - 'USER': 'cltbld', 14:23:10 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:10 INFO - '_': '/tools/buildbot/bin/python'} 14:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:23:11 INFO - Cleaning up... 14:23:11 INFO - Return code: 0 14:23:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:23:11 INFO - Using env: {'DISPLAY': ':0', 14:23:11 INFO - 'HOME': '/home/cltbld', 14:23:11 INFO - 'LANG': 'en_US.UTF-8', 14:23:11 INFO - 'LANGUAGE': 'en_US:en', 14:23:11 INFO - 'LOGNAME': 'cltbld', 14:23:11 INFO - 'MAIL': '/var/mail/cltbld', 14:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:11 INFO - 'MOZ_NO_REMOTE': '1', 14:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:11 INFO - 'NO_EM_RESTART': '1', 14:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:11 INFO - 'PWD': '/builds/slave/test', 14:23:11 INFO - 'SHELL': '/bin/bash', 14:23:11 INFO - 'SHLVL': '1', 14:23:11 INFO - 'TERM': 'linux', 14:23:11 INFO - 'TMOUT': '86400', 14:23:11 INFO - 'USER': 'cltbld', 14:23:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:11 INFO - '_': '/tools/buildbot/bin/python'} 14:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:11 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:13 INFO - Downloading blobuploader-1.2.4.tar.gz 14:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:23:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:23:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:13 INFO - Downloading docopt-0.6.1.tar.gz 14:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:23:13 INFO - Installing collected packages: blobuploader, requests, docopt 14:23:13 INFO - Running setup.py install for blobuploader 14:23:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:23:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:23:14 INFO - Running setup.py install for requests 14:23:14 INFO - Running setup.py install for docopt 14:23:14 INFO - Successfully installed blobuploader requests docopt 14:23:14 INFO - Cleaning up... 14:23:14 INFO - Return code: 0 14:23:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:14 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')]} 14:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:14 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')]} 14:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:14 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:14 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 14:23:14 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 14:23:14 INFO - Using env: {'DISPLAY': ':0', 14:23:14 INFO - 'HOME': '/home/cltbld', 14:23:14 INFO - 'LANG': 'en_US.UTF-8', 14:23:14 INFO - 'LANGUAGE': 'en_US:en', 14:23:14 INFO - 'LOGNAME': 'cltbld', 14:23:14 INFO - 'MAIL': '/var/mail/cltbld', 14:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:14 INFO - 'MOZ_NO_REMOTE': '1', 14:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:14 INFO - 'NO_EM_RESTART': '1', 14:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:14 INFO - 'PWD': '/builds/slave/test', 14:23:14 INFO - 'SHELL': '/bin/bash', 14:23:14 INFO - 'SHLVL': '1', 14:23:14 INFO - 'TERM': 'linux', 14:23:14 INFO - 'TMOUT': '86400', 14:23:14 INFO - 'USER': 'cltbld', 14:23:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:14 INFO - '_': '/tools/buildbot/bin/python'} 14:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:14 INFO - Running setup.py (path:/tmp/pip-hx2LnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:14 INFO - Running setup.py (path:/tmp/pip-JhQGxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:14 INFO - Running setup.py (path:/tmp/pip-9xjMwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:14 INFO - Running setup.py (path:/tmp/pip-uZbrMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:15 INFO - Running setup.py (path:/tmp/pip-QgyGPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:15 INFO - Running setup.py (path:/tmp/pip-9Ic58W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:15 INFO - Running setup.py (path:/tmp/pip-oiOGqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:15 INFO - Running setup.py (path:/tmp/pip-xlNQyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:15 INFO - Running setup.py (path:/tmp/pip-62ggIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:15 INFO - Running setup.py (path:/tmp/pip-HPWxOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:15 INFO - Running setup.py (path:/tmp/pip-ekv9tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:15 INFO - Running setup.py (path:/tmp/pip-3uox39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:15 INFO - Running setup.py (path:/tmp/pip-QNbrBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:15 INFO - Running setup.py (path:/tmp/pip-3MgPpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:15 INFO - Running setup.py (path:/tmp/pip-waeavd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:15 INFO - Running setup.py (path:/tmp/pip-S7Rp0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:15 INFO - Running setup.py (path:/tmp/pip-edbhbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:15 INFO - Running setup.py install for manifestparser 14:23:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Running setup.py install for mozcrash 14:23:16 INFO - Running setup.py install for mozdebug 14:23:16 INFO - Running setup.py install for mozdevice 14:23:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Running setup.py install for mozfile 14:23:16 INFO - Running setup.py install for mozhttpd 14:23:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Running setup.py install for mozinfo 14:23:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Running setup.py install for mozInstall 14:23:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:16 INFO - Running setup.py install for mozleak 14:23:16 INFO - Running setup.py install for mozlog 14:23:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Running setup.py install for moznetwork 14:23:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Running setup.py install for mozprocess 14:23:17 INFO - Running setup.py install for mozprofile 14:23:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Running setup.py install for mozrunner 14:23:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Running setup.py install for mozscreenshot 14:23:17 INFO - Running setup.py install for moztest 14:23:17 INFO - Running setup.py install for mozversion 14:23:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:17 INFO - Cleaning up... 14:23:17 INFO - Return code: 0 14:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:17 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:17 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 14:23:17 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 14:23:17 INFO - Using env: {'DISPLAY': ':0', 14:23:17 INFO - 'HOME': '/home/cltbld', 14:23:17 INFO - 'LANG': 'en_US.UTF-8', 14:23:17 INFO - 'LANGUAGE': 'en_US:en', 14:23:17 INFO - 'LOGNAME': 'cltbld', 14:23:17 INFO - 'MAIL': '/var/mail/cltbld', 14:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:17 INFO - 'MOZ_NO_REMOTE': '1', 14:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:17 INFO - 'NO_EM_RESTART': '1', 14:23:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:17 INFO - 'PWD': '/builds/slave/test', 14:23:17 INFO - 'SHELL': '/bin/bash', 14:23:17 INFO - 'SHLVL': '1', 14:23:17 INFO - 'TERM': 'linux', 14:23:17 INFO - 'TMOUT': '86400', 14:23:17 INFO - 'USER': 'cltbld', 14:23:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:17 INFO - '_': '/tools/buildbot/bin/python'} 14:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:18 INFO - Running setup.py (path:/tmp/pip-n1o6Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:18 INFO - Running setup.py (path:/tmp/pip-Y0gN6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:18 INFO - Running setup.py (path:/tmp/pip-NbIWcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:18 INFO - Running setup.py (path:/tmp/pip-BqguOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:18 INFO - Running setup.py (path:/tmp/pip-0axpZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:18 INFO - Running setup.py (path:/tmp/pip-ECQTkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:18 INFO - Running setup.py (path:/tmp/pip-zTGEJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:18 INFO - Running setup.py (path:/tmp/pip-owSVNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:18 INFO - Running setup.py (path:/tmp/pip-6AvmDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:18 INFO - Running setup.py (path:/tmp/pip-tpco0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:18 INFO - Running setup.py (path:/tmp/pip-xxDwV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:18 INFO - Running setup.py (path:/tmp/pip-TiHGqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:18 INFO - Running setup.py (path:/tmp/pip-0DrFkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:18 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)) 14:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:18 INFO - Running setup.py (path:/tmp/pip-yBFpcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:19 INFO - Running setup.py (path:/tmp/pip-Sej3Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:19 INFO - Running setup.py (path:/tmp/pip-qUGbo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:19 INFO - Running setup.py (path:/tmp/pip-yh_jyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:21 INFO - Downloading blessings-1.6.tar.gz 14:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:21 INFO - Installing collected packages: blessings 14:23:21 INFO - Running setup.py install for blessings 14:23:21 INFO - Successfully installed blessings 14:23:21 INFO - Cleaning up... 14:23:21 INFO - Return code: 0 14:23:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:21 INFO - Reading from file tmpfile_stdout 14:23:21 INFO - Current package versions: 14:23:21 INFO - argparse == 1.2.1 14:23:21 INFO - blessings == 1.6 14:23:21 INFO - blobuploader == 1.2.4 14:23:21 INFO - docopt == 0.6.1 14:23:21 INFO - functools32 == 3.2.3-2 14:23:21 INFO - jsonschema == 2.5.1 14:23:21 INFO - manifestparser == 1.1 14:23:21 INFO - mozInstall == 1.12 14:23:21 INFO - mozcrash == 0.17 14:23:21 INFO - mozdebug == 0.1 14:23:21 INFO - mozdevice == 0.48 14:23:21 INFO - mozfile == 1.2 14:23:21 INFO - mozhttpd == 0.7 14:23:21 INFO - mozinfo == 0.9 14:23:21 INFO - mozleak == 0.1 14:23:21 INFO - mozlog == 3.3 14:23:21 INFO - moznetwork == 0.27 14:23:21 INFO - mozprocess == 0.23 14:23:21 INFO - mozprofile == 0.28 14:23:21 INFO - mozrunner == 6.12 14:23:21 INFO - mozscreenshot == 0.1 14:23:21 INFO - mozsystemmonitor == 0.3 14:23:21 INFO - moztest == 0.7 14:23:21 INFO - mozversion == 1.4 14:23:21 INFO - psutil == 3.1.1 14:23:21 INFO - requests == 1.2.3 14:23:21 INFO - wsgiref == 0.1.2 14:23:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:21 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 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:21 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 14:23:21 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 14:23:21 INFO - Using env: {'DISPLAY': ':0', 14:23:21 INFO - 'HOME': '/home/cltbld', 14:23:21 INFO - 'LANG': 'en_US.UTF-8', 14:23:21 INFO - 'LANGUAGE': 'en_US:en', 14:23:21 INFO - 'LOGNAME': 'cltbld', 14:23:21 INFO - 'MAIL': '/var/mail/cltbld', 14:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:21 INFO - 'MOZ_NO_REMOTE': '1', 14:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:21 INFO - 'NO_EM_RESTART': '1', 14:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:21 INFO - 'PWD': '/builds/slave/test', 14:23:21 INFO - 'SHELL': '/bin/bash', 14:23:21 INFO - 'SHLVL': '1', 14:23:21 INFO - 'TERM': 'linux', 14:23:21 INFO - 'TMOUT': '86400', 14:23:21 INFO - 'USER': 'cltbld', 14:23:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:21 INFO - '_': '/tools/buildbot/bin/python'} 14:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 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)) 14:23:21 INFO - Cleaning up... 14:23:21 INFO - Return code: 0 14:23:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20dc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', '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 14:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:23:21 INFO - Using env: {'DISPLAY': ':0', 14:23:21 INFO - 'HOME': '/home/cltbld', 14:23:21 INFO - 'LANG': 'en_US.UTF-8', 14:23:21 INFO - 'LANGUAGE': 'en_US:en', 14:23:21 INFO - 'LOGNAME': 'cltbld', 14:23:21 INFO - 'MAIL': '/var/mail/cltbld', 14:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:21 INFO - 'MOZ_NO_REMOTE': '1', 14:23:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:21 INFO - 'NO_EM_RESTART': '1', 14:23:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:21 INFO - 'PWD': '/builds/slave/test', 14:23:21 INFO - 'SHELL': '/bin/bash', 14:23:21 INFO - 'SHLVL': '1', 14:23:21 INFO - 'TERM': 'linux', 14:23:21 INFO - 'TMOUT': '86400', 14:23:21 INFO - 'USER': 'cltbld', 14:23:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:21 INFO - '_': '/tools/buildbot/bin/python'} 14:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:21 INFO - Cleaning up... 14:23:22 INFO - Return code: 0 14:23:22 INFO - Running post-action listener: _resource_record_post_action 14:23:22 INFO - Running post-action listener: _start_resource_monitoring 14:23:22 INFO - Starting resource monitoring. 14:23:22 INFO - [mozharness: 2016-08-22 21:23:22.038297Z] Finished create-virtualenv step (success) 14:23:22 INFO - [mozharness: 2016-08-22 21:23:22.038731Z] Running install step. 14:23:22 INFO - Running pre-action listener: _resource_record_pre_action 14:23:22 INFO - Running main action method: install 14:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:22 INFO - Reading from file tmpfile_stdout 14:23:22 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:22 INFO - Reading from file tmpfile_stdout 14:23:22 INFO - Output received: 14:23:22 INFO - Usage: mozinstall [options] installer 14:23:22 INFO - Options: 14:23:22 INFO - -h, --help show this help message and exit 14:23:22 INFO - -d DEST, --destination=DEST 14:23:22 INFO - Directory to install application into. [default: 14:23:22 INFO - "/builds/slave/test"] 14:23:22 INFO - --app=APP Application being installed. [default: firefox] 14:23:22 INFO - mkdir: /builds/slave/test/build/application 14:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:23:39 INFO - Reading from file tmpfile_stdout 14:23:39 INFO - Output received: 14:23:39 INFO - /builds/slave/test/build/application/firefox/firefox 14:23:39 INFO - Running post-action listener: _resource_record_post_action 14:23:39 INFO - [mozharness: 2016-08-22 21:23:39.223405Z] Finished install step (success) 14:23:39 INFO - [mozharness: 2016-08-22 21:23:39.223747Z] Running run-tests step. 14:23:39 INFO - Running pre-action listener: _resource_record_pre_action 14:23:39 INFO - Running main action method: run_tests 14:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:23:39 INFO - Python 2.7.3 14:23:39 INFO - Return code: 0 14:23:39 INFO - grabbing minidump binary from tooltool 14:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239b3b0>, '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 0x239c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:23:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:23:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:23:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:23:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:39 INFO - Return code: 0 14:23:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:23:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:23:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:23:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:23:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 14:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 14:23:39 INFO - Using env: {'DISPLAY': ':0', 14:23:39 INFO - 'HOME': '/home/cltbld', 14:23:39 INFO - 'LANG': 'en_US.UTF-8', 14:23:39 INFO - 'LANGUAGE': 'en_US:en', 14:23:39 INFO - 'LOGNAME': 'cltbld', 14:23:39 INFO - 'MAIL': '/var/mail/cltbld', 14:23:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:23:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:39 INFO - 'MOZ_NO_REMOTE': '1', 14:23:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:39 INFO - 'NO_EM_RESTART': '1', 14:23:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:39 INFO - 'PWD': '/builds/slave/test', 14:23:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:23:39 INFO - 'SHELL': '/bin/bash', 14:23:39 INFO - 'SHLVL': '1', 14:23:39 INFO - 'TERM': 'linux', 14:23:39 INFO - 'TMOUT': '86400', 14:23:39 INFO - 'USER': 'cltbld', 14:23:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324', 14:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:39 INFO - '_': '/tools/buildbot/bin/python'} 14:23:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 14:23:39 INFO - mozversion application_buildid: 20160822111416 14:23:39 INFO - mozversion application_changeset: 2a0981531355dc77614e72c72fda7498bb074ffe 14:23:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:23:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:39 INFO - mozversion application_name: Firefox 14:23:39 INFO - mozversion application_remotingname: firefox-dev 14:23:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:23:39 INFO - mozversion application_vendor: Mozilla 14:23:39 INFO - mozversion application_version: 50.0a2 14:23:39 INFO - mozversion platform_buildid: 20160822111416 14:23:39 INFO - mozversion platform_changeset: 2a0981531355dc77614e72c72fda7498bb074ffe 14:23:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:23:39 INFO - mozversion platform_version: 50.0a2 14:23:39 INFO - using testdate: 1471901019 14:23:39 INFO - actual date: 1471901019 14:23:39 INFO - starting webserver on 'localhost:46127' 14:23:39 INFO - SUITE-START | Running 2 tests 14:23:39 INFO - TEST-START | dromaeo_css 14:23:39 INFO - operating with platform_type : linux_ 14:23:39 INFO - Initialising browser for dromaeo_css test... 14:23:39 INFO - TEST-INFO | started process 31800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxjkNgE/profile http://localhost:46127/getInfo.html) 14:23:43 INFO - PROCESS | 31800 | __metrics Screen width/height:1600/1200 14:23:43 INFO - PROCESS | 31800 | colorDepth:24 14:23:43 INFO - PROCESS | 31800 | Browser inner width/height: 1024/697 14:23:43 INFO - PROCESS | 31800 | __metrics 14:23:44 INFO - PROCESS | 31800 | 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" 14:23:44 INFO - PROCESS | 31800 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:23:45 INFO - TEST-INFO | 31800: exit 0 14:23:45 INFO - Browser initialized. 14:23:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:45 INFO - TEST-INFO | started process 31892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxjkNgE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:57 INFO - PROCESS | 31892 | RSS: Main: 144703488 14:23:57 INFO - PROCESS | 31892 | 14:24:48 INFO - PROCESS | 31892 | 1471901088282 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] 14:26:57 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46127/tests/dromaeo/cssquery-ext.html) 14:26:58 INFO - PROCESS | 31892 | RSS: Main: 280866816 14:26:58 INFO - PROCESS | 31892 | 14:30:01 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-ext.html (next: http://localhost:46127/tests/dromaeo/cssquery-jquery.html) 14:30:01 INFO - PROCESS | 31892 | RSS: Main: 232824832 14:30:01 INFO - PROCESS | 31892 | 14:33:03 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46127/tests/dromaeo/cssquery-mootools.html) 14:33:04 INFO - PROCESS | 31892 | RSS: Main: 230506496 14:33:04 INFO - PROCESS | 31892 | 14:36:06 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46127/tests/dromaeo/cssquery-prototype.html) 14:36:06 INFO - PROCESS | 31892 | RSS: Main: 175276032 14:36:06 INFO - PROCESS | 31892 | 14:39:09 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46127/tests/dromaeo/cssquery-yui.html) 14:39:10 INFO - PROCESS | 31892 | RSS: Main: 173137920 14:39:10 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | Cycle 1(1): loaded http://localhost:46127/tests/dromaeo/cssquery-yui.html (next: http://localhost:46127/tests/dromaeo/cssquery-dojo.html) 14:42:12 INFO - PROCESS | 31892 | RSS: Main: 265969664 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | __start_tp_report 14:42:12 INFO - PROCESS | 31892 | _x_x_mozilla_page_load 14:42:12 INFO - PROCESS | 31892 | _x_x_mozilla_page_load_details 14:42:12 INFO - PROCESS | 31892 | |i|pagename|runs| 14:42:12 INFO - PROCESS | 31892 | |0;dojo.html;3297.934041319172;3306.1569299828548;3314.171457135716;3317.8838740644082;3339.9165020874475;5576.693281869496;5672.546196304296;5678.063119585274;5681.431856814316;5688.459596338346;5609.915851262234;5674.148877668352;5674.375818659944;5682.317682317683;5685.914711279334;5005.599552035828;5130.640855140142;5139.331886854713;5184.662996905189;5208.947910520903;5064.24036394542;5089.3129427527365;5122.743862806863;5161.30322406476;5194.480551944813;5072.9492705073035;5079.441261461255;5106.055379754739;5122.078025955345;5123.257127716479;5152.9484705152845;5171.637985341028;5173.741312934339;5190.92213616796;5194.376674799029;5054.69671819692;5086.109930762102;5087.211482220262;5094.2103973884105;5128;5608.859778505529;5621.662700237999;5643.661380317153;5650.6892120933335;5683.715814209314;6127.601705889103;6161.661108639023;6164.8767024659255;6165.075238714184;6166.475849552793;5939.168516407706;6052.563567324724;6063.514918806485;6099.48154406876;6181.721822517952;5752.28096487939;5765.423457654243;5767.826965191009;5776.913346299809;5779;6236.251649802053;6278.183836101278;6288.874222515524;6299.055141728686;6299.5275354348605;5796.304443466811;5869.20765696634;5956;5956.821295361146;6028.09578563219;6090.573659843857;6091.568092356343;6093.658089151685;6093.659031762139;6095.384723048396;6058.515318774487;6060.090986351995;6061.54538409621;6063.454289114001;6091.66495842724;5905.586608937333;5946.07835785454;5971.253593300838;6031.095335699592;6058.303295121117;4621.353010578522;4655.86032419028;4673.626109911199;4675.158471475167;4695.060987802453;4158.397032430281;4164.8125834337525;4170.04089059518;4180.770057646798;4194.3498757692605;5623.184638227434;5747.109198074305;5815.88368232633;5826.126081087871;5908.202392676933;6208.751649934043;6211.2857021442205;6219.564630475823;6222.595531290543;6223.96888015557;5882.764689412461;5891.675957822361;5897.941020589825;5899.528037756969;5920.644761314335;5151.562117220078;5182.714950677673;5204.71374074422;5231.633785635046;5349.732513374316;6252.5935814171935;6297.716602752796;6303.842403939938;6311.305756366796;6323.36766323364;6069.939300606938;6085.482733967662;6085.756569737338;6088.208532890696;6089.117078023751;6134.294556126102;6227.1282020516255;6249.562530622813;6325.209348831396;6367.395097465734;6112.381899552462;6118.741008805849;6125.7238540429535;6126.029144987285;6132.3017872136115;6205.627662340274;6223.128761973237;6229.688515574294;6231.034189700604;6235.469985051322;5776.740046697824;5876.676782776988;5892.145638882253;5928.140419639214;6082.2701275847185;5901.704914754331;5944.494717949023;5949.762009519571;5960.374160713236;5979.970100149471;5887.352391236875;5930.881382372242;5945.851353716192;5947.583669143291;5951.7619295227705;5884.882302354014;5885.176075349369;5941.2276404068925;5959.553033522417;6008.459238668541;5867.9413205867395;5876.6180198286975;5880.588358814842;5881.5588830838715;5911.201987731602 14:42:12 INFO - PROCESS | 31892 | |1;ext.html;603.8641305706217;638.256431257585;648.8702259548089;660.3264670036563;661.4146480364876;35442.1139471513;46444;47630.523694763084;47659.76170119145;47776.76111619437;47943.76028119854;48289.1026538408;48428.2735758963;48432.2735158972;48481;8489.61796719146;9127.224185944202;9276.397034192756;9283.210927071206;10854.24020318578;10741.570337186504;11190.944045279803;11223.214374993753;11226.550937962471;11227.101831853473;11290.379029153435;11309.830352544719;11318.773624527505;11326.886731132665;11334.603288884891;11161.274517156358;11190.552377904874;11233.550657973672;11241.100711943025;11251.774964500746;10981.725456863562;11042.613508527164;11044.613438529694;11045.171612129123;11059.944700276528;44203.33694994578;44225.11549768987;44382.55617443816;44568.3314750279;44608;3856.093817952779;4257.446531950826;4443.511213766483;4553.248713962193;4577.725336479822;35215.647843521496;35422.82288588566;35503.82248088743;36044.81977590122;37660.811695941346;4560.498345182027;4572.771361431948;4600.861974140781;4624.745638989854;4642.837500687494;407011;408965;409159;409641.99999999703;410257;63567.364326356146;63743.36256637374;63949.36050639527;63965.68017159885;64056;38771.224575508335;39282.41076383857;39533.60466395357;42155.367669484986;42174;19433.611327773648;19605.803941960683;19639.803601964082;19643.60712785765;19647.41057768269;12497.062720296015;13759.10565813239;14153.504627337994;14355;14426.783598246035;20496;20502.077406516742;20571.58856822855;20584;20594.897025515078;7183.56898586076;7315.487915845946;7412.592307423143;7432.925670743223;7542.681220263066;1062.8671416072991;1075.9677209683723;1105.9778804423865;1112.093643680398;1153.8326942593278;34403.82798085993;34720.305593887984;35192;35313;35382.64617353845;1154.9538018479334;1194.5699548162654;1199.3463562358536;1232.9321887296285;1237.9972222499805;17690.29238830458;18347.54131146732;18398.26406943734;18417.81582184188;18456;668120;672348;673646;677938;681146;62901.370986290465;63627;63806.36193638004;63806.76676316399;63984;1319.2480286236753;1354.7290541891662;1428.221619217508;1462.531989763287;1494.260341131147;30961.535576965915;36474.635253647124;36555.26889462249;36619.26761464809;36691.44962825613;55137.72431137819;55590.444095560146;55649.16526252191;55686.16470753023;55695.44304556902;456220;459886;460376;460429;460851;29988;30771.230719232197;30899.845500772353;31310.686893130776;33046.50430243596;52621.73689131683;52728.736356317975;53208.73395633152;53345.46654533405;53402;42752;44223.33664995092;44624.776876115415;45123.548764511936;46170.76914615405;45380.77309613563;45894.77052614716;46555.53444465512;47102;47297.29054064261;42776.78611606922;43884.78057609691;44289.557104428546;44881.10237795291;48079;37586.436203456426;39685.801570991964;40785.79607101945;40914.18171636491;41005;29788;30463.390732185675;30804.53793193149;30820.22949426282;31269 14:42:12 INFO - PROCESS | 31892 | |2;jquery.html;387.6007712056579;403.0849970566812;410.3803257081806;416.95413504514505;428.9249381358263;16468.17659117044;19929;20417.897910510426;20683.96580170991;22206.66689999647;19664.41006769799;19916.60166796663;19972.70040949383;20065.899670501665;20312.390628281137;5854.677992710399;5880.882382352372;6004.279486461631;6050.788222412217;6065.514758819284;4415.161119387322;6012.428819262163;6014.218151640281;6426.967865160667;6782.932170678279;6769;6811.421029212523;6822.6588670566525;6822.692978815965;6837.247902730695;5598.552115830744;5837.387074357195;5850.590458667895;5918.674472903988;5919.319278283009;5597.104463285896;5730.68481233532;5753.539716822686;5765.711714414305;5775.855603609902;13544.796828047587;13598.38807253676;13760.862391376058;13790.793138102936;13814.447422103103;2484.080890070685;2511.0583531175807;2844.2604922720243;2857.9428411431863;2939.1623387334507;10045;10360.48197590125;10562.31344962575;10609.363438193734;14797.186154761484;2015.284573976232;2030.9086091125935;2039.2047101630376;2039.7858224886322;2065.9690104648444;79243.20756792415;79834;84086.57956710238;84479.57760211221;92324.53837730836;9962.750931226778;10216.233782466345;10306.742331441625;10507.684769456928;10530.63142790014;14229.430822766974;14278.571642850731;14288.642783930278;14304.141751494735;14306.713865722835;8607.483550986835;8654.87017694735;8666.436681615802;8736.344774141964;8896.665500174897;8170.591470426455;8207.507549547046;8826.691065812665;8934.687285944961;9315.068493150766;4125.29869922114;4267.317229243306;4267.701260911706;4359.17175736609;4496.235639941217;1991.9004049797452;2044.826189773886;2082.37528741377;2099.086897199723;2138.518833262535;940.2008292951074;959.3524371049514;978.8189185000798;1032.509557959963;1062.436908438529;20603.4849128773;20685.896570517052;20807;21163.114384636956;21175.894120529607;995.1640621877658;1004.8492726091145;1025.0876719719456;1040.4225654761528;1072.5012869015945;10789.730256743644;11585.478653460614;11752.823707644393;11802.232854864405;11837.171398002058;533197;565192;571579;576131;579441;58482.707586461795;99835.50082249688;101061.49469252754;102674;103077;1000.2348203624233;1001.0940099210227;1010.4291075542295;1035.7410647338165;1041.859348987877;18149.637007260044;18541.814581854007;18929.33747318837;18971.620567588296;19109.426717198505;46456.53543464522;48158.5184148154;48443.757781210865;48991.75504122457;49114.75442622764;61379.693101535995;66238;66920.33079669141;67081;67987.3201267981;8109.553974531457;8149.266566009088;8271.421000529905;8411.747647570583;8443.324534037385;10729.356238625707;12983.480660773841;13000.024998124989;13441.395137218653;13470.595882123553;10831.313560380862;11486.081113510732;11507.194496385173;11635.47640356203;11739.354335511629;11541.249818761753;11816.172867899508;11856.76286474283;11870.050395968474;12056.51773929033;11345.205835591429;11455.255408398427;11753.294802311888;11787.705307367385;11934.761304774029;7478.214787447458;7755.689772409266;7867.567283799446;8217.137200593852;9385.108414700866;10981;11201.831972520256;11390.37352945596;11445.885541144482;11470.254433461798 14:42:12 INFO - PROCESS | 31892 | |3;mootools.html;1369.5412036967616;1526.2521364531376;1595.4176725495195;1778.4042345814153;1907.141786196212;8270.297024752896;9160.496172710507;9528.237740980721;9542.236621070306;9808.754781130476;9564.234861211104;9644.469554174535;9648.179904708108;9662.227021838253;9665.226781857453;2436.0621160853066;2467.4571594249237;2473.134402958968;2474.8391354561895;2475.096589744748;2391.8086553075714;2476.7523247675267;2477.7770000699934;2489.128804918282;2490.0164435048146;2412.8190385721055;2433.5862903306393;2481.85108893467;2484.043643197364;2486.7264600893886;2382.618780995041;2412.5777988851883;2420.1166574200465;2423.103451722865;2426.3206302235403;2358.481134150484;2366.7751563601432;2385.2009576791797;2395.101836811196;2395.7843794058617;8832.690855820016;8991.910080899173;9090.363674542785;9093.363464557484;9385.812283754287;5316.20256961455;5517.227588137664;5517.420670829585;5523.4200408956885;5526.889462210734;3579.49887015818;3611.0791748104066;3655.1044993976648;3714.851405943759;3727.962720372816;1302.7459645369156;1303.4134639412207;1309.5416604188554;1335.7462082204447;1339.0894191949471;13444.058915875892;13579.388927498287;13592.864071359358;13597.728045439135;13641.317934103256;4467.061916997402;4724.905501889943;4772.164871147605;4810.158222311152;4818.855434336975;10605.946970265253;10610.363378197333;10614.044735973644;10614.25700200994;10624.150067994697;5428.4028756836715;5460.863478412992;5464.3442786865835;5465.07093794056;5467.152591348347;4409.559044095629;4835.927461088087;4937.185364414831;4987.700737955734;5116.872078198;4060.817263223162;4086.346184610448;4195.098053918422;4215.873523794291;4215.978920105441;1217.8234156047502;1221.6579357779835;1240.8945239654731;1246.1277106025818;1246.1463897230426;964.6334392930781;980.54404701814;983.7786498037884;988.8628077710547;993.2302465589082;6179.412955769195;6189.50483961282;6289.937100628935;6321.051842223703;6329.240491141092;915.949622770754;989.3420875117966;994.5126887824914;1018.5314755212537;1040.6825918094903;4054.8580799671868;4077.2864748668862;4081.97959010209;4172.311568591149;4204.285271503852;12012.63962081139;12237.93881030607;12399.194052386567;12435.813462798065;12507.3746312684;12198.939005305094;12217.205881617667;12236.877631223751;12252.877471225174;12275.140740148283;8866.64533418656;8895.154960278764;9114.81770364589;9128.178463938277;9134.543272836332;6199.845003874939;6220.284667263322;6311.495080393649;6313.936860631335;6359.841003974938;11083.168762342695;11160;11228.88771112311;11238.10095192399;11264.774704506028;10466.110380617429;10754.354738715701;10802.837957430487;10876.673699789018;11139.554417823521;7765.262300081483;8173.632186551738;8278.793030174053;8286;8523.275521580736;9500.95249523748;9531;9565.665201717906;9574.856377154487;9579.664711735335;8338.87491687613;8379.413441059067;8389.538575378412;8434.409591328547;8660.523671197892;8158.8776168356335;8175.591220438835;8193.46742461738;8216.26053655174;8416.2004609562;7286.271372862883;7331.083614548182;7342.632868356454;7342.8898566520475;7353.77938661841;5246.685198888079;5339.919901201408;5342.919856202082;5369.946300536944;5383.892322153613;5529.917051244154;5552.222688823649;5607.067313674256;5625.437456254342;5643.492085712305 14:42:12 INFO - PROCESS | 31892 | |4;prototype.html;339.67560979264806;347.64887463661694;348.8273304714166;354.8633775996241;358.8510768031269;5483.314585676791;5561.221428999943;5583.609147359686;5587.653185662021;5599.7480113394895;5483.232347471358;5486;5488.396276409601;5502.147167189082;5524.613277070606;3926.82329295181;4181.163767246555;4211.136716973028;4335.891602709926;4369.300911854104;4342.218400687875;4343.109662519192;4346.978265108686;4360.25875601147;4391.165678521087;4359;4361.825526978933;4362.105768317488;4367.628751556122;4396.758178300192;4175.4363160973335;4250.936235956463;4303.913921721548;4355.368471571636;4401.647868170555;3852.9422058669143;3854.4411060396087;3857.324968130566;3860.7104467164795;3871.2064026874423;5396;5423.620346575742;5428.321459817523;5431.402545719968;5447.645903016291;4800;4964.156093464084;4968.180250258703;4997.5002499749735;4998.850034498971;5689.146628005832;5709.971450142764;5723.856903577403;5723.971380143114;5724.313082430093;4050.756954582705;4203.516595591485;4204.915901681979;4253.340732186516;4254.765987870666;5626.240457538221;5792.275965504312;5970.552208584373;5979.402059794029;5991.790287339966;4255.680823938217;4257.3613957905945;4258.212230737324;4317.805698743564;4323.135372925428;4299.742015479082;4330.2638551446325;4369.69412141147;4372.584604462571;4382.517923028464;4772.5227477252;4856.465788763233;4917.459079501252;4923.030787684955;4954.826581069645;5719.799807006735;5738.221151443066;5746.9425305747245;5787.160861675034;5793.768249270066;1485.2202593638426;1500.9624759381102;1504.7968524249086;1505.5558610210014;1509.1397903195293;928.8188803183315;952.7951490429591;963.6530848894392;980.54404701814;989.4014121456559;5024.045431368028;5032.874178145503;5061.392632884077;5063.443021267658;5093.261477085802;5189.948100518946;5223.425423203445;5245.763940622681;5247.134222853187;5284.630075894651;5036.823711170165;5050.545450909436;5084.872878178076;5271.341082364705;5664.603477756517;3162.826044567525;3172.4131035757996;3174.999875039355;3175.2220705926734;3199.9040028799172;6001.489873360726;6202.255729312421;6231.189945307172;6231.532635052389;6268.435840774352;6022.006368949162;6108.389161083856;6110.877782444326;6110.938890611126;6142.692865356804;6131.846703832351;6140.416660417253;6148.538859585459;6175.8265929473255;6176.934913370993;5846.473817356458;5855.795047173328;5927.4665280125;5936.317323507852;5990.64056156632;5887.852803679943;5897.764089436375;5908.2275317405465;5915.2845674753835;5926.7850090730435;5789.45000224978;5948.53859605296;6047.606905551301;6084.361142080018;6199.659018753831;3850.807459626951;4273.209456250604;4297.462817147856;4308.224519586504;4313.331433627793;5954.439590975922;5995.940040599538;5997.610155339889;6000.459958603572;6020.759169633166;5683.687397193194;5727.198192253171;5782.681952492653;5798.10129429939;5819.679917604572;5771.480566749013;5771.624844385102;5784.623999440023;5787.585324658472;5788.334341550607;5716.885662286814;5723.109829536058;5750.482456578928;5768.884622307614;5785.8553536161935;5837.883242335045;5839.708014599338;5875.823725288248;5882.911756323573;5895.380984996514;5463.918041229305;5683.204351390726;5683.318001839806;5717.112363592797;5726.312842458931 14:42:12 INFO - PROCESS | 31892 | |5;yui.html;829.473284464365;839.2069494327859;891.1801142948486;909.9044600316971;917.1153280009578;17176.39882604109;18259.543511412223;18265.08674566273;18296.90851545742;18378.35675751349;16406.83593164068;16538.58653533659;16587.585310367245;16590.7511387329;16615.08617026063;879.463527248378;897.5960817632059;903.3947255338906;906.4289497616493;908.0783005249676;880.6697488441835;881.788370791012;882.1619461511558;882.4572887674067;882.7704796752827;925.8240934222476;927.6984979881531;928.0209379105041;932.3753085432743;933.5052422216236;918.5269586163131;925.7824411263348;926.2312280806948;926.7497775600584;927.8793756811605;679.0493309366926;679.2290749998738;679.4835924697217;679.7077256779583;680.098868998574;17438.215280312288;17455;17485.213165407586;17486.47540573785;17499.912500437542;15872.589541957057;15955;16537;16710.331586736516;17535.386261480915;13257.403416846266;13506.594802155852;13671.658208544866;13698;13704.683923313332;4044.3933409988736;4071.796410179479;4073.429719839195;4096.139810639769;4221.155768846243;56753.71623141899;65819.67090164518;66420.3357966419;70939;72769.63615181943;12924.741505169844;13054.54309099177;13119.868801312055;13130.671733206555;13137.080404371793;22996.65505017426;23072.653910191362;23077.307680769605;23150.53698926012;23160.18939337115;9372.62509499626;9517.476538790295;9539.379940303857;9561.474118923386;9721.173700235558;4992.625553083533;5241.73791310433;5255.08036093682;5319.760610772524;5331.360236771612;3802.144517483544;3805.82873770681;3819.732618716663;3824.808759562011;3825.8087095645105;657.3032585459429;683.3712984054681;687.6046273392819;691.5435812363917;699.7690762048512;705.8023753349071;787.4251796188814;803.7468197517763;812.5327936436572;821.5768879027306;9937.850932236022;10628.627998020187;10837.512311945982;10966.616168434066;11222.382768947786;667.6728403082552;674.2212744280364;726.8655298769745;782.338923609549;788.8698364769749;4891.088321806151;4924.113659541245;4953.702777833341;5073.949260507347;5125.1287281162295;78451.60774196207;112503;122137.38931305286;131079.34460327637;136483;70169;90253.54873225592;101241.49379253204;103096.48451757693;105054.47472762587;86486.56756716176;93544;95138;95145.52427237958;97582.51208743911;9265.814683706154;9366.250699943852;9807.656732014346;10610.363378197333;10811.243212975016;52303;55465.722671386386;55566.16650750161;56125;56987.71506142443;52414;60514.69742651258;77158;79286.6035669818;79863.20136798557;11205.887941120485;11538.423078846192;12037.939810300892;12039.518419263131;12259.69350766238;12806.231626102464;13491.32543372847;13804.861951380759;13839;14791.408343666133;13156.868431315947;13271.336433178496;13283.203007819175;13635.250061246727;13685.178889266675;13138.408771605109;13472.124311919693;13498.59504214875;13732.382042807898;14026.158430494203;11207.607733729606;11352.091832653203;11353.262037967506;11673.416329183678;11797.35114568707;10660.62687805923;10690.679279621623;10865.619703310345;10983.83524247121;12051.698707532381;10402.583896644352;11116.888831111586;11302.265352752045;11422.485988130387;11619.302841829518 14:42:12 INFO - PROCESS | 31892 | __end_tp_report 14:42:12 INFO - PROCESS | 31892 | __start_cc_report 14:42:12 INFO - PROCESS | 31892 | _x_x_mozilla_cycle_collect,1184 14:42:12 INFO - PROCESS | 31892 | __end_cc_report 14:42:12 INFO - PROCESS | 31892 | __startTimestamp1471902132944__endTimestamp 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | ------- Summary: start ------- 14:42:12 INFO - PROCESS | 31892 | Number of tests: 6 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#0] dojo.html Cycles:165 Average:5660.88 Median:5892.15 stddev:651.70 (11.1%) stddev-sans-first:626.78 14:42:12 INFO - PROCESS | 31892 | Values: 3297.9 3306.2 3314.2 3317.9 3339.9 5576.7 5672.5 5678.1 5681.4 5688.5 5609.9 5674.1 5674.4 5682.3 5685.9 5005.6 5130.6 5139.3 5184.7 5208.9 5064.2 5089.3 5122.7 5161.3 5194.5 5072.9 5079.4 5106.1 5122.1 5123.3 5152.9 5171.6 5173.7 5190.9 5194.4 5054.7 5086.1 5087.2 5094.2 5128.0 5608.9 5621.7 5643.7 5650.7 5683.7 6127.6 6161.7 6164.9 6165.1 6166.5 5939.2 6052.6 6063.5 6099.5 6181.7 5752.3 5765.4 5767.8 5776.9 5779.0 6236.3 6278.2 6288.9 6299.1 6299.5 5796.3 5869.2 5956.0 5956.8 6028.1 6090.6 6091.6 6093.7 6093.7 6095.4 6058.5 6060.1 6061.5 6063.5 6091.7 5905.6 5946.1 5971.3 6031.1 6058.3 4621.4 4655.9 4673.6 4675.2 4695.1 4158.4 4164.8 4170.0 4180.8 4194.3 5623.2 5747.1 5815.9 5826.1 5908.2 6208.8 6211.3 6219.6 6222.6 6224.0 5882.8 5891.7 5897.9 5899.5 5920.6 5151.6 5182.7 5204.7 5231.6 5349.7 6252.6 6297.7 6303.8 6311.3 6323.4 6069.9 6085.5 6085.8 6088.2 6089.1 6134.3 6227.1 6249.6 6325.2 6367.4 6112.4 6118.7 6125.7 6126.0 6132.3 6205.6 6223.1 6229.7 6231.0 6235.5 5776.7 5876.7 5892.1 5928.1 6082.3 5901.7 5944.5 5949.8 5960.4 5980.0 5887.4 5930.9 5945.9 5947.6 5951.8 5884.9 5885.2 5941.2 5959.6 6008.5 5867.9 5876.6 5880.6 5881.6 5911.2 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#1] ext.html Cycles:180 Average:68012.82 Median:224939.59 stddev:140290.61 (62.4%) stddev-sans-first:140592.87 14:42:12 INFO - PROCESS | 31892 | Values: 603.9 638.3 648.9 660.3 661.4 35442.1 46444.0 47630.5 47659.8 47776.8 47943.8 48289.1 48428.3 48432.3 48481.0 8489.6 9127.2 9276.4 9283.2 10854.2 10741.6 11190.9 11223.2 11226.6 11227.1 11290.4 11309.8 11318.8 11326.9 11334.6 11161.3 11190.6 11233.6 11241.1 11251.8 10981.7 11042.6 11044.6 11045.2 11059.9 44203.3 44225.1 44382.6 44568.3 44608.0 3856.1 4257.4 4443.5 4553.2 4577.7 35215.6 35422.8 35503.8 36044.8 37660.8 4560.5 4572.8 4600.9 4624.7 4642.8 407011.0 408965.0 409159.0 409642.0 410257.0 63567.4 63743.4 63949.4 63965.7 64056.0 38771.2 39282.4 39533.6 42155.4 42174.0 19433.6 19605.8 19639.8 19643.6 19647.4 12497.1 13759.1 14153.5 14355.0 14426.8 20496.0 20502.1 20571.6 20584.0 20594.9 7183.6 7315.5 7412.6 7432.9 7542.7 1062.9 1076.0 1106.0 1112.1 1153.8 34403.8 34720.3 35192.0 35313.0 35382.6 1155.0 1194.6 1199.3 1232.9 1238.0 17690.3 18347.5 18398.3 18417.8 18456.0 668120.0 672348.0 673646.0 677938.0 681146.0 62901.4 63627.0 63806.4 63806.8 63984.0 1319.2 1354.7 1428.2 1462.5 1494.3 30961.5 36474.6 36555.3 36619.3 36691.4 55137.7 55590.4 55649.2 55686.2 55695.4 456220.0 459886.0 460376.0 460429.0 460851.0 29988.0 30771.2 30899.8 31310.7 33046.5 52621.7 52728.7 53208.7 53345.5 53402.0 42752.0 44223.3 44624.8 45123.5 46170.8 45380.8 45894.8 46555.5 47102.0 47297.3 42776.8 43884.8 44289.6 44881.1 48079.0 37586.4 39685.8 40785.8 40914.2 41005.0 29788.0 30463.4 30804.5 30820.2 31269.0 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#2] jquery.html Cycles:180 Average:31515.94 Median:11657.21 stddev:93074.87 (798.4%) stddev-sans-first:93306.62 14:42:12 INFO - PROCESS | 31892 | Values: 387.6 403.1 410.4 417.0 428.9 16468.2 19929.0 20417.9 20684.0 22206.7 19664.4 19916.6 19972.7 20065.9 20312.4 5854.7 5880.9 6004.3 6050.8 6065.5 4415.2 6012.4 6014.2 6427.0 6782.9 6769.0 6811.4 6822.7 6822.7 6837.2 5598.6 5837.4 5850.6 5918.7 5919.3 5597.1 5730.7 5753.5 5765.7 5775.9 13544.8 13598.4 13760.9 13790.8 13814.4 2484.1 2511.1 2844.3 2857.9 2939.2 10045.0 10360.5 10562.3 10609.4 14797.2 2015.3 2030.9 2039.2 2039.8 2066.0 79243.2 79834.0 84086.6 84479.6 92324.5 9962.8 10216.2 10306.7 10507.7 10530.6 14229.4 14278.6 14288.6 14304.1 14306.7 8607.5 8654.9 8666.4 8736.3 8896.7 8170.6 8207.5 8826.7 8934.7 9315.1 4125.3 4267.3 4267.7 4359.2 4496.2 1991.9 2044.8 2082.4 2099.1 2138.5 940.2 959.4 978.8 1032.5 1062.4 20603.5 20685.9 20807.0 21163.1 21175.9 995.2 1004.8 1025.1 1040.4 1072.5 10789.7 11585.5 11752.8 11802.2 11837.2 533197.0 565192.0 571579.0 576131.0 579441.0 58482.7 99835.5 101061.5 102674.0 103077.0 1000.2 1001.1 1010.4 1035.7 1041.9 18149.6 18541.8 18929.3 18971.6 19109.4 46456.5 48158.5 48443.8 48991.8 49114.8 61379.7 66238.0 66920.3 67081.0 67987.3 8109.6 8149.3 8271.4 8411.7 8443.3 10729.4 12983.5 13000.0 13441.4 13470.6 10831.3 11486.1 11507.2 11635.5 11739.4 11541.2 11816.2 11856.8 11870.1 12056.5 11345.2 11455.3 11753.3 11787.7 11934.8 7478.2 7755.7 7867.6 8217.1 9385.1 10981.0 11201.8 11390.4 11445.9 11470.3 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#3] mootools.html Cycles:180 Average:6215.80 Median:4791.16 stddev:3636.15 (75.9%) stddev-sans-first:3628.11 14:42:12 INFO - PROCESS | 31892 | Values: 1369.5 1526.3 1595.4 1778.4 1907.1 8270.3 9160.5 9528.2 9542.2 9808.8 9564.2 9644.5 9648.2 9662.2 9665.2 2436.1 2467.5 2473.1 2474.8 2475.1 2391.8 2476.8 2477.8 2489.1 2490.0 2412.8 2433.6 2481.9 2484.0 2486.7 2382.6 2412.6 2420.1 2423.1 2426.3 2358.5 2366.8 2385.2 2395.1 2395.8 8832.7 8991.9 9090.4 9093.4 9385.8 5316.2 5517.2 5517.4 5523.4 5526.9 3579.5 3611.1 3655.1 3714.9 3728.0 1302.7 1303.4 1309.5 1335.7 1339.1 13444.1 13579.4 13592.9 13597.7 13641.3 4467.1 4724.9 4772.2 4810.2 4818.9 10605.9 10610.4 10614.0 10614.3 10624.2 5428.4 5460.9 5464.3 5465.1 5467.2 4409.6 4835.9 4937.2 4987.7 5116.9 4060.8 4086.3 4195.1 4215.9 4216.0 1217.8 1221.7 1240.9 1246.1 1246.1 964.6 980.5 983.8 988.9 993.2 6179.4 6189.5 6289.9 6321.1 6329.2 915.9 989.3 994.5 1018.5 1040.7 4054.9 4077.3 4082.0 4172.3 4204.3 12012.6 12237.9 12399.2 12435.8 12507.4 12198.9 12217.2 12236.9 12252.9 12275.1 8866.6 8895.2 9114.8 9128.2 9134.5 6199.8 6220.3 6311.5 6313.9 6359.8 11083.2 11160.0 11228.9 11238.1 11264.8 10466.1 10754.4 10802.8 10876.7 11139.6 7765.3 8173.6 8278.8 8286.0 8523.3 9501.0 9531.0 9565.7 9574.9 9579.7 8338.9 8379.4 8389.5 8434.4 8660.5 8158.9 8175.6 8193.5 8216.3 8416.2 7286.3 7331.1 7342.6 7342.9 7353.8 5246.7 5339.9 5342.9 5369.9 5383.9 5529.9 5552.2 5607.1 5625.4 5643.5 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#4] prototype.html Cycles:180 Average:4843.58 Median:5483.27 stddev:1413.51 (25.8%) stddev-sans-first:1376.46 14:42:12 INFO - PROCESS | 31892 | Values: 339.7 347.6 348.8 354.9 358.9 5483.3 5561.2 5583.6 5587.7 5599.7 5483.2 5486.0 5488.4 5502.1 5524.6 3926.8 4181.2 4211.1 4335.9 4369.3 4342.2 4343.1 4347.0 4360.3 4391.2 4359.0 4361.8 4362.1 4367.6 4396.8 4175.4 4250.9 4303.9 4355.4 4401.6 3852.9 3854.4 3857.3 3860.7 3871.2 5396.0 5423.6 5428.3 5431.4 5447.6 4800.0 4964.2 4968.2 4997.5 4998.9 5689.1 5710.0 5723.9 5724.0 5724.3 4050.8 4203.5 4204.9 4253.3 4254.8 5626.2 5792.3 5970.6 5979.4 5991.8 4255.7 4257.4 4258.2 4317.8 4323.1 4299.7 4330.3 4369.7 4372.6 4382.5 4772.5 4856.5 4917.5 4923.0 4954.8 5719.8 5738.2 5746.9 5787.2 5793.8 1485.2 1501.0 1504.8 1505.6 1509.1 928.8 952.8 963.7 980.5 989.4 5024.0 5032.9 5061.4 5063.4 5093.3 5189.9 5223.4 5245.8 5247.1 5284.6 5036.8 5050.5 5084.9 5271.3 5664.6 3162.8 3172.4 3175.0 3175.2 3199.9 6001.5 6202.3 6231.2 6231.5 6268.4 6022.0 6108.4 6110.9 6110.9 6142.7 6131.8 6140.4 6148.5 6175.8 6176.9 5846.5 5855.8 5927.5 5936.3 5990.6 5887.9 5897.8 5908.2 5915.3 5926.8 5789.5 5948.5 6047.6 6084.4 6199.7 3850.8 4273.2 4297.5 4308.2 4313.3 5954.4 5995.9 5997.6 6000.5 6020.8 5683.7 5727.2 5782.7 5798.1 5819.7 5771.5 5771.6 5784.6 5787.6 5788.3 5716.9 5723.1 5750.5 5768.9 5785.9 5837.9 5839.7 5875.8 5882.9 5895.4 5463.9 5683.2 5683.3 5717.1 5726.3 14:42:12 INFO - PROCESS | 31892 | 14:42:12 INFO - PROCESS | 31892 | [#5] yui.html Cycles:180 Average:20991.84 Median:4084.78 stddev:29837.35 (730.5%) stddev-sans-first:29882.64 14:42:12 INFO - PROCESS | 31892 | Values: 829.5 839.2 891.2 909.9 917.1 17176.4 18259.5 18265.1 18296.9 18378.4 16406.8 16538.6 16587.6 16590.8 16615.1 879.5 897.6 903.4 906.4 908.1 880.7 881.8 882.2 882.5 882.8 925.8 927.7 928.0 932.4 933.5 918.5 925.8 926.2 926.7 927.9 679.0 679.2 679.5 679.7 680.1 17438.2 17455.0 17485.2 17486.5 17499.9 15872.6 15955.0 16537.0 16710.3 17535.4 13257.4 13506.6 13671.7 13698.0 13704.7 4044.4 4071.8 4073.4 4096.1 4221.2 56753.7 65819.7 66420.3 70939.0 72769.6 12924.7 13054.5 13119.9 13130.7 13137.1 22996.7 23072.7 23077.3 23150.5 23160.2 9372.6 9517.5 9539.4 9561.5 9721.2 4992.6 5241.7 5255.1 5319.8 5331.4 3802.1 3805.8 3819.7 3824.8 3825.8 657.3 683.4 687.6 691.5 699.8 705.8 787.4 803.7 812.5 821.6 9937.9 10628.6 10837.5 10966.6 11222.4 667.7 674.2 726.9 782.3 788.9 4891.1 4924.1 4953.7 5073.9 5125.1 78451.6 112503.0 122137.4 131079.3 136483.0 70169.0 90253.5 101241.5 103096.5 105054.5 86486.6 93544.0 95138.0 95145.5 97582.5 9265.8 9366.3 9807.7 10610.4 10811.2 52303.0 55465.7 55566.2 56125.0 56987.7 52414.0 60514.7 77158.0 79286.6 79863.2 11205.9 11538.4 12037.9 12039.5 12259.7 12806.2 13491.3 13804.9 13839.0 14791.4 13156.9 13271.3 13283.2 13635.3 13685.2 13138.4 13472.1 13498.6 13732.4 14026.2 11207.6 11352.1 11353.3 11673.4 11797.4 10660.6 10690.7 10865.6 10983.8 12051.7 10402.6 11116.9 11302.3 11422.5 11619.3 14:42:12 INFO - PROCESS | 31892 | -------- Summary: end -------- 14:42:12 INFO - PROCESS | 31892 | 14:42:13 INFO - TEST-INFO | 31892: exit 0 14:42:13 INFO - TEST-OK | dromaeo_css | took 1114228ms 14:42:13 INFO - TEST-START | kraken 14:42:13 INFO - operating with platform_type : linux_ 14:42:13 INFO - Initialising browser for kraken test... 14:42:13 INFO - TEST-INFO | started process 32097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFPc_rg/profile http://localhost:46127/getInfo.html) 14:42:17 INFO - PROCESS | 32097 | __metrics Screen width/height:1600/1200 14:42:17 INFO - PROCESS | 32097 | colorDepth:24 14:42:17 INFO - PROCESS | 32097 | Browser inner width/height: 1024/697 14:42:17 INFO - PROCESS | 32097 | __metrics 14:42:18 INFO - PROCESS | 32097 | 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" 14:42:18 INFO - PROCESS | 32097 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:42:19 INFO - TEST-INFO | 32097: exit 0 14:42:19 INFO - Browser initialized. 14:42:19 INFO - Running cycle 1/1 for kraken test... 14:42:19 INFO - TEST-INFO | started process 32185 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFPc_rg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:42:31 INFO - PROCESS | 32185 | RSS: Main: 145661952 14:42:31 INFO - PROCESS | 32185 | 14:42:34 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46127/tests/kraken/driver.html?testName=audio-beat-detection) 14:42:34 INFO - PROCESS | 32185 | RSS: Main: 192327680 14:42:34 INFO - PROCESS | 32185 | 14:42:37 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46127/tests/kraken/driver.html?testName=audio-dft) 14:42:38 INFO - PROCESS | 32185 | RSS: Main: 181809152 14:42:38 INFO - PROCESS | 32185 | 14:42:40 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46127/tests/kraken/driver.html?testName=audio-fft) 14:42:41 INFO - PROCESS | 32185 | RSS: Main: 269070336 14:42:41 INFO - PROCESS | 32185 | 14:42:43 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46127/tests/kraken/driver.html?testName=audio-oscillator) 14:42:44 INFO - PROCESS | 32185 | RSS: Main: 175509504 14:42:44 INFO - PROCESS | 32185 | 14:42:46 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46127/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:46 INFO - PROCESS | 32185 | RSS: Main: 177352704 14:42:46 INFO - PROCESS | 32185 | 14:42:49 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46127/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:50 INFO - PROCESS | 32185 | RSS: Main: 213348352 14:42:50 INFO - PROCESS | 32185 | 14:42:53 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46127/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:54 INFO - PROCESS | 32185 | RSS: Main: 211984384 14:42:54 INFO - PROCESS | 32185 | 14:42:57 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46127/tests/kraken/driver.html?testName=json-parse-financial) 14:42:57 INFO - PROCESS | 32185 | RSS: Main: 212045824 14:42:57 INFO - PROCESS | 32185 | 14:42:59 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46127/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:00 INFO - PROCESS | 32185 | RSS: Main: 176189440 14:43:00 INFO - PROCESS | 32185 | 14:43:01 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:02 INFO - PROCESS | 32185 | RSS: Main: 181391360 14:43:02 INFO - PROCESS | 32185 | 14:43:04 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:04 INFO - PROCESS | 32185 | RSS: Main: 186748928 14:43:04 INFO - PROCESS | 32185 | 14:43:07 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:08 INFO - PROCESS | 32185 | RSS: Main: 180719616 14:43:08 INFO - PROCESS | 32185 | 14:43:10 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:11 INFO - PROCESS | 32185 | RSS: Main: 184836096 14:43:11 INFO - PROCESS | 32185 | 14:43:12 INFO - PROCESS | 32185 | Cycle 1(1): loaded http://localhost:46127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46127/tests/kraken/driver.html?testName=ai-astar) 14:43:13 INFO - PROCESS | 32185 | RSS: Main: 235393024 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | __start_tp_report 14:43:13 INFO - PROCESS | 32185 | _x_x_mozilla_page_load 14:43:13 INFO - PROCESS | 32185 | _x_x_mozilla_page_load_details 14:43:13 INFO - PROCESS | 32185 | |i|pagename|runs| 14:43:13 INFO - PROCESS | 32185 | |0;ai-astar;101;111;110;111;112;113;111;109;112;109 14:43:13 INFO - PROCESS | 32185 | |1;audio-beat-detection;147;116;121;169;124;120;146;118;147;119 14:43:13 INFO - PROCESS | 32185 | |2;audio-dft;159;159;161;159;160;160;159;160;160;159 14:43:13 INFO - PROCESS | 32185 | |3;audio-fft;74;75;74;112;75;75;74;84;102;75 14:43:13 INFO - PROCESS | 32185 | |4;audio-oscillator;83;84;128;84;84;83;130;83;84;125 14:43:13 INFO - PROCESS | 32185 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 14:43:13 INFO - PROCESS | 32185 | |6;imaging-darkroom;146;146;146;146;147;146;146;146;147;148 14:43:13 INFO - PROCESS | 32185 | |7;imaging-desaturate;94;94;95;95;95;96;95;96;95;94 14:43:13 INFO - PROCESS | 32185 | |8;json-parse-financial;51;50;49;50;56;51;51;51;51;50 14:43:13 INFO - PROCESS | 32185 | |9;json-stringify-tinderbox;60;60;58;61;59;59;59;58;59;58 14:43:13 INFO - PROCESS | 32185 | |10;stanford-crypto-aes;73;78;73;73;72;72;71;72;72;76 14:43:13 INFO - PROCESS | 32185 | |11;stanford-crypto-ccm;129;134;120;120;116;120;119;114;163;133 14:43:13 INFO - PROCESS | 32185 | |12;stanford-crypto-pbkdf2;154;152;151;157;152;155;156;157;154;158 14:43:13 INFO - PROCESS | 32185 | |13;stanford-crypto-sha256-iterative;61;60;63;61;61;64;60;70;61;58 14:43:13 INFO - PROCESS | 32185 | __end_tp_report 14:43:13 INFO - PROCESS | 32185 | __start_cc_report 14:43:13 INFO - PROCESS | 32185 | _x_x_mozilla_cycle_collect,1662 14:43:13 INFO - PROCESS | 32185 | __end_cc_report 14:43:13 INFO - PROCESS | 32185 | __startTimestamp1471902193206__endTimestamp 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | ------- Summary: start ------- 14:43:13 INFO - PROCESS | 32185 | Number of tests: 14 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.38 (3.0%) stddev-sans-first:1.36 14:43:13 INFO - PROCESS | 32185 | Values: 101.0 111.0 110.0 111.0 112.0 113.0 111.0 109.0 112.0 109.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:135.00 stddev:18.14 (13.4%) stddev-sans-first:18.48 14:43:13 INFO - PROCESS | 32185 | Values: 147.0 116.0 121.0 169.0 124.0 120.0 146.0 118.0 147.0 119.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#2] audio-dft Cycles:10 Average:159.60 Median:160.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 14:43:13 INFO - PROCESS | 32185 | Values: 159.0 159.0 161.0 159.0 160.0 160.0 159.0 160.0 160.0 159.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:13.71 (18.3%) stddev-sans-first:14.23 14:43:13 INFO - PROCESS | 32185 | Values: 74.0 75.0 74.0 112.0 75.0 75.0 74.0 84.0 102.0 75.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:83.50 stddev:21.34 (25.6%) stddev-sans-first:22.04 14:43:13 INFO - PROCESS | 32185 | Values: 83.0 84.0 128.0 84.0 84.0 83.0 130.0 83.0 84.0 125.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:43:13 INFO - PROCESS | 32185 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:43:13 INFO - PROCESS | 32185 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 148.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 14:43:13 INFO - PROCESS | 32185 | Values: 94.0 94.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 94.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.89 (3.7%) stddev-sans-first:2.00 14:43:13 INFO - PROCESS | 32185 | Values: 51.0 50.0 49.0 50.0 56.0 51.0 51.0 51.0 51.0 50.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.99 (1.7%) stddev-sans-first:1.00 14:43:13 INFO - PROCESS | 32185 | Values: 60.0 60.0 58.0 61.0 59.0 59.0 59.0 58.0 59.0 58.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:2.15 (2.9%) stddev-sans-first:2.28 14:43:13 INFO - PROCESS | 32185 | Values: 73.0 78.0 73.0 73.0 72.0 72.0 71.0 72.0 72.0 76.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:124.50 stddev:14.47 (11.6%) stddev-sans-first:15.33 14:43:13 INFO - PROCESS | 32185 | Values: 129.0 134.0 120.0 120.0 116.0 120.0 119.0 114.0 163.0 133.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.50 stddev:2.41 (1.6%) stddev-sans-first:2.55 14:43:13 INFO - PROCESS | 32185 | Values: 154.0 152.0 151.0 157.0 152.0 155.0 156.0 157.0 154.0 158.0 14:43:13 INFO - PROCESS | 32185 | 14:43:13 INFO - PROCESS | 32185 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:61.00 stddev:3.28 (5.4%) stddev-sans-first:3.46 14:43:13 INFO - PROCESS | 32185 | Values: 61.0 60.0 63.0 61.0 61.0 64.0 60.0 70.0 61.0 58.0 14:43:13 INFO - PROCESS | 32185 | -------- Summary: end -------- 14:43:13 INFO - PROCESS | 32185 | 14:43:14 INFO - TEST-INFO | 32185: exit 0 14:43:14 INFO - TEST-OK | kraken | took 60539ms 14:43:14 INFO - SUITE-END | took 1174s 14:43:14 INFO - Completed test suite (00:19:34) 14:43:14 INFO - javascript benchmark 14:43:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.934041319172, 3306.1569299828548, 3314.171457135716, 3317.8838740644082, 3339.9165020874475, 5576.693281869496, 5672.546196304296, 5678.063119585274, 5681.431856814316, 5688.459596338346, 5609.915851262234, 5674.148877668352, 5674.375818659944, 5682.317682317683, 5685.914711279334, 5005.599552035828, 5130.640855140142, 5139.331886854713, 5184.662996905189, 5208.947910520903, 5064.24036394542, 5089.3129427527365, 5122.743862806863, 5161.30322406476, 5194.480551944813, 5072.9492705073035, 5079.441261461255, 5106.055379754739, 5122.078025955345, 5123.257127716479, 5152.9484705152845, 5171.637985341028, 5173.741312934339, 5190.92213616796, 5194.376674799029, 5054.69671819692, 5086.109930762102, 5087.211482220262, 5094.2103973884105, 5128.0, 5608.859778505529, 5621.662700237999, 5643.661380317153, 5650.6892120933335, 5683.715814209314, 6127.601705889103, 6161.661108639023, 6164.8767024659255, 6165.075238714184, 6166.475849552793, 5939.168516407706, 6052.563567324724, 6063.514918806485, 6099.48154406876, 6181.721822517952, 5752.28096487939, 5765.423457654243, 5767.826965191009, 5776.913346299809, 5779.0, 6236.251649802053, 6278.183836101278, 6288.874222515524, 6299.055141728686, 6299.5275354348605, 5796.304443466811, 5869.20765696634, 5956.0, 5956.821295361146, 6028.09578563219, 6090.573659843857, 6091.568092356343, 6093.658089151685, 6093.659031762139, 6095.384723048396, 6058.515318774487, 6060.090986351995, 6061.54538409621, 6063.454289114001, 6091.66495842724, 5905.586608937333, 5946.07835785454, 5971.253593300838, 6031.095335699592, 6058.303295121117, 4621.353010578522, 4655.86032419028, 4673.626109911199, 4675.158471475167, 4695.060987802453, 4158.397032430281, 4164.8125834337525, 4170.04089059518, 4180.770057646798, 4194.3498757692605, 5623.184638227434, 5747.109198074305, 5815.88368232633, 5826.126081087871, 5908.202392676933, 6208.751649934043, 6211.2857021442205, 6219.564630475823, 6222.595531290543, 6223.96888015557, 5882.764689412461, 5891.675957822361, 5897.941020589825, 5899.528037756969, 5920.644761314335, 5151.562117220078, 5182.714950677673, 5204.71374074422, 5231.633785635046, 5349.732513374316, 6252.5935814171935, 6297.716602752796, 6303.842403939938, 6311.305756366796, 6323.36766323364, 6069.939300606938, 6085.482733967662, 6085.756569737338, 6088.208532890696, 6089.117078023751, 6134.294556126102, 6227.1282020516255, 6249.562530622813, 6325.209348831396, 6367.395097465734, 6112.381899552462, 6118.741008805849, 6125.7238540429535, 6126.029144987285, 6132.3017872136115, 6205.627662340274, 6223.128761973237, 6229.688515574294, 6231.034189700604, 6235.469985051322, 5776.740046697824, 5876.676782776988, 5892.145638882253, 5928.140419639214, 6082.2701275847185, 5901.704914754331, 5944.494717949023, 5949.762009519571, 5960.374160713236, 5979.970100149471, 5887.352391236875, 5930.881382372242, 5945.851353716192, 5947.583669143291, 5951.7619295227705, 5884.882302354014, 5885.176075349369, 5941.2276404068925, 5959.553033522417, 6008.459238668541, 5867.9413205867395, 5876.6180198286975, 5880.588358814842, 5881.5588830838715, 5911.201987731602], "value": 5616.534114737549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.8641305706217, 638.256431257585, 648.8702259548089, 660.3264670036563, 661.4146480364876, 35442.1139471513, 46444.0, 47630.523694763084, 47659.76170119145, 47776.76111619437, 47943.76028119854, 48289.1026538408, 48428.2735758963, 48432.2735158972, 48481.0, 8489.61796719146, 9127.224185944202, 9276.397034192756, 9283.210927071206, 10854.24020318578, 10741.570337186504, 11190.944045279803, 11223.214374993753, 11226.550937962471, 11227.101831853473, 11290.379029153435, 11309.830352544719, 11318.773624527505, 11326.886731132665, 11334.603288884891, 11161.274517156358, 11190.552377904874, 11233.550657973672, 11241.100711943025, 11251.774964500746, 10981.725456863562, 11042.613508527164, 11044.613438529694, 11045.171612129123, 11059.944700276528, 44203.33694994578, 44225.11549768987, 44382.55617443816, 44568.3314750279, 44608.0, 3856.093817952779, 4257.446531950826, 4443.511213766483, 4553.248713962193, 4577.725336479822, 35215.647843521496, 35422.82288588566, 35503.82248088743, 36044.81977590122, 37660.811695941346, 4560.498345182027, 4572.771361431948, 4600.861974140781, 4624.745638989854, 4642.837500687494, 407011.0, 408965.0, 409159.0, 409641.99999999703, 410257.0, 63567.364326356146, 63743.36256637374, 63949.36050639527, 63965.68017159885, 64056.0, 38771.224575508335, 39282.41076383857, 39533.60466395357, 42155.367669484986, 42174.0, 19433.611327773648, 19605.803941960683, 19639.803601964082, 19643.60712785765, 19647.41057768269, 12497.062720296015, 13759.10565813239, 14153.504627337994, 14355.0, 14426.783598246035, 20496.0, 20502.077406516742, 20571.58856822855, 20584.0, 20594.897025515078, 7183.56898586076, 7315.487915845946, 7412.592307423143, 7432.925670743223, 7542.681220263066, 1062.8671416072991, 1075.9677209683723, 1105.9778804423865, 1112.093643680398, 1153.8326942593278, 34403.82798085993, 34720.305593887984, 35192.0, 35313.0, 35382.64617353845, 1154.9538018479334, 1194.5699548162654, 1199.3463562358536, 1232.9321887296285, 1237.9972222499805, 17690.29238830458, 18347.54131146732, 18398.26406943734, 18417.81582184188, 18456.0, 668120.0, 672348.0, 673646.0, 677938.0, 681146.0, 62901.370986290465, 63627.0, 63806.36193638004, 63806.76676316399, 63984.0, 1319.2480286236753, 1354.7290541891662, 1428.221619217508, 1462.531989763287, 1494.260341131147, 30961.535576965915, 36474.635253647124, 36555.26889462249, 36619.26761464809, 36691.44962825613, 55137.72431137819, 55590.444095560146, 55649.16526252191, 55686.16470753023, 55695.44304556902, 456220.0, 459886.0, 460376.0, 460429.0, 460851.0, 29988.0, 30771.230719232197, 30899.845500772353, 31310.686893130776, 33046.50430243596, 52621.73689131683, 52728.736356317975, 53208.73395633152, 53345.46654533405, 53402.0, 42752.0, 44223.33664995092, 44624.776876115415, 45123.548764511936, 46170.76914615405, 45380.77309613563, 45894.77052614716, 46555.53444465512, 47102.0, 47297.29054064261, 42776.78611606922, 43884.78057609691, 44289.557104428546, 44881.10237795291, 48079.0, 37586.436203456426, 39685.801570991964, 40785.79607101945, 40914.18171636491, 41005.0, 29788.0, 30463.390732185675, 30804.53793193149, 30820.22949426282, 31269.0], "value": 22095.84822499158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.6007712056579, 403.0849970566812, 410.3803257081806, 416.95413504514505, 428.9249381358263, 16468.17659117044, 19929.0, 20417.897910510426, 20683.96580170991, 22206.66689999647, 19664.41006769799, 19916.60166796663, 19972.70040949383, 20065.899670501665, 20312.390628281137, 5854.677992710399, 5880.882382352372, 6004.279486461631, 6050.788222412217, 6065.514758819284, 4415.161119387322, 6012.428819262163, 6014.218151640281, 6426.967865160667, 6782.932170678279, 6769.0, 6811.421029212523, 6822.6588670566525, 6822.692978815965, 6837.247902730695, 5598.552115830744, 5837.387074357195, 5850.590458667895, 5918.674472903988, 5919.319278283009, 5597.104463285896, 5730.68481233532, 5753.539716822686, 5765.711714414305, 5775.855603609902, 13544.796828047587, 13598.38807253676, 13760.862391376058, 13790.793138102936, 13814.447422103103, 2484.080890070685, 2511.0583531175807, 2844.2604922720243, 2857.9428411431863, 2939.1623387334507, 10045.0, 10360.48197590125, 10562.31344962575, 10609.363438193734, 14797.186154761484, 2015.284573976232, 2030.9086091125935, 2039.2047101630376, 2039.7858224886322, 2065.9690104648444, 79243.20756792415, 79834.0, 84086.57956710238, 84479.57760211221, 92324.53837730836, 9962.750931226778, 10216.233782466345, 10306.742331441625, 10507.684769456928, 10530.63142790014, 14229.430822766974, 14278.571642850731, 14288.642783930278, 14304.141751494735, 14306.713865722835, 8607.483550986835, 8654.87017694735, 8666.436681615802, 8736.344774141964, 8896.665500174897, 8170.591470426455, 8207.507549547046, 8826.691065812665, 8934.687285944961, 9315.068493150766, 4125.29869922114, 4267.317229243306, 4267.701260911706, 4359.17175736609, 4496.235639941217, 1991.9004049797452, 2044.826189773886, 2082.37528741377, 2099.086897199723, 2138.518833262535, 940.2008292951074, 959.3524371049514, 978.8189185000798, 1032.509557959963, 1062.436908438529, 20603.4849128773, 20685.896570517052, 20807.0, 21163.114384636956, 21175.894120529607, 995.1640621877658, 1004.8492726091145, 1025.0876719719456, 1040.4225654761528, 1072.5012869015945, 10789.730256743644, 11585.478653460614, 11752.823707644393, 11802.232854864405, 11837.171398002058, 533197.0, 565192.0, 571579.0, 576131.0, 579441.0, 58482.707586461795, 99835.50082249688, 101061.49469252754, 102674.0, 103077.0, 1000.2348203624233, 1001.0940099210227, 1010.4291075542295, 1035.7410647338165, 1041.859348987877, 18149.637007260044, 18541.814581854007, 18929.33747318837, 18971.620567588296, 19109.426717198505, 46456.53543464522, 48158.5184148154, 48443.757781210865, 48991.75504122457, 49114.75442622764, 61379.693101535995, 66238.0, 66920.33079669141, 67081.0, 67987.3201267981, 8109.553974531457, 8149.266566009088, 8271.421000529905, 8411.747647570583, 8443.324534037385, 10729.356238625707, 12983.480660773841, 13000.024998124989, 13441.395137218653, 13470.595882123553, 10831.313560380862, 11486.081113510732, 11507.194496385173, 11635.47640356203, 11739.354335511629, 11541.249818761753, 11816.172867899508, 11856.76286474283, 11870.050395968474, 12056.51773929033, 11345.205835591429, 11455.255408398427, 11753.294802311888, 11787.705307367385, 11934.761304774029, 7478.214787447458, 7755.689772409266, 7867.567283799446, 8217.137200593852, 9385.108414700866, 10981.0, 11201.831972520256, 11390.37352945596, 11445.885541144482, 11470.254433461798], "value": 9413.72937744507, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.5412036967616, 1526.2521364531376, 1595.4176725495195, 1778.4042345814153, 1907.141786196212, 8270.297024752896, 9160.496172710507, 9528.237740980721, 9542.236621070306, 9808.754781130476, 9564.234861211104, 9644.469554174535, 9648.179904708108, 9662.227021838253, 9665.226781857453, 2436.0621160853066, 2467.4571594249237, 2473.134402958968, 2474.8391354561895, 2475.096589744748, 2391.8086553075714, 2476.7523247675267, 2477.7770000699934, 2489.128804918282, 2490.0164435048146, 2412.8190385721055, 2433.5862903306393, 2481.85108893467, 2484.043643197364, 2486.7264600893886, 2382.618780995041, 2412.5777988851883, 2420.1166574200465, 2423.103451722865, 2426.3206302235403, 2358.481134150484, 2366.7751563601432, 2385.2009576791797, 2395.101836811196, 2395.7843794058617, 8832.690855820016, 8991.910080899173, 9090.363674542785, 9093.363464557484, 9385.812283754287, 5316.20256961455, 5517.227588137664, 5517.420670829585, 5523.4200408956885, 5526.889462210734, 3579.49887015818, 3611.0791748104066, 3655.1044993976648, 3714.851405943759, 3727.962720372816, 1302.7459645369156, 1303.4134639412207, 1309.5416604188554, 1335.7462082204447, 1339.0894191949471, 13444.058915875892, 13579.388927498287, 13592.864071359358, 13597.728045439135, 13641.317934103256, 4467.061916997402, 4724.905501889943, 4772.164871147605, 4810.158222311152, 4818.855434336975, 10605.946970265253, 10610.363378197333, 10614.044735973644, 10614.25700200994, 10624.150067994697, 5428.4028756836715, 5460.863478412992, 5464.3442786865835, 5465.07093794056, 5467.152591348347, 4409.559044095629, 4835.927461088087, 4937.185364414831, 4987.700737955734, 5116.872078198, 4060.817263223162, 4086.346184610448, 4195.098053918422, 4215.873523794291, 4215.978920105441, 1217.8234156047502, 1221.6579357779835, 1240.8945239654731, 1246.1277106025818, 1246.1463897230426, 964.6334392930781, 980.54404701814, 983.7786498037884, 988.8628077710547, 993.2302465589082, 6179.412955769195, 6189.50483961282, 6289.937100628935, 6321.051842223703, 6329.240491141092, 915.949622770754, 989.3420875117966, 994.5126887824914, 1018.5314755212537, 1040.6825918094903, 4054.8580799671868, 4077.2864748668862, 4081.97959010209, 4172.311568591149, 4204.285271503852, 12012.63962081139, 12237.93881030607, 12399.194052386567, 12435.813462798065, 12507.3746312684, 12198.939005305094, 12217.205881617667, 12236.877631223751, 12252.877471225174, 12275.140740148283, 8866.64533418656, 8895.154960278764, 9114.81770364589, 9128.178463938277, 9134.543272836332, 6199.845003874939, 6220.284667263322, 6311.495080393649, 6313.936860631335, 6359.841003974938, 11083.168762342695, 11160.0, 11228.88771112311, 11238.10095192399, 11264.774704506028, 10466.110380617429, 10754.354738715701, 10802.837957430487, 10876.673699789018, 11139.554417823521, 7765.262300081483, 8173.632186551738, 8278.793030174053, 8286.0, 8523.275521580736, 9500.95249523748, 9531.0, 9565.665201717906, 9574.856377154487, 9579.664711735335, 8338.87491687613, 8379.413441059067, 8389.538575378412, 8434.409591328547, 8660.523671197892, 8158.8776168356335, 8175.591220438835, 8193.46742461738, 8216.26053655174, 8416.2004609562, 7286.271372862883, 7331.083614548182, 7342.632868356454, 7342.8898566520475, 7353.77938661841, 5246.685198888079, 5339.919901201408, 5342.919856202082, 5369.946300536944, 5383.892322153613, 5529.917051244154, 5552.222688823649, 5607.067313674256, 5625.437456254342, 5643.492085712305], "value": 4927.813293433856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.67560979264806, 347.64887463661694, 348.8273304714166, 354.8633775996241, 358.8510768031269, 5483.314585676791, 5561.221428999943, 5583.609147359686, 5587.653185662021, 5599.7480113394895, 5483.232347471358, 5486.0, 5488.396276409601, 5502.147167189082, 5524.613277070606, 3926.82329295181, 4181.163767246555, 4211.136716973028, 4335.891602709926, 4369.300911854104, 4342.218400687875, 4343.109662519192, 4346.978265108686, 4360.25875601147, 4391.165678521087, 4359.0, 4361.825526978933, 4362.105768317488, 4367.628751556122, 4396.758178300192, 4175.4363160973335, 4250.936235956463, 4303.913921721548, 4355.368471571636, 4401.647868170555, 3852.9422058669143, 3854.4411060396087, 3857.324968130566, 3860.7104467164795, 3871.2064026874423, 5396.0, 5423.620346575742, 5428.321459817523, 5431.402545719968, 5447.645903016291, 4800.0, 4964.156093464084, 4968.180250258703, 4997.5002499749735, 4998.850034498971, 5689.146628005832, 5709.971450142764, 5723.856903577403, 5723.971380143114, 5724.313082430093, 4050.756954582705, 4203.516595591485, 4204.915901681979, 4253.340732186516, 4254.765987870666, 5626.240457538221, 5792.275965504312, 5970.552208584373, 5979.402059794029, 5991.790287339966, 4255.680823938217, 4257.3613957905945, 4258.212230737324, 4317.805698743564, 4323.135372925428, 4299.742015479082, 4330.2638551446325, 4369.69412141147, 4372.584604462571, 4382.517923028464, 4772.5227477252, 4856.465788763233, 4917.459079501252, 4923.030787684955, 4954.826581069645, 5719.799807006735, 5738.221151443066, 5746.9425305747245, 5787.160861675034, 5793.768249270066, 1485.2202593638426, 1500.9624759381102, 1504.7968524249086, 1505.5558610210014, 1509.1397903195293, 928.8188803183315, 952.7951490429591, 963.6530848894392, 980.54404701814, 989.4014121456559, 5024.045431368028, 5032.874178145503, 5061.392632884077, 5063.443021267658, 5093.261477085802, 5189.948100518946, 5223.425423203445, 5245.763940622681, 5247.134222853187, 5284.630075894651, 5036.823711170165, 5050.545450909436, 5084.872878178076, 5271.341082364705, 5664.603477756517, 3162.826044567525, 3172.4131035757996, 3174.999875039355, 3175.2220705926734, 3199.9040028799172, 6001.489873360726, 6202.255729312421, 6231.189945307172, 6231.532635052389, 6268.435840774352, 6022.006368949162, 6108.389161083856, 6110.877782444326, 6110.938890611126, 6142.692865356804, 6131.846703832351, 6140.416660417253, 6148.538859585459, 6175.8265929473255, 6176.934913370993, 5846.473817356458, 5855.795047173328, 5927.4665280125, 5936.317323507852, 5990.64056156632, 5887.852803679943, 5897.764089436375, 5908.2275317405465, 5915.2845674753835, 5926.7850090730435, 5789.45000224978, 5948.53859605296, 6047.606905551301, 6084.361142080018, 6199.659018753831, 3850.807459626951, 4273.209456250604, 4297.462817147856, 4308.224519586504, 4313.331433627793, 5954.439590975922, 5995.940040599538, 5997.610155339889, 6000.459958603572, 6020.759169633166, 5683.687397193194, 5727.198192253171, 5782.681952492653, 5798.10129429939, 5819.679917604572, 5771.480566749013, 5771.624844385102, 5784.623999440023, 5787.585324658472, 5788.334341550607, 5716.885662286814, 5723.109829536058, 5750.482456578928, 5768.884622307614, 5785.8553536161935, 5837.883242335045, 5839.708014599338, 5875.823725288248, 5882.911756323573, 5895.380984996514, 5463.918041229305, 5683.204351390726, 5683.318001839806, 5717.112363592797, 5726.312842458931], "value": 4394.819563650514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.473284464365, 839.2069494327859, 891.1801142948486, 909.9044600316971, 917.1153280009578, 17176.39882604109, 18259.543511412223, 18265.08674566273, 18296.90851545742, 18378.35675751349, 16406.83593164068, 16538.58653533659, 16587.585310367245, 16590.7511387329, 16615.08617026063, 879.463527248378, 897.5960817632059, 903.3947255338906, 906.4289497616493, 908.0783005249676, 880.6697488441835, 881.788370791012, 882.1619461511558, 882.4572887674067, 882.7704796752827, 925.8240934222476, 927.6984979881531, 928.0209379105041, 932.3753085432743, 933.5052422216236, 918.5269586163131, 925.7824411263348, 926.2312280806948, 926.7497775600584, 927.8793756811605, 679.0493309366926, 679.2290749998738, 679.4835924697217, 679.7077256779583, 680.098868998574, 17438.215280312288, 17455.0, 17485.213165407586, 17486.47540573785, 17499.912500437542, 15872.589541957057, 15955.0, 16537.0, 16710.331586736516, 17535.386261480915, 13257.403416846266, 13506.594802155852, 13671.658208544866, 13698.0, 13704.683923313332, 4044.3933409988736, 4071.796410179479, 4073.429719839195, 4096.139810639769, 4221.155768846243, 56753.71623141899, 65819.67090164518, 66420.3357966419, 70939.0, 72769.63615181943, 12924.741505169844, 13054.54309099177, 13119.868801312055, 13130.671733206555, 13137.080404371793, 22996.65505017426, 23072.653910191362, 23077.307680769605, 23150.53698926012, 23160.18939337115, 9372.62509499626, 9517.476538790295, 9539.379940303857, 9561.474118923386, 9721.173700235558, 4992.625553083533, 5241.73791310433, 5255.08036093682, 5319.760610772524, 5331.360236771612, 3802.144517483544, 3805.82873770681, 3819.732618716663, 3824.808759562011, 3825.8087095645105, 657.3032585459429, 683.3712984054681, 687.6046273392819, 691.5435812363917, 699.7690762048512, 705.8023753349071, 787.4251796188814, 803.7468197517763, 812.5327936436572, 821.5768879027306, 9937.850932236022, 10628.627998020187, 10837.512311945982, 10966.616168434066, 11222.382768947786, 667.6728403082552, 674.2212744280364, 726.8655298769745, 782.338923609549, 788.8698364769749, 4891.088321806151, 4924.113659541245, 4953.702777833341, 5073.949260507347, 5125.1287281162295, 78451.60774196207, 112503.0, 122137.38931305286, 131079.34460327637, 136483.0, 70169.0, 90253.54873225592, 101241.49379253204, 103096.48451757693, 105054.47472762587, 86486.56756716176, 93544.0, 95138.0, 95145.52427237958, 97582.51208743911, 9265.814683706154, 9366.250699943852, 9807.656732014346, 10610.363378197333, 10811.243212975016, 52303.0, 55465.722671386386, 55566.16650750161, 56125.0, 56987.71506142443, 52414.0, 60514.69742651258, 77158.0, 79286.6035669818, 79863.20136798557, 11205.887941120485, 11538.423078846192, 12037.939810300892, 12039.518419263131, 12259.69350766238, 12806.231626102464, 13491.32543372847, 13804.861951380759, 13839.0, 14791.408343666133, 13156.868431315947, 13271.336433178496, 13283.203007819175, 13635.250061246727, 13685.178889266675, 13138.408771605109, 13472.124311919693, 13498.59504214875, 13732.382042807898, 14026.158430494203, 11207.607733729606, 11352.091832653203, 11353.262037967506, 11673.416329183678, 11797.35114568707, 10660.62687805923, 10690.679279621623, 10865.619703310345, 10983.83524247121, 12051.698707532381, 10402.583896644352, 11116.888831111586, 11302.265352752045, 11422.485988130387, 11619.302841829518], "value": 7950.889490148137, "unit": "score"}], "name": "dromaeo_css", "value": 7654.779363893627, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 110.0, 111.0, 112.0, 113.0, 111.0, 109.0, 112.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 121.0, 169.0, 124.0, 120.0, 146.0, 118.0, 147.0, 119.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 161.0, 159.0, 160.0, 160.0, 159.0, 160.0, 160.0, 159.0], "value": 159.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 112.0, 75.0, 75.0, 74.0, 84.0, 102.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 128.0, 84.0, 84.0, 83.0, 130.0, 83.0, 84.0, 125.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 148.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 50.0, 56.0, 51.0, 51.0, 51.0, 51.0, 50.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 58.0, 61.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 73.0, 73.0, 72.0, 72.0, 71.0, 72.0, 72.0, 76.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 134.0, 120.0, 120.0, 116.0, 120.0, 119.0, 114.0, 163.0, 133.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 151.0, 157.0, 152.0, 155.0, 156.0, 157.0, 154.0, 158.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 63.0, 61.0, 61.0, 64.0, 60.0, 70.0, 61.0, 58.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1465.8999999999999, "alertThreshold": 2.0}]} 14:43:14 INFO - Return code: 0 14:43:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:14 INFO - # TBPL SUCCESS # 14:43:14 INFO - Running post-action listener: _resource_record_post_action 14:43:14 INFO - [mozharness: 2016-08-22 21:43:14.515128Z] Finished run-tests step (success) 14:43:14 INFO - Running post-run listener: _resource_record_post_run 14:43:14 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 326742016; Read time: 28; Write time: 310132 14:43:14 INFO - TinderboxPrint: CPU usage
13.0% 14:43:14 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 14:43:14 INFO - TinderboxPrint: I/O write bytes / time
326,742,016 / 310,132 14:43:14 INFO - TinderboxPrint: CPU idle
8,280.2 (87.0%) 14:43:14 INFO - TinderboxPrint: CPU user
1,186.4 (12.5%) 14:43:14 INFO - TinderboxPrint: Swap in / out
0 / 0 14:43:14 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79589376; Read time: 0; Write time: 106224 14:43:14 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 246800384; Read time: 16; Write time: 203852 14:43:15 INFO - Running post-run listener: _upload_blobber_files 14:43:15 INFO - Blob upload gear active. 14:43:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:15 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 14:43:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:43:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:43:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:16 INFO - (blobuploader) - INFO - Done attempting. 14:43:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:43:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:17 INFO - (blobuploader) - INFO - Done attempting. 14:43:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:20 INFO - (blobuploader) - INFO - Done attempting. 14:43:20 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:20 INFO - Return code: 0 14:43:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b102baf7dad8aabb34c41421681d04609fbb13b88f34d293be9af25c1f35fd3b81a342c5496b92f74202d46212587b6bdcc51101f1021f954e871d3028cdfe2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e16b91a80140099d939687c6ad19baef40728ec74805b755f80134618aed63bf292e114ace2231ec2121aad4ae4843f3f12a45bf953941c91a933cce28b1b6d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/406e888bb94a01fea5a288834da259842d6f27832db007396eb88299a27030875d34576c807539167088afb3fbfef81f8aee85b9d1b94ee8a68887131fe7ce0b"} 14:43:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:20 INFO - Contents: 14:43:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b102baf7dad8aabb34c41421681d04609fbb13b88f34d293be9af25c1f35fd3b81a342c5496b92f74202d46212587b6bdcc51101f1021f954e871d3028cdfe2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e16b91a80140099d939687c6ad19baef40728ec74805b755f80134618aed63bf292e114ace2231ec2121aad4ae4843f3f12a45bf953941c91a933cce28b1b6d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/406e888bb94a01fea5a288834da259842d6f27832db007396eb88299a27030875d34576c807539167088afb3fbfef81f8aee85b9d1b94ee8a68887131fe7ce0b"} 14:43:20 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:20 INFO - Copying logs to upload dir... 14:43:20 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.528557 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 14:43:20.432700) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:43:20.436539) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b102baf7dad8aabb34c41421681d04609fbb13b88f34d293be9af25c1f35fd3b81a342c5496b92f74202d46212587b6bdcc51101f1021f954e871d3028cdfe2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e16b91a80140099d939687c6ad19baef40728ec74805b755f80134618aed63bf292e114ace2231ec2121aad4ae4843f3f12a45bf953941c91a933cce28b1b6d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/406e888bb94a01fea5a288834da259842d6f27832db007396eb88299a27030875d34576c807539167088afb3fbfef81f8aee85b9d1b94ee8a68887131fe7ce0b"} build_url:https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b102baf7dad8aabb34c41421681d04609fbb13b88f34d293be9af25c1f35fd3b81a342c5496b92f74202d46212587b6bdcc51101f1021f954e871d3028cdfe2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e16b91a80140099d939687c6ad19baef40728ec74805b755f80134618aed63bf292e114ace2231ec2121aad4ae4843f3f12a45bf953941c91a933cce28b1b6d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/406e888bb94a01fea5a288834da259842d6f27832db007396eb88299a27030875d34576c807539167088afb3fbfef81f8aee85b9d1b94ee8a68887131fe7ce0b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:43:20.462072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:43:20.462542) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471900519.332219-759695324 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:43:20.544866) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:43:20.545200) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:43:20.545493) ========= ========= Total master_lag: 0.16 =========