builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1471658511.07 results: success (0) buildid: 20160819162415 builduid: cd734351005c45fb84e1098e5aabc176 revision: 9aea5a70f2679e59ad80a66785215a10c7073928 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.071554) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.072505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.073161) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.089328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.090027) ========= 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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 19:01:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.9M=0s 2016-08-19 19:01:51 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113214 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.222018) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.222725) ========= 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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018159 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.274549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.275295) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9aea5a70f2679e59ad80a66785215a10c7073928 --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 9aea5a70f2679e59ad80a66785215a10c7073928 --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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 19:01:51,616 truncating revision to first 12 chars 2016-08-19 19:01:51,616 Setting DEBUG logging. 2016-08-19 19:01:51,616 attempt 1/10 2016-08-19 19:01:51,616 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9aea5a70f267?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 19:01:51,914 unpacking tar archive at: mozilla-aurora-9aea5a70f267/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.919379) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.920055) ========= script_repo_revision: 9aea5a70f2679e59ad80a66785215a10c7073928 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.920949) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.921636) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 19:01:51.933344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-19 19:01:51.933643) ========= /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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:01:52 INFO - MultiFileLogger online at 20160819 19:01:52 in /builds/slave/test 19:01:52 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 19:01:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:52 INFO - {'append_to_log': False, 19:01:52 INFO - 'base_work_dir': '/builds/slave/test', 19:01:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:01:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:52 INFO - 'branch': 'Mozilla-Aurora', 19:01:52 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:52 INFO - 'config_files': ('talos/linux_config.py',), 19:01:52 INFO - 'default_actions': ('clobber', 19:01:52 INFO - 'read-buildbot-config', 19:01:52 INFO - 'download-and-extract', 19:01:52 INFO - 'populate-webroot', 19:01:52 INFO - 'create-virtualenv', 19:01:52 INFO - 'install', 19:01:52 INFO - 'run-tests'), 19:01:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:52 INFO - 'download_minidump_stackwalk': True, 19:01:52 INFO - 'download_symbols': 'ondemand', 19:01:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:52 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:52 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:52 INFO - 'installer_path': 'installer.exe', 19:01:52 INFO - 'log_level': 'info', 19:01:52 INFO - 'log_name': 'talos', 19:01:52 INFO - 'log_to_console': True, 19:01:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:01:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:01:52 INFO - 'opt_config_files': (), 19:01:52 INFO - 'pip_index': False, 19:01:52 INFO - 'sps_profile': False, 19:01:52 INFO - 'sps_profile_interval': 0, 19:01:52 INFO - 'suite': 'dromaeojs', 19:01:52 INFO - 'system_bits': '32', 19:01:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:52 INFO - 'title': 'talos-linux64-ix-060', 19:01:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:52 INFO - 'use_talos_json': True, 19:01:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:52 INFO - 'work_dir': 'build'} 19:01:52 INFO - [mozharness: 2016-08-20 02:01:52.366838Z] Running clobber step. 19:01:52 INFO - Running pre-action listener: _resource_record_pre_action 19:01:52 INFO - Running main action method: clobber 19:01:52 INFO - rmtree: /builds/slave/test/build 19:01:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:52 INFO - Running post-action listener: _resource_record_post_action 19:01:52 INFO - [mozharness: 2016-08-20 02:01:52.826856Z] Finished clobber step (success) 19:01:52 INFO - [mozharness: 2016-08-20 02:01:52.826957Z] Running read-buildbot-config step. 19:01:52 INFO - Running pre-action listener: _resource_record_pre_action 19:01:52 INFO - Running main action method: read_buildbot_config 19:01:52 INFO - Using buildbot properties: 19:01:52 INFO - { 19:01:52 INFO - "project": "", 19:01:52 INFO - "product": "firefox", 19:01:52 INFO - "script_repo_revision": "production", 19:01:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:01:52 INFO - "repository": "", 19:01:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:01:52 INFO - "buildid": "20160819162415", 19:01:52 INFO - "pgo_build": "True", 19:01:52 INFO - "basedir": "/builds/slave/test", 19:01:52 INFO - "buildnumber": 476, 19:01:52 INFO - "slavename": "talos-linux64-ix-060", 19:01:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:01:52 INFO - "platform": "ubuntu64_hw", 19:01:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:01:52 INFO - "branch": "mozilla-aurora", 19:01:52 INFO - "repo_path": "releases/mozilla-aurora", 19:01:52 INFO - "revision": "9aea5a70f2679e59ad80a66785215a10c7073928", 19:01:52 INFO - "stage_platform": "linux64-pgo", 19:01:52 INFO - "builduid": "cd734351005c45fb84e1098e5aabc176", 19:01:52 INFO - "slavebuilddir": "test" 19:01:52 INFO - } 19:01:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 19:01:52 INFO - Running post-action listener: _resource_record_post_action 19:01:52 INFO - [mozharness: 2016-08-20 02:01:52.829398Z] Finished read-buildbot-config step (success) 19:01:52 INFO - [mozharness: 2016-08-20 02:01:52.829497Z] Running download-and-extract step. 19:01:52 INFO - Running pre-action listener: _resource_record_pre_action 19:01:52 INFO - Running main action method: download_and_extract 19:01:52 INFO - mkdir: /builds/slave/test/build/tests 19:01: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')]} 19:01:52 INFO - https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:01:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:01:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:01:52 INFO - retry: Failed, sleeping 30 seconds before retrying 19:02:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:02:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:02:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:02:22 INFO - retry: Failed, sleeping 60 seconds before retrying 19:03:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:03:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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! 19:03:22 INFO - Caught exception: HTTP Error 404: Not Found 19:03:22 INFO - Caught exception: HTTP Error 404: Not Found 19:03:22 INFO - Caught exception: HTTP Error 404: Not Found 19:03:22 INFO - trying https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:22 INFO - Downloading https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:23 INFO - Downloaded 1361 bytes. 19:03:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:23 INFO - Using the following test package requirements: 19:03:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:03:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:03:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:03:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'jsshell-linux-x86_64.zip'], 19:03:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:03:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:03:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:03:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:03:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:03:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:03:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:23 INFO - https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:03:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:03:23 INFO - retry: Failed, sleeping 30 seconds before retrying 19:03:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:03:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:03:53 INFO - retry: Failed, sleeping 60 seconds before retrying 19:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:04:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:04:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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! 19:04:53 INFO - Caught exception: HTTP Error 404: Not Found 19:04:53 INFO - Caught exception: HTTP Error 404: Not Found 19:04:53 INFO - Caught exception: HTTP Error 404: Not Found 19:04:53 INFO - trying https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:53 INFO - Downloading https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:04:54 INFO - Downloaded 21766203 bytes. 19:04:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:04:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:04:55 INFO - Return code: 0 19:04:55 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:55 INFO - https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:04:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:04:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:04:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:04:55 INFO - retry: Failed, sleeping 30 seconds before retrying 19:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:05:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:05:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:05:25 INFO - retry: Failed, sleeping 60 seconds before retrying 19:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:06:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:06:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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! 19:06:25 INFO - Caught exception: HTTP Error 404: Not Found 19:06:25 INFO - Caught exception: HTTP Error 404: Not Found 19:06:25 INFO - Caught exception: HTTP Error 404: Not Found 19:06:25 INFO - trying https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:06:25 INFO - Downloading https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:26 INFO - Downloaded 21766203 bytes. 19:06:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:06:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:06:27 INFO - Return code: 0 19:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:06:27 INFO - https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:06:27 INFO - retry: Failed, sleeping 30 seconds before retrying 19:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:06:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:06:58 INFO - retry: Failed, sleeping 60 seconds before retrying 19:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:07:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:07:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:07:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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! 19:07:58 INFO - Caught exception: HTTP Error 404: Not Found 19:07:58 INFO - Caught exception: HTTP Error 404: Not Found 19:07:58 INFO - Caught exception: HTTP Error 404: Not Found 19:07:58 INFO - trying https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:07:58 INFO - Downloading https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:07:59 INFO - Downloaded 14413723 bytes. 19:07:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:07:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:07:59 INFO - Return code: 0 19:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:59 INFO - https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:07:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:07:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:07:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:07:59 INFO - retry: Failed, sleeping 30 seconds before retrying 19:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:08:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:08:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:08:29 INFO - retry: Failed, sleeping 60 seconds before retrying 19:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:09:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:09:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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! 19:09:30 INFO - Caught exception: HTTP Error 404: Not Found 19:09:30 INFO - Caught exception: HTTP Error 404: Not Found 19:09:30 INFO - Caught exception: HTTP Error 404: Not Found 19:09:30 INFO - trying https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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 19:09:31 INFO - Downloaded 63744870 bytes. 19:09:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:31 INFO - mkdir: /builds/slave/test/properties 19:09:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:31 INFO - Contents: 19:09:31 INFO - build_url:https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:31 INFO - Running post-action listener: _resource_record_post_action 19:09:31 INFO - Running post-action listener: set_extra_try_arguments 19:09:31 INFO - [mozharness: 2016-08-20 02:09:31.899240Z] Finished download-and-extract step (success) 19:09:31 INFO - [mozharness: 2016-08-20 02:09:31.899476Z] Running populate-webroot step. 19:09:31 INFO - Running pre-action listener: _resource_record_pre_action 19:09:31 INFO - Running main action method: populate_webroot 19:09:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:31 INFO - '--tppagecycles', 19:09:31 INFO - '7'], 19:09:31 INFO - 'tests': ['tsvgm']}}, 19:09:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['damp', 'tps']}, 19:09:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'tests': ['damp', 'tps']}, 19:09:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['dromaeo_dom']}, 19:09:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['basic_compositor_video']}, 19:09:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:09:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['a11yr', 19:09:31 INFO - 'ts_paint', 19:09:31 INFO - 'tpaint', 19:09:31 INFO - 'sessionrestore', 19:09:31 INFO - 'sessionrestore_no_auto_restore', 19:09:31 INFO - 'tabpaint']}, 19:09:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['tsvgx', 19:09:31 INFO - 'tsvgr_opacity', 19:09:31 INFO - 'tart', 19:09:31 INFO - 'tscrollx', 19:09:31 INFO - 'cart']}, 19:09:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:31 INFO - 'tsvgr_opacity', 19:09:31 INFO - 'tart', 19:09:31 INFO - 'tscrollx', 19:09:31 INFO - 'cart']}, 19:09:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'talos_options': ['--disable-e10s'], 19:09:31 INFO - 'tests': ['tp5o']}, 19:09:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'tests': ['tp5o']}, 19:09:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'talos_options': ['--disable-e10s', 19:09:31 INFO - '--xperf_path', 19:09:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:31 INFO - 'tests': ['tp5n']}, 19:09:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:31 INFO - 'talos_options': ['--xperf_path', 19:09:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:31 INFO - 'tests': ['tp5n']}}, 19:09:31 INFO - 'talos.zip': {'path': '', 19:09:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:31 INFO - Running post-action listener: _resource_record_post_action 19:09:31 INFO - [mozharness: 2016-08-20 02:09:31.916625Z] Finished populate-webroot step (success) 19:09:31 INFO - [mozharness: 2016-08-20 02:09:31.916714Z] Running create-virtualenv step. 19:09:31 INFO - Running pre-action listener: _resource_record_pre_action 19:09:31 INFO - Running main action method: create_virtualenv 19:09:31 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:09:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:32 INFO - Using real prefix '/usr' 19:09:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:34 INFO - Installing pip.................done. 19:09:34 INFO - Return code: 0 19:09:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:34 INFO - Using env: {'DISPLAY': ':0', 19:09:34 INFO - 'HOME': '/home/cltbld', 19:09:34 INFO - 'LANG': 'en_US.UTF-8', 19:09:34 INFO - 'LANGUAGE': 'en_US:en', 19:09:34 INFO - 'LOGNAME': 'cltbld', 19:09:34 INFO - 'MAIL': '/var/mail/cltbld', 19:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:34 INFO - 'MOZ_NO_REMOTE': '1', 19:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:34 INFO - 'NO_EM_RESTART': '1', 19:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:34 INFO - 'PWD': '/builds/slave/test', 19:09:34 INFO - 'SHELL': '/bin/bash', 19:09:34 INFO - 'SHLVL': '1', 19:09:34 INFO - 'TERM': 'linux', 19:09:34 INFO - 'TMOUT': '86400', 19:09:34 INFO - 'USER': 'cltbld', 19:09:34 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:34 INFO - '_': '/tools/buildbot/bin/python'} 19:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:09:34 INFO - Cleaning up... 19:09:34 INFO - Return code: 0 19:09:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:09:34 INFO - Using env: {'DISPLAY': ':0', 19:09:34 INFO - 'HOME': '/home/cltbld', 19:09:34 INFO - 'LANG': 'en_US.UTF-8', 19:09:34 INFO - 'LANGUAGE': 'en_US:en', 19:09:34 INFO - 'LOGNAME': 'cltbld', 19:09:34 INFO - 'MAIL': '/var/mail/cltbld', 19:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:34 INFO - 'MOZ_NO_REMOTE': '1', 19:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:34 INFO - 'NO_EM_RESTART': '1', 19:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:34 INFO - 'PWD': '/builds/slave/test', 19:09:34 INFO - 'SHELL': '/bin/bash', 19:09:34 INFO - 'SHLVL': '1', 19:09:34 INFO - 'TERM': 'linux', 19:09:34 INFO - 'TMOUT': '86400', 19:09:34 INFO - 'USER': 'cltbld', 19:09:34 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:34 INFO - '_': '/tools/buildbot/bin/python'} 19:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:34 INFO - Downloading/unpacking psutil>=3.1.1 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:36 INFO - Installing collected packages: psutil 19:09:36 INFO - Running setup.py install for psutil 19:09:36 INFO - building 'psutil._psutil_linux' extension 19:09:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:09:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:09:37 INFO - building 'psutil._psutil_posix' extension 19:09:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:09:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:37 INFO - Successfully installed psutil 19:09:37 INFO - Cleaning up... 19:09:37 INFO - Return code: 0 19:09:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:09:37 INFO - Using env: {'DISPLAY': ':0', 19:09:37 INFO - 'HOME': '/home/cltbld', 19:09:37 INFO - 'LANG': 'en_US.UTF-8', 19:09:37 INFO - 'LANGUAGE': 'en_US:en', 19:09:37 INFO - 'LOGNAME': 'cltbld', 19:09:37 INFO - 'MAIL': '/var/mail/cltbld', 19:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:37 INFO - 'MOZ_NO_REMOTE': '1', 19:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:37 INFO - 'NO_EM_RESTART': '1', 19:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:37 INFO - 'PWD': '/builds/slave/test', 19:09:37 INFO - 'SHELL': '/bin/bash', 19:09:37 INFO - 'SHLVL': '1', 19:09:37 INFO - 'TERM': 'linux', 19:09:37 INFO - 'TMOUT': '86400', 19:09:37 INFO - 'USER': 'cltbld', 19:09:37 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:37 INFO - '_': '/tools/buildbot/bin/python'} 19:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:39 INFO - Installing collected packages: mozsystemmonitor 19:09:39 INFO - Running setup.py install for mozsystemmonitor 19:09:39 INFO - Successfully installed mozsystemmonitor 19:09:39 INFO - Cleaning up... 19:09:39 INFO - Return code: 0 19:09:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:09:39 INFO - Using env: {'DISPLAY': ':0', 19:09:39 INFO - 'HOME': '/home/cltbld', 19:09:39 INFO - 'LANG': 'en_US.UTF-8', 19:09:39 INFO - 'LANGUAGE': 'en_US:en', 19:09:39 INFO - 'LOGNAME': 'cltbld', 19:09:39 INFO - 'MAIL': '/var/mail/cltbld', 19:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:39 INFO - 'MOZ_NO_REMOTE': '1', 19:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:39 INFO - 'NO_EM_RESTART': '1', 19:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:39 INFO - 'PWD': '/builds/slave/test', 19:09:39 INFO - 'SHELL': '/bin/bash', 19:09:39 INFO - 'SHLVL': '1', 19:09:39 INFO - 'TERM': 'linux', 19:09:39 INFO - 'TMOUT': '86400', 19:09:39 INFO - 'USER': 'cltbld', 19:09:39 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:39 INFO - '_': '/tools/buildbot/bin/python'} 19:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:39 INFO - Downloading/unpacking jsonschema==2.5.1 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:42 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:42 INFO - warning: no files found matching '*.txt' 19:09:42 INFO - no previously-included directories found matching 'build' 19:09:42 INFO - no previously-included directories found matching 'dist' 19:09:42 INFO - no previously-included directories found matching '.git*' 19:09:42 INFO - Installing collected packages: jsonschema, functools32 19:09:42 INFO - Running setup.py install for functools32 19:09:42 INFO - warning: no files found matching '*.txt' 19:09:42 INFO - no previously-included directories found matching 'build' 19:09:42 INFO - no previously-included directories found matching 'dist' 19:09:42 INFO - no previously-included directories found matching '.git*' 19:09:42 INFO - Successfully installed jsonschema functools32 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:09:42 INFO - Using env: {'DISPLAY': ':0', 19:09:42 INFO - 'HOME': '/home/cltbld', 19:09:42 INFO - 'LANG': 'en_US.UTF-8', 19:09:42 INFO - 'LANGUAGE': 'en_US:en', 19:09:42 INFO - 'LOGNAME': 'cltbld', 19:09:42 INFO - 'MAIL': '/var/mail/cltbld', 19:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:42 INFO - 'MOZ_NO_REMOTE': '1', 19:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:42 INFO - 'NO_EM_RESTART': '1', 19:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:42 INFO - 'PWD': '/builds/slave/test', 19:09:42 INFO - 'SHELL': '/bin/bash', 19:09:42 INFO - 'SHLVL': '1', 19:09:42 INFO - 'TERM': 'linux', 19:09:42 INFO - 'TMOUT': '86400', 19:09:42 INFO - 'USER': 'cltbld', 19:09:42 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:42 INFO - '_': '/tools/buildbot/bin/python'} 19:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:42 INFO - Using env: {'DISPLAY': ':0', 19:09:42 INFO - 'HOME': '/home/cltbld', 19:09:42 INFO - 'LANG': 'en_US.UTF-8', 19:09:42 INFO - 'LANGUAGE': 'en_US:en', 19:09:42 INFO - 'LOGNAME': 'cltbld', 19:09:42 INFO - 'MAIL': '/var/mail/cltbld', 19:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:42 INFO - 'MOZ_NO_REMOTE': '1', 19:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:42 INFO - 'NO_EM_RESTART': '1', 19:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:42 INFO - 'PWD': '/builds/slave/test', 19:09:42 INFO - 'SHELL': '/bin/bash', 19:09:42 INFO - 'SHLVL': '1', 19:09:42 INFO - 'TERM': 'linux', 19:09:42 INFO - 'TMOUT': '86400', 19:09:42 INFO - 'USER': 'cltbld', 19:09:42 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:42 INFO - '_': '/tools/buildbot/bin/python'} 19:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:45 INFO - Downloading docopt-0.6.1.tar.gz 19:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:45 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:45 INFO - Running setup.py install for blobuploader 19:09:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:45 INFO - Running setup.py install for requests 19:09:45 INFO - Running setup.py install for docopt 19:09:45 INFO - Successfully installed blobuploader requests docopt 19:09:45 INFO - Cleaning up... 19:09:45 INFO - Return code: 0 19:09:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:45 INFO - Using env: {'DISPLAY': ':0', 19:09:45 INFO - 'HOME': '/home/cltbld', 19:09:45 INFO - 'LANG': 'en_US.UTF-8', 19:09:45 INFO - 'LANGUAGE': 'en_US:en', 19:09:45 INFO - 'LOGNAME': 'cltbld', 19:09:45 INFO - 'MAIL': '/var/mail/cltbld', 19:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:45 INFO - 'MOZ_NO_REMOTE': '1', 19:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:45 INFO - 'NO_EM_RESTART': '1', 19:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:45 INFO - 'PWD': '/builds/slave/test', 19:09:45 INFO - 'SHELL': '/bin/bash', 19:09:45 INFO - 'SHLVL': '1', 19:09:45 INFO - 'TERM': 'linux', 19:09:45 INFO - 'TMOUT': '86400', 19:09:45 INFO - 'USER': 'cltbld', 19:09:45 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:45 INFO - '_': '/tools/buildbot/bin/python'} 19:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:46 INFO - Running setup.py (path:/tmp/pip-EEbsyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:46 INFO - Running setup.py (path:/tmp/pip-xgPBo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:46 INFO - Running setup.py (path:/tmp/pip-EOtfH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:46 INFO - Running setup.py (path:/tmp/pip-1VQkid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:46 INFO - Running setup.py (path:/tmp/pip-Q64bE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:46 INFO - Running setup.py (path:/tmp/pip-ljvkkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:46 INFO - Running setup.py (path:/tmp/pip-uLTCgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:46 INFO - Running setup.py (path:/tmp/pip-jS7uxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:46 INFO - Running setup.py (path:/tmp/pip-ssIcat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:46 INFO - Running setup.py (path:/tmp/pip-bz9L7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:46 INFO - Running setup.py (path:/tmp/pip-1IhNng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:46 INFO - Running setup.py (path:/tmp/pip-JSCtmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:47 INFO - Running setup.py (path:/tmp/pip-vbOzzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:47 INFO - Running setup.py (path:/tmp/pip-Glq2jO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:47 INFO - Running setup.py (path:/tmp/pip-JdJXZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:47 INFO - Running setup.py (path:/tmp/pip-40Wc4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:47 INFO - Running setup.py (path:/tmp/pip-na7pvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:47 INFO - Running setup.py install for manifestparser 19:09:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:47 INFO - Running setup.py install for mozcrash 19:09:47 INFO - Running setup.py install for mozdebug 19:09:47 INFO - Running setup.py install for mozdevice 19:09:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:47 INFO - Running setup.py install for mozfile 19:09:47 INFO - Running setup.py install for mozhttpd 19:09:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozinfo 19:09:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozInstall 19:09:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozleak 19:09:48 INFO - Running setup.py install for mozlog 19:09:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for moznetwork 19:09:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozprocess 19:09:48 INFO - Running setup.py install for mozprofile 19:09:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozrunner 19:09:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:48 INFO - Running setup.py install for mozscreenshot 19:09:48 INFO - Running setup.py install for moztest 19:09:49 INFO - Running setup.py install for mozversion 19:09:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:49 INFO - Cleaning up... 19:09:49 INFO - Return code: 0 19:09:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:49 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 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:49 INFO - Using env: {'DISPLAY': ':0', 19:09:49 INFO - 'HOME': '/home/cltbld', 19:09:49 INFO - 'LANG': 'en_US.UTF-8', 19:09:49 INFO - 'LANGUAGE': 'en_US:en', 19:09:49 INFO - 'LOGNAME': 'cltbld', 19:09:49 INFO - 'MAIL': '/var/mail/cltbld', 19:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:49 INFO - 'MOZ_NO_REMOTE': '1', 19:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:49 INFO - 'NO_EM_RESTART': '1', 19:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:49 INFO - 'PWD': '/builds/slave/test', 19:09:49 INFO - 'SHELL': '/bin/bash', 19:09:49 INFO - 'SHLVL': '1', 19:09:49 INFO - 'TERM': 'linux', 19:09:49 INFO - 'TMOUT': '86400', 19:09:49 INFO - 'USER': 'cltbld', 19:09:49 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:49 INFO - '_': '/tools/buildbot/bin/python'} 19:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:49 INFO - Running setup.py (path:/tmp/pip-kBFwQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:49 INFO - Running setup.py (path:/tmp/pip-Aq22ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:49 INFO - Running setup.py (path:/tmp/pip-4IzflU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:49 INFO - Running setup.py (path:/tmp/pip-3mq0qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:49 INFO - Running setup.py (path:/tmp/pip-gU6Lez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:49 INFO - Running setup.py (path:/tmp/pip-4Y_yV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:49 INFO - Running setup.py (path:/tmp/pip-evJstA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:49 INFO - Running setup.py (path:/tmp/pip-zwLcoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:49 INFO - Running setup.py (path:/tmp/pip-mgJ9Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:50 INFO - Running setup.py (path:/tmp/pip-NnaldT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:50 INFO - Running setup.py (path:/tmp/pip-D6NmyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:50 INFO - Running setup.py (path:/tmp/pip-4_kK_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:50 INFO - Running setup.py (path:/tmp/pip-b9_6B8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:50 INFO - Running setup.py (path:/tmp/pip-G4jMaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:50 INFO - Running setup.py (path:/tmp/pip-6NTruu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:50 INFO - Running setup.py (path:/tmp/pip-8x5XdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:50 INFO - Running setup.py (path:/tmp/pip-a6Zm4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:52 INFO - Downloading blessings-1.6.tar.gz 19:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:52 INFO - Installing collected packages: blessings 19:09:52 INFO - Running setup.py install for blessings 19:09:52 INFO - Successfully installed blessings 19:09:52 INFO - Cleaning up... 19:09:52 INFO - Return code: 0 19:09:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:52 INFO - Reading from file tmpfile_stdout 19:09:52 INFO - Current package versions: 19:09:52 INFO - argparse == 1.2.1 19:09:52 INFO - blessings == 1.6 19:09:52 INFO - blobuploader == 1.2.4 19:09:52 INFO - docopt == 0.6.1 19:09:52 INFO - functools32 == 3.2.3-2 19:09:52 INFO - jsonschema == 2.5.1 19:09:52 INFO - manifestparser == 1.1 19:09:52 INFO - mozInstall == 1.12 19:09:52 INFO - mozcrash == 0.17 19:09:52 INFO - mozdebug == 0.1 19:09:52 INFO - mozdevice == 0.48 19:09:52 INFO - mozfile == 1.2 19:09:52 INFO - mozhttpd == 0.7 19:09:52 INFO - mozinfo == 0.9 19:09:52 INFO - mozleak == 0.1 19:09:52 INFO - mozlog == 3.3 19:09:52 INFO - moznetwork == 0.27 19:09:52 INFO - mozprocess == 0.23 19:09:52 INFO - mozprofile == 0.28 19:09:52 INFO - mozrunner == 6.12 19:09:52 INFO - mozscreenshot == 0.1 19:09:52 INFO - mozsystemmonitor == 0.3 19:09:52 INFO - moztest == 0.7 19:09:52 INFO - mozversion == 1.4 19:09:52 INFO - psutil == 3.1.1 19:09:52 INFO - requests == 1.2.3 19:09:52 INFO - wsgiref == 0.1.2 19:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:52 INFO - Using env: {'DISPLAY': ':0', 19:09:52 INFO - 'HOME': '/home/cltbld', 19:09:52 INFO - 'LANG': 'en_US.UTF-8', 19:09:52 INFO - 'LANGUAGE': 'en_US:en', 19:09:52 INFO - 'LOGNAME': 'cltbld', 19:09:52 INFO - 'MAIL': '/var/mail/cltbld', 19:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:52 INFO - 'MOZ_NO_REMOTE': '1', 19:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:52 INFO - 'NO_EM_RESTART': '1', 19:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:52 INFO - 'PWD': '/builds/slave/test', 19:09:52 INFO - 'SHELL': '/bin/bash', 19:09:52 INFO - 'SHLVL': '1', 19:09:52 INFO - 'TERM': 'linux', 19:09:52 INFO - 'TMOUT': '86400', 19:09:52 INFO - 'USER': 'cltbld', 19:09:52 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:52 INFO - '_': '/tools/buildbot/bin/python'} 19:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:09:53 INFO - Cleaning up... 19:09:53 INFO - Return code: 0 19:09:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:53 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 0x263b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x279e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, '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': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:09:53 INFO - Using env: {'DISPLAY': ':0', 19:09:53 INFO - 'HOME': '/home/cltbld', 19:09:53 INFO - 'LANG': 'en_US.UTF-8', 19:09:53 INFO - 'LANGUAGE': 'en_US:en', 19:09:53 INFO - 'LOGNAME': 'cltbld', 19:09:53 INFO - 'MAIL': '/var/mail/cltbld', 19:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:53 INFO - 'MOZ_NO_REMOTE': '1', 19:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:53 INFO - 'NO_EM_RESTART': '1', 19:09:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:53 INFO - 'PWD': '/builds/slave/test', 19:09:53 INFO - 'SHELL': '/bin/bash', 19:09:53 INFO - 'SHLVL': '1', 19:09:53 INFO - 'TERM': 'linux', 19:09:53 INFO - 'TMOUT': '86400', 19:09:53 INFO - 'USER': 'cltbld', 19:09:53 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:53 INFO - '_': '/tools/buildbot/bin/python'} 19:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:09:53 INFO - Cleaning up... 19:09:53 INFO - Return code: 0 19:09:53 INFO - Running post-action listener: _resource_record_post_action 19:09:53 INFO - Running post-action listener: _start_resource_monitoring 19:09:53 INFO - Starting resource monitoring. 19:09:53 INFO - [mozharness: 2016-08-20 02:09:53.449880Z] Finished create-virtualenv step (success) 19:09:53 INFO - [mozharness: 2016-08-20 02:09:53.450309Z] Running install step. 19:09:53 INFO - Running pre-action listener: _resource_record_pre_action 19:09:53 INFO - Running main action method: install 19:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:53 INFO - Reading from file tmpfile_stdout 19:09:53 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:53 INFO - Reading from file tmpfile_stdout 19:09:53 INFO - Output received: 19:09:53 INFO - Usage: mozinstall [options] installer 19:09:53 INFO - Options: 19:09:53 INFO - -h, --help show this help message and exit 19:09:53 INFO - -d DEST, --destination=DEST 19:09:53 INFO - Directory to install application into. [default: 19:09:53 INFO - "/builds/slave/test"] 19:09:53 INFO - --app=APP Application being installed. [default: firefox] 19:09:53 INFO - mkdir: /builds/slave/test/build/application 19:09:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:10:09 INFO - Reading from file tmpfile_stdout 19:10:09 INFO - Output received: 19:10:09 INFO - /builds/slave/test/build/application/firefox/firefox 19:10:09 INFO - Running post-action listener: _resource_record_post_action 19:10:09 INFO - [mozharness: 2016-08-20 02:10:09.749439Z] Finished install step (success) 19:10:09 INFO - [mozharness: 2016-08-20 02:10:09.749723Z] Running run-tests step. 19:10:09 INFO - Running pre-action listener: _resource_record_pre_action 19:10:09 INFO - Running main action method: run_tests 19:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:10:09 INFO - Python 2.7.3 19:10:09 INFO - Return code: 0 19:10:09 INFO - grabbing minidump binary from tooltool 19:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:10:09 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 0x28fa3b0>, '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 0x28fb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28fb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:10:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:10:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:10:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:10:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:09 INFO - Return code: 0 19:10:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:10:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:10:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:10:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:10:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:10:09 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/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:10:09 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/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:10:09 INFO - Using env: {'DISPLAY': ':0', 19:10:09 INFO - 'HOME': '/home/cltbld', 19:10:09 INFO - 'LANG': 'en_US.UTF-8', 19:10:09 INFO - 'LANGUAGE': 'en_US:en', 19:10:09 INFO - 'LOGNAME': 'cltbld', 19:10:09 INFO - 'MAIL': '/var/mail/cltbld', 19:10:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:10:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:09 INFO - 'MOZ_NO_REMOTE': '1', 19:10:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:09 INFO - 'NO_EM_RESTART': '1', 19:10:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:09 INFO - 'PWD': '/builds/slave/test', 19:10:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:10:09 INFO - 'SHELL': '/bin/bash', 19:10:09 INFO - 'SHLVL': '1', 19:10:09 INFO - 'TERM': 'linux', 19:10:09 INFO - 'TMOUT': '86400', 19:10:09 INFO - 'USER': 'cltbld', 19:10:09 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263', 19:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:09 INFO - '_': '/tools/buildbot/bin/python'} 19:10:09 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/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:10:10 INFO - mozversion application_buildid: 20160819162415 19:10:10 INFO - mozversion application_changeset: 9aea5a70f2679e59ad80a66785215a10c7073928 19:10:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:10:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:10 INFO - mozversion application_name: Firefox 19:10:10 INFO - mozversion application_remotingname: firefox-dev 19:10:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:10 INFO - mozversion application_vendor: Mozilla 19:10:10 INFO - mozversion application_version: 50.0a2 19:10:10 INFO - mozversion platform_buildid: 20160819162415 19:10:10 INFO - mozversion platform_changeset: 9aea5a70f2679e59ad80a66785215a10c7073928 19:10:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:10 INFO - mozversion platform_version: 50.0a2 19:10:10 INFO - using testdate: 1471659010 19:10:10 INFO - actual date: 1471659010 19:10:10 INFO - starting webserver on 'localhost:34812' 19:10:10 INFO - SUITE-START | Running 2 tests 19:10:10 INFO - TEST-START | dromaeo_css 19:10:10 INFO - operating with platform_type : linux_ 19:10:10 INFO - Initialising browser for dromaeo_css test... 19:10:10 INFO - TEST-INFO | started process 4799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Ow1fH/profile http://localhost:34812/getInfo.html) 19:10:13 INFO - PROCESS | 4799 | __metrics Screen width/height:1600/1200 19:10:13 INFO - PROCESS | 4799 | colorDepth:24 19:10:13 INFO - PROCESS | 4799 | Browser inner width/height: 1024/697 19:10:13 INFO - PROCESS | 4799 | __metrics 19:10:14 INFO - PROCESS | 4799 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:10:14 INFO - PROCESS | 4799 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:15 INFO - TEST-INFO | 4799: exit 0 19:10:15 INFO - Browser initialized. 19:10:15 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:15 INFO - TEST-INFO | started process 4887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Ow1fH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:27 INFO - PROCESS | 4887 | RSS: Main: 145907712 19:10:27 INFO - PROCESS | 4887 | 19:11:18 INFO - PROCESS | 4887 | 1471659078674 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:13:26 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34812/tests/dromaeo/cssquery-ext.html) 19:13:28 INFO - PROCESS | 4887 | RSS: Main: 278827008 19:13:28 INFO - PROCESS | 4887 | 19:16:30 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-ext.html (next: http://localhost:34812/tests/dromaeo/cssquery-jquery.html) 19:16:31 INFO - PROCESS | 4887 | RSS: Main: 230539264 19:16:31 INFO - PROCESS | 4887 | 19:19:33 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34812/tests/dromaeo/cssquery-mootools.html) 19:19:33 INFO - PROCESS | 4887 | RSS: Main: 172085248 19:19:33 INFO - PROCESS | 4887 | 19:22:35 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34812/tests/dromaeo/cssquery-prototype.html) 19:22:36 INFO - PROCESS | 4887 | RSS: Main: 173608960 19:22:36 INFO - PROCESS | 4887 | 19:25:38 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34812/tests/dromaeo/cssquery-yui.html) 19:25:39 INFO - PROCESS | 4887 | RSS: Main: 171098112 19:25:39 INFO - PROCESS | 4887 | 19:28:41 INFO - PROCESS | 4887 | Cycle 1(1): loaded http://localhost:34812/tests/dromaeo/cssquery-yui.html (next: http://localhost:34812/tests/dromaeo/cssquery-dojo.html) 19:28:42 INFO - PROCESS | 4887 | RSS: Main: 260366336 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | __start_tp_report 19:28:42 INFO - PROCESS | 4887 | _x_x_mozilla_page_load 19:28:42 INFO - PROCESS | 4887 | _x_x_mozilla_page_load_details 19:28:42 INFO - PROCESS | 4887 | |i|pagename|runs| 19:28:42 INFO - PROCESS | 4887 | |0;dojo.html;3251.089694885432;3261.8042917424964;3268.7385009199265;3296.357210343981;3313.3704596126736;5584.078627026541;5680.801171958982;5684.261046064016;5684.914726279109;5690.886182276352;5692.516136128424;5759.971200143993;5767.423257674231;5777.740001699923;5785.045467497868;5032.5470707636305;5096.413912400066;5229.50319719626;5230.450802665703;5277.973610131944;5110.7700153493;5132.332796736438;5143.331366922295;5166.43169251382;5181.119209734355;5168.431472538018;5264.026155161288;5265.315508983846;5277.577793776508;5278.155495120782;5194.558462530652;5217.947820521822;5251.081060814342;5266.5523430508265;5287.894242115155;4855.393075865517;4991.201407774739;5147.948520514784;5149.2791009258735;5249.42256351801;5743.0811070228565;5753.424657534213;5755.107958266433;5758.136279558058;5784.045632470637;6114.633122012693;6116.174316467264;6163.383661633846;6173.938260617381;6182.1345011698395;5936.228290322274;6015.3383127855905;6066.059760737093;6078.726457309387;6210.844728881769;5721.199032135504;5727.341355744059;5727.541796656299;5738.7991420300505;5748.223989761371;6227.346128656516;6233.968830155911;6241.21984751906;6241.438270555672;6247.96876015617;5815.2149459822385;5828.6211396259105;5852.736626851802;5888.263967004124;6026.126211699279;6035.124906888476;6085.726142323606;6089.725962331617;6089.939100609025;6093.086037094471;5963.642181469126;5990.760369585255;6016.639001659914;6021;6022.759089636453;5832.358440571534;5881.911771323434;5969.641821490724;5975.52195824333;6023.608465449732;4714;4716.221823399169;4720.622350211975;4736.881577960509;4739.052189562032;4176.206520761046;4179.9791001045405;4187.790610469464;4190.622843944059;4205.495340559091;5722.37053924068;5765.625234359753;5839.824805255849;5842.649441033466;5846.269216347957;6155.261368635793;6156.138140660311;6190.876182476325;6200.038993955944;6209.813705588839;5713.942860571424;5729.398413166641;5733.598648094593;5754.251947246831;5777.2200752897825;5120.18077107661;5177.534021938043;5299.231611416323;5300;5301.1783173608155;6455.9677201613695;6458.612483250926;6464.062710906892;6470.12653291809;6476.190476190476;6083;6135.63186208811;6155.107509411021;6158.599691020069;6171.981623032149;6148.323684394623;6292.528060395397;6306.337834527309;6320.1151838743535;6466.773662921775;6050.273967123973;6051.3041000283765;6055.9091613626715;6062.666553339609;6091.7563297467605;6113.877722445615;6123.8162855114415;6127.816165515042;6130.908036379368;6136.539759517965;5777.94222057774;5885.352611212677;5958.433948774859;5968.313643931004;6016;5965.164876917148;5977.491913187254;5984.640921544721;5984.910226346521;5995.430434108753;5903.26209223847;5911;5950.761969521171;5968.970155149196;5981.551383646157;5782.913256301243;5834.18321435008;5914.083317085859;5941.851453713692;6052.515798736003;5890.204822348928;5909.202257695156;5917.260342457193;5931.614445061057;5951.791687290924 19:28:42 INFO - PROCESS | 4887 | |1;ext.html;597.871457636608;628.5819929746718;642.2389468479852;649.4641920415652;655.3512023097134;34812.477812832825;38746;47032.5296747032;47981.520184798144;48052;48003.75998120005;48464.515354846444;48465.51534484654;48475.03049938999;48546.27180592294;9289.396189247695;9449.480278584675;9454.716358509222;9460.385074970105;9462.29032822541;8494.447860889053;11049.723756906062;11324.943375283112;11331.093512518979;11737.295762254249;11456.770864582704;11494.310341379502;11533;11538.826917596201;11558.30650160993;11130.38782866942;11225.887741122566;11281.210315277935;11291.717707057307;11294.94352528232;11167.050800681951;11242.887571124265;11243.83134252987;11259.774804503946;11266.549338026469;46045.53954460446;46190.76904615489;46243.30635040477;46433.30350044752;46576.7671161642;3851.287511810296;3910.354791459406;3967.4247234151176;3968.226195891802;4042.898927526806;34932.6506734932;35216.295674086374;35251.82374088139;35532.82233588841;35802.46296305557;4707.152712511746;4766.237402015661;4790.592799612037;4791.113643975876;4812.566868981773;398944;402206;402363;402666.9999999971;403136;66021.33978660151;66808;66848.33151668518;66873.66563167152;66939.33060669429;39377.40933885994;40184.799076004434;40188.39717404241;71666.283337167;71825.6408717953;19058.332958346393;19513.414597562096;19529.414117576496;19533.90233048854;19595.11821968015;12924.741505169844;14504.202268875102;14862.628434289012;14973.925130374497;14974.47589334368;20254.392368228975;21058.89470552668;21169.15323387078;21207.575848482946;21277.68083478749;7466.290702383265;7482.5884576347735;7571;7577.734779282699;7607.869796457001;1085.5115198160859;1089.1341383600056;1125.431657013203;1129.1474936422949;1185.3421351150193;35924.820375898475;36209.094772630895;36352;36428.98921677894;36557.817210913774;1162.3548930343572;1175.8177482490228;1232.5193174661888;1272.1540175783186;1291.6189724031433;17815.46553603394;18525.81474185241;18783.436496905113;18927;18930.148143333307;173938;174741;175359;175419.12290438722;175826;65742.67128664326;66463.66768166129;66641.33358666448;66729;66819.33180668131;1329.8803107720353;1437.1879887863324;1446.3780574352918;1451.1293224065687;1465.2234315812636;34643.4803477943;36797.81601091977;37240.441393378576;37378.813105935384;37671;55469;56310.7184464075;56404.43595563992;56489;56519;425195;426631;427547;429636;430316;30725.692743072283;31773.20566985844;32055.198620034687;33396.66603333935;33411.164720882174;52652.47347526476;53026.469735302155;53756.46243537671;53784.19323710069;54083;43318;44753.55246447494;45581.77209113933;46663;48444.273335900696;45878.08243835171;45964;47046;47070.05859882715;48026.27960580664;46519.246519246466;47252.29121563111;47253.7637311825;47515.76242118906;48097.5190248107;38555.22889542249;40480.59519404768;40501.39247911343;40858.38712419375;41386;31190.22024449407;31319.671517359795;31675.366492670477;31831.681683182873;31857 19:28:42 INFO - PROCESS | 4887 | |2;jquery.html;399.7821187452838;419.55736697783834;419.68733293696204;422.8646833013436;442.37845826613597;15004.624884377883;19913.900430497844;20247.696284555743;20427.48931276717;20988.58022839542;20612.89693551534;20770.480737981557;20790.792092079075;20837.270695525658;21031.47421314464;5894.764209431618;5896.675682837442;5948.16725658406;5981.252343457068;6060.848478788043;5969.253843269591;5989.760409583611;5999.6700181490005;6008.128821320927;6012.398760123996;5845.7953971611005;5907.202527658776;5959.284885813709;5973.044312909935;5982.491488223484;5911.408859114097;5961.403859614025;5965.433283838029;5967.820965371024;5984.970075149618;5245.924585459971;5357.839264822022;5359.38367087786;5360.2763626910255;5360.463953604647;15897.841021589751;22256.221032263795;23147.7685223149;23168.18911338095;24015.879920600284;2377.7859992600747;2546.490701859617;2638.6965498967675;2649.5363311420615;3178.475551533994;10733;11129.554817807279;11182.32906025641;11409.315441073479;12862.807057894139;2005.8896760678158;2014.8388128949648;2045.5499790046167;2077.0549400022956;2079.916803327865;72383;80842.59578702127;84824.57587712083;86121.56939215264;86168.56915715382;11413.086953043736;11543.307401555765;11809.346691463203;11876.88123118775;11972.461239244254;16698.415555455744;16721.74917376216;16797.41209057677;16814.66370672604;16890.83109168917;7598.240175982468;8252.33981281496;8270.958645206736;8407.8318433631;8460.196281353261;7623.275788800055;7791.003398759904;7826.099998500245;7857.646405911748;7979.3616510679;4999.5500404963;5016.949830501721;5032.32063671407;5145.691258524501;5217.530422262014;2001.7297664815355;2088.488320361491;2105.147415296807;2123.193186589086;2151.5804418138314;959.9856002159974;1047.8061558611682;1072.9892701073045;1096.3915027159844;1139.6353166986485;20953.476163096046;21055.368338949855;21291;21381.144754209967;21409.143634254455;1064.0689396777818;1081.6917178604217;1099.5217080569978;1122.9719257018476;1149.1438878035917;11514.827277590843;12470.43883025266;12486.750264994831;12770.68073298175;12885.613431597067;492963;499143;503724;508308;511685;79240.60379698065;81713.18286817175;96044;99571;99716.50141749244;1068.4016950507585;1078.2991055813627;1098.3629494893103;1124.241137232365;1127.8139107047245;18283.634327313644;18509.444716658523;19014.619707605492;19038.143283551995;19320;42485;42858.35712464378;43524;43579.782101089295;44345.77827110844;60254.397456024875;61515.692421537606;61598;61928.690356549734;62485.375146247956;9032.774180645536;9033.59348829291;9111.498867562348;9140.360174787702;9349;10441.947790261303;13941.58175254744;14097.295135243401;14204.720343947998;14408.927955360155;11875.406229688653;12324.938375308066;12401.193922395016;12562.246265224116;12759.106862519906;12009.039276857655;12015.339156346485;12199.695007624525;12374.257544547356;12482.625521234377;12414.751704965669;12427.254364738144;12496.158764460291;12830.358482076046;12952.222866628032;8771.764705882353;8947.194752472049;9049.366544341832;9176.311776616914;9329.673461428818;11999.76000480003;12004;12219.45012474423;12523.561675341683;13108.541201057917 19:28:42 INFO - PROCESS | 4887 | |3;mootools.html;1528.5872814340132;1571.5206861907118;1715.5282297368221;1867.729179269006;1874.9906250468746;9409.576569054392;10154.74613134671;10158.54286557105;10160.339577927449;10169.644062457815;8668.39321247513;8860.246879015276;8864.46813191209;9263.722088337343;9950.402975821456;2485.4407758254383;2541.987290063547;2557.5907854743245;2563.256655569892;2563.5641940870005;2536.581464058428;2564.358910272432;2599.1033093582682;2603.609458581209;2605.947881042378;2525.1162093267394;2533.40464990727;2550.4516528946274;2581.367564946591;2594.9740502594923;2446.8409553378974;2455.7789798918184;2456.7297597264287;2487.092211342865;2545.8599777012255;2510.184190138212;2511.4977004599154;2512.133314006683;2516.7609077137645;2522.6846644169477;9693.806123877554;9765.16996055329;9796.118349348522;9889.851652225223;9922;5584.46947539983;5733.627314224562;5734.311882574076;5750.971245143747;5752.482276595127;3796.52543432071;3821.9235615287816;3841.4045822897497;3851.4607954886337;3872.961270387288;1226.0988173692417;1234.802431610947;1237.6472705278952;1257.792464243408;1261.9495220191181;14193.858061419356;14375;14387.352569134415;14449.855501445061;14456;4902.142125128088;4980.90038199234;5188.766505507261;5196.376434827845;5218.399884013311;12162.452689628988;12165.148439609142;12183.269003859798;12191.207571507824;12191.695207619881;5853.385394533512;5931.347551769302;5937.287525496968;5937.554683398763;5937.584369094208;4426.667999900021;5361;5364;5437.782488700487;5443.101888188484;4390.47314322277;4397.31841564558;4474.552544745499;4502.909941801146;4542.409486766699;1337.625464869838;1372.8695773901463;1386.2999185411302;1390.5480718766441;1397.9301034948212;1084.8698156221303;1088.6625146204701;1089.2810744908481;1098.1873413673825;1106.2422240765102;6644.933550664528;6694.364035416627;6716.194056713226;6910.44716422685;7011.123609548807;492.95070492951135;505.4339140163041;508.4204007431531;511.94624564420945;519.3559985617807;4583.564561366665;4623.237165867661;4701.7414042228;4750.476039352622;4777.593904518086;12323.507059717691;12691.555795547112;12803.61589152327;12817.743645127232;12826.61520154397;11798.41007949599;12571.93714031424;12702.36488175606;12711.872881271254;12727;8876.378653494194;9061.77345566366;9080.909190908138;9085.591148398338;9117.225035872025;6746.325367463214;6829.863402732017;6865.828354291183;6874.759383421756;6960.547564408297;11115.610953616908;11389.373584452935;11396.487158078073;11417.029552488131;11446.771064578828;11047.558097676003;11754.118441117122;11760.823587646364;11772.705682357668;11851.822222666495;7846.764597062097;8505.234528892182;8642.740717778477;8947.776305592412;8979.910200898168;9308.44149351041;9676.22590192797;9678.370905891095;9680;9685.70942871715;8027.317677997436;8031.59842007909;8299.543525106177;8365.581720913808;8447;8380.706675266336;8392.412531122764;8451.32389408858;8477.109903460294;8609.569521524025;7726.575038372944;7798.142204357403;7809.609519523888;7827.569483678452;7874.330681892104;5649.71751412436;5663.377028526941;5687.374388817309;5693.9430605693415;5850;5756.94243057564;5849.356570777126;5900.793472228452;5950.553708471968;5979.431953964367 19:28:42 INFO - PROCESS | 4887 | |4;prototype.html;349.57875759709555;358.54643875497516;361.1693105856531;361.3567849228373;362.8657396763197;5414.187871819229;5470.617056806015;5487.725613719313;5490.752916118774;5507.311586051744;5430.321209848769;5435.1847222916585;5439.401665816767;5439.972800136004;5457.781688732446;3819.2743378758087;4084.019835239543;4283.400323954649;4316.395704601358;4325.113351762897;4274.29474136767;4277.443932288798;4283.850065247717;4285.250081235787;4291.313389857623;4082.5713300103507;4267.274563324242;4274.4229529013655;4279.914401711964;4293.840663020983;3992.7205095643026;4205.705600607958;4235.597618226264;4297.656187504992;4320.524742278347;3810.733248672594;3814.141818090935;3817.4846395736777;3841.135744457503;3861.2663593917064;5238.511706939212;5286.365636123652;5291.777453469552;5323.813666521692;5332.693490094913;4890.510948905081;4901.8529444116375;4914.6068314535105;4929.950700493021;4949.158643030693;5619;5619.522340601054;5629.971850140764;5645.2096706461125;5665.688387138705;4130.6282434580735;4186.455760751083;4216.831326746927;4217.683673724483;4219.556946520633;5531.7234138293325;5680.971595142039;5835.970820145914;5846.619969701998;5876.2067120938555;4250.681198910094;4250.936235956463;4270.551592082787;4296.205202037633;4297.290946993773;4336.132773445323;4357.8256869724855;4358.934615980763;4373.562643735665;4378.168148051924;4804.735739534359;4839.872514555934;4950.108980383566;4951.90096198074;4972.90054198914;5631.465010823965;5647.858803529944;5677.034904066319;5678.744456499468;5686.9715651421475;1487.315834716021;1509.3208056374676;1511.3878879054;1511.5314252581516;1511.9697606047816;940.5374642430949;981.8723565936383;992.712113487095;995.7560397702609;1005.3967619428431;4879.658423910292;4936.481669424658;5001.0998020355955;5005.299258103868;5042.722650254272;5090.363704536933;5101.795928162832;5131.332926719577;5159.922601160985;5167.442116152529;5019.422766381913;5056.241563765465;5063.493650634907;5264.236685680555;5695.601307908407;3126.9530957035663;3135.357251763383;3136.4944826483415;3148.843042515884;3152.7004934531183;6034.922189683293;6181.227346581677;6190;6193.213054125661;6203.627782333075;5930.525557955353;5940;5950.196723442366;5952.755748117286;5985.6408615483215;6064.575479716465;6124.601900876429;6164.291106522717;6182.536309776785;6195.473384762346;5599.412061733622;5811.912821307599;5872.236609240771;5912.408759124053;5931.792387266425;5795.478406943395;5846.386129456346;5869.354371019099;5906.616069955439;5911.172435858897;5971.701414929323;6072.180255665428;6092.847678808065;6126.142340072304;6264.937350626436;4309.267424537773;4377.496587892306;4389.978050109729;4399.912001760011;4404.603585677304;5712.742926568231;5908.497777688945;5929.644221346733;5933.525317974638;5934.703264836828;5661.858453538694;5678.290213723285;5693.601447898607;5702.6293290935955;5722.713864306685;5646.971765141148;5651.124075768263;5652.558597557597;5676.460736230051;5693.259876216065;5413.160960051198;5616.494515493625;5619.157126431068;5649.406812284651;5695.943040569541;5686.488216060575;5695.401982791747;5695.430456954271;5702.258706368311;5719.971400142973;5550.8057217997175;5555.888702326549;5564.582656300713;5580.525655319181;5606.327240730975 19:28:42 INFO - PROCESS | 4887 | |5;yui.html;807;811.1604489353521;856.327782690588;876.631814637852;925.9489484860087;14334.569962901116;14987.400503979828;15034.77447838282;15151.924240378796;15292;15008.849911500882;15137.091774493538;15210.315535800888;15224.08655480672;15244.085354878714;739.7447880481226;872.0320444306815;881.5900606218107;892.8973168085656;899.6266549381999;901.33301356996;901.8962819275769;902.6975963052386;904.055262250946;905.2395987370608;942.3309450290268;948.042477098129;950.5294879034888;951.4719330771419;951.5717926932873;763.6410886883156;783.5416281475344;785.7289235213899;894.8612964990436;946.8627049077879;672.4015626092781;676.0737789228739;676.2155899156954;676.5805200775551;676.9356911093438;15383.615409614737;15403;15425.228738563026;15426.460073897471;15447.30487128082;15701.842981570153;15707.136107514083;15909.363625454967;15938.521844344688;16157.272922718506;14269.78595321071;14492.637684057878;14531.927340363336;14660.266986650624;14707.558773236713;4802.35168252285;4842.346283251751;4866.6106711463;4952.133376659106;4964.131277026542;87860.56069719611;88121;88802.55598721965;90731.54634226918;91541;11602.18784685064;11746.29522228669;11816.763664726657;11818.290902545874;12137.393130343447;21918;22198.889005555193;22238.555228895333;22298.665520017214;22300.442488937908;8479.06730259671;8877.20105190536;9043.095690430904;9114.772130696652;9180.954095229481;4913.606911447076;5085.466026067284;5104.3109180260935;5198.636095473281;5286.127788914786;3712.9628703712615;3838.05967537955;3838.443425703257;3839.788811615332;3841.5390153181797;648.7891435283552;664.4153145232165;665.3446355339986;680.7583307925714;688.7245101959256;615.7383112177307;673.6497021548865;702.7154002628944;706.057413353169;747.8952946587482;9482.620695172116;9743.951280243553;10273.794524109477;10302.639407620698;10380.32527885685;674.8515326628134;676.6008055247329;709.4714437743913;716.0941409117471;763.3015790551694;4786.760661966888;4860.708357498562;4910.116179087799;4922.4339200992345;4976.2038073908;114665;129959;131192;132724;137670;112530.43734781437;115427;120631;120732.39633801774;120808;103053;104462;104493.47753261337;104517.47741261245;105647;9599.136077753035;10055.798884022424;10148.797024059626;10193.541290641791;10211.897881021094;57131.71473141028;57329;57519.42480575308;58684;60414.39585604263;77889.61055194688;78513;80763.19236807557;81187;82609;12388.690282743004;12449.688757781129;12582.18215815969;12618.747625047632;12716;12562.560310389092;12635.305058221518;12666;12849.743005140032;12992.090553661154;12013;12471.314077725448;13389.608065382106;13474.056816022785;13680.315984200552;12757.298348590546;12881.871181288067;13002.674933126747;13074.215547067206;13168.996690213977;12988.805167922677;13038.348082595643;13908;13967;13998.090124141898;9963.402195868272;10226.130778883879;10688.519016644113;10719.624813131815;10765.46172691378;11088.390138542134;11235.4382280884;11350.716232094264;11517;11526.423678816192 19:28:42 INFO - PROCESS | 4887 | __end_tp_report 19:28:42 INFO - PROCESS | 4887 | __start_cc_report 19:28:42 INFO - PROCESS | 4887 | _x_x_mozilla_cycle_collect,1170 19:28:42 INFO - PROCESS | 4887 | __end_cc_report 19:28:42 INFO - PROCESS | 4887 | __startTimestamp1471660122246__endTimestamp 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | ------- Summary: start ------- 19:28:42 INFO - PROCESS | 4887 | Number of tests: 6 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#0] dojo.html Cycles:165 Average:5669.33 Median:5881.91 stddev:646.17 (11.0%) stddev-sans-first:619.68 19:28:42 INFO - PROCESS | 4887 | Values: 3251.1 3261.8 3268.7 3296.4 3313.4 5584.1 5680.8 5684.3 5684.9 5690.9 5692.5 5760.0 5767.4 5777.7 5785.0 5032.5 5096.4 5229.5 5230.5 5278.0 5110.8 5132.3 5143.3 5166.4 5181.1 5168.4 5264.0 5265.3 5277.6 5278.2 5194.6 5217.9 5251.1 5266.6 5287.9 4855.4 4991.2 5147.9 5149.3 5249.4 5743.1 5753.4 5755.1 5758.1 5784.0 6114.6 6116.2 6163.4 6173.9 6182.1 5936.2 6015.3 6066.1 6078.7 6210.8 5721.2 5727.3 5727.5 5738.8 5748.2 6227.3 6234.0 6241.2 6241.4 6248.0 5815.2 5828.6 5852.7 5888.3 6026.1 6035.1 6085.7 6089.7 6089.9 6093.1 5963.6 5990.8 6016.6 6021.0 6022.8 5832.4 5881.9 5969.6 5975.5 6023.6 4714.0 4716.2 4720.6 4736.9 4739.1 4176.2 4180.0 4187.8 4190.6 4205.5 5722.4 5765.6 5839.8 5842.6 5846.3 6155.3 6156.1 6190.9 6200.0 6209.8 5713.9 5729.4 5733.6 5754.3 5777.2 5120.2 5177.5 5299.2 5300.0 5301.2 6456.0 6458.6 6464.1 6470.1 6476.2 6083.0 6135.6 6155.1 6158.6 6172.0 6148.3 6292.5 6306.3 6320.1 6466.8 6050.3 6051.3 6055.9 6062.7 6091.8 6113.9 6123.8 6127.8 6130.9 6136.5 5777.9 5885.4 5958.4 5968.3 6016.0 5965.2 5977.5 5984.6 5984.9 5995.4 5903.3 5911.0 5950.8 5969.0 5981.6 5782.9 5834.2 5914.1 5941.9 6052.5 5890.2 5909.2 5917.3 5931.6 5951.8 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#1] ext.html Cycles:180 Average:53865.46 Median:3967.83 stddev:93240.61 (2349.9%) stddev-sans-first:93416.40 19:28:42 INFO - PROCESS | 4887 | Values: 597.9 628.6 642.2 649.5 655.4 34812.5 38746.0 47032.5 47981.5 48052.0 48003.8 48464.5 48465.5 48475.0 48546.3 9289.4 9449.5 9454.7 9460.4 9462.3 8494.4 11049.7 11324.9 11331.1 11737.3 11456.8 11494.3 11533.0 11538.8 11558.3 11130.4 11225.9 11281.2 11291.7 11294.9 11167.1 11242.9 11243.8 11259.8 11266.5 46045.5 46190.8 46243.3 46433.3 46576.8 3851.3 3910.4 3967.4 3968.2 4042.9 34932.7 35216.3 35251.8 35532.8 35802.5 4707.2 4766.2 4790.6 4791.1 4812.6 398944.0 402206.0 402363.0 402667.0 403136.0 66021.3 66808.0 66848.3 66873.7 66939.3 39377.4 40184.8 40188.4 71666.3 71825.6 19058.3 19513.4 19529.4 19533.9 19595.1 12924.7 14504.2 14862.6 14973.9 14974.5 20254.4 21058.9 21169.2 21207.6 21277.7 7466.3 7482.6 7571.0 7577.7 7607.9 1085.5 1089.1 1125.4 1129.1 1185.3 35924.8 36209.1 36352.0 36429.0 36557.8 1162.4 1175.8 1232.5 1272.2 1291.6 17815.5 18525.8 18783.4 18927.0 18930.1 173938.0 174741.0 175359.0 175419.1 175826.0 65742.7 66463.7 66641.3 66729.0 66819.3 1329.9 1437.2 1446.4 1451.1 1465.2 34643.5 36797.8 37240.4 37378.8 37671.0 55469.0 56310.7 56404.4 56489.0 56519.0 425195.0 426631.0 427547.0 429636.0 430316.0 30725.7 31773.2 32055.2 33396.7 33411.2 52652.5 53026.5 53756.5 53784.2 54083.0 43318.0 44753.6 45581.8 46663.0 48444.3 45878.1 45964.0 47046.0 47070.1 48026.3 46519.2 47252.3 47253.8 47515.8 48097.5 38555.2 40480.6 40501.4 40858.4 41386.0 31190.2 31319.7 31675.4 31831.7 31857.0 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#2] jquery.html Cycles:180 Average:29934.96 Median:22701.99 stddev:82879.03 (365.1%) stddev-sans-first:83081.86 19:28:42 INFO - PROCESS | 4887 | Values: 399.8 419.6 419.7 422.9 442.4 15004.6 19913.9 20247.7 20427.5 20988.6 20612.9 20770.5 20790.8 20837.3 21031.5 5894.8 5896.7 5948.2 5981.3 6060.8 5969.3 5989.8 5999.7 6008.1 6012.4 5845.8 5907.2 5959.3 5973.0 5982.5 5911.4 5961.4 5965.4 5967.8 5985.0 5245.9 5357.8 5359.4 5360.3 5360.5 15897.8 22256.2 23147.8 23168.2 24015.9 2377.8 2546.5 2638.7 2649.5 3178.5 10733.0 11129.6 11182.3 11409.3 12862.8 2005.9 2014.8 2045.5 2077.1 2079.9 72383.0 80842.6 84824.6 86121.6 86168.6 11413.1 11543.3 11809.3 11876.9 11972.5 16698.4 16721.7 16797.4 16814.7 16890.8 7598.2 8252.3 8271.0 8407.8 8460.2 7623.3 7791.0 7826.1 7857.6 7979.4 4999.6 5016.9 5032.3 5145.7 5217.5 2001.7 2088.5 2105.1 2123.2 2151.6 960.0 1047.8 1073.0 1096.4 1139.6 20953.5 21055.4 21291.0 21381.1 21409.1 1064.1 1081.7 1099.5 1123.0 1149.1 11514.8 12470.4 12486.8 12770.7 12885.6 492963.0 499143.0 503724.0 508308.0 511685.0 79240.6 81713.2 96044.0 99571.0 99716.5 1068.4 1078.3 1098.4 1124.2 1127.8 18283.6 18509.4 19014.6 19038.1 19320.0 42485.0 42858.4 43524.0 43579.8 44345.8 60254.4 61515.7 61598.0 61928.7 62485.4 9032.8 9033.6 9111.5 9140.4 9349.0 10441.9 13941.6 14097.3 14204.7 14408.9 11875.4 12324.9 12401.2 12562.2 12759.1 12009.0 12015.3 12199.7 12374.3 12482.6 12414.8 12427.3 12496.2 12830.4 12952.2 8771.8 8947.2 9049.4 9176.3 9329.7 11999.8 12004.0 12219.5 12523.6 13108.5 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#3] mootools.html Cycles:180 Average:6505.43 Median:4603.40 stddev:3810.16 (82.8%) stddev-sans-first:3802.49 19:28:42 INFO - PROCESS | 4887 | Values: 1528.6 1571.5 1715.5 1867.7 1875.0 9409.6 10154.7 10158.5 10160.3 10169.6 8668.4 8860.2 8864.5 9263.7 9950.4 2485.4 2542.0 2557.6 2563.3 2563.6 2536.6 2564.4 2599.1 2603.6 2605.9 2525.1 2533.4 2550.5 2581.4 2595.0 2446.8 2455.8 2456.7 2487.1 2545.9 2510.2 2511.5 2512.1 2516.8 2522.7 9693.8 9765.2 9796.1 9889.9 9922.0 5584.5 5733.6 5734.3 5751.0 5752.5 3796.5 3821.9 3841.4 3851.5 3873.0 1226.1 1234.8 1237.6 1257.8 1261.9 14193.9 14375.0 14387.4 14449.9 14456.0 4902.1 4980.9 5188.8 5196.4 5218.4 12162.5 12165.1 12183.3 12191.2 12191.7 5853.4 5931.3 5937.3 5937.6 5937.6 4426.7 5361.0 5364.0 5437.8 5443.1 4390.5 4397.3 4474.6 4502.9 4542.4 1337.6 1372.9 1386.3 1390.5 1397.9 1084.9 1088.7 1089.3 1098.2 1106.2 6644.9 6694.4 6716.2 6910.4 7011.1 493.0 505.4 508.4 511.9 519.4 4583.6 4623.2 4701.7 4750.5 4777.6 12323.5 12691.6 12803.6 12817.7 12826.6 11798.4 12571.9 12702.4 12711.9 12727.0 8876.4 9061.8 9080.9 9085.6 9117.2 6746.3 6829.9 6865.8 6874.8 6960.5 11115.6 11389.4 11396.5 11417.0 11446.8 11047.6 11754.1 11760.8 11772.7 11851.8 7846.8 8505.2 8642.7 8947.8 8979.9 9308.4 9676.2 9678.4 9680.0 9685.7 8027.3 8031.6 8299.5 8365.6 8447.0 8380.7 8392.4 8451.3 8477.1 8609.6 7726.6 7798.1 7809.6 7827.6 7874.3 5649.7 5663.4 5687.4 5693.9 5850.0 5756.9 5849.4 5900.8 5950.6 5979.4 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#4] prototype.html Cycles:180 Average:4791.49 Median:5413.67 stddev:1386.68 (25.6%) stddev-sans-first:1349.89 19:28:42 INFO - PROCESS | 4887 | Values: 349.6 358.5 361.2 361.4 362.9 5414.2 5470.6 5487.7 5490.8 5507.3 5430.3 5435.2 5439.4 5440.0 5457.8 3819.3 4084.0 4283.4 4316.4 4325.1 4274.3 4277.4 4283.9 4285.3 4291.3 4082.6 4267.3 4274.4 4279.9 4293.8 3992.7 4205.7 4235.6 4297.7 4320.5 3810.7 3814.1 3817.5 3841.1 3861.3 5238.5 5286.4 5291.8 5323.8 5332.7 4890.5 4901.9 4914.6 4930.0 4949.2 5619.0 5619.5 5630.0 5645.2 5665.7 4130.6 4186.5 4216.8 4217.7 4219.6 5531.7 5681.0 5836.0 5846.6 5876.2 4250.7 4250.9 4270.6 4296.2 4297.3 4336.1 4357.8 4358.9 4373.6 4378.2 4804.7 4839.9 4950.1 4951.9 4972.9 5631.5 5647.9 5677.0 5678.7 5687.0 1487.3 1509.3 1511.4 1511.5 1512.0 940.5 981.9 992.7 995.8 1005.4 4879.7 4936.5 5001.1 5005.3 5042.7 5090.4 5101.8 5131.3 5159.9 5167.4 5019.4 5056.2 5063.5 5264.2 5695.6 3127.0 3135.4 3136.5 3148.8 3152.7 6034.9 6181.2 6190.0 6193.2 6203.6 5930.5 5940.0 5950.2 5952.8 5985.6 6064.6 6124.6 6164.3 6182.5 6195.5 5599.4 5811.9 5872.2 5912.4 5931.8 5795.5 5846.4 5869.4 5906.6 5911.2 5971.7 6072.2 6092.8 6126.1 6264.9 4309.3 4377.5 4390.0 4399.9 4404.6 5712.7 5908.5 5929.6 5933.5 5934.7 5661.9 5678.3 5693.6 5702.6 5722.7 5647.0 5651.1 5652.6 5676.5 5693.3 5413.2 5616.5 5619.2 5649.4 5695.9 5686.5 5695.4 5695.4 5702.3 5720.0 5550.8 5555.9 5564.6 5580.5 5606.3 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - PROCESS | 4887 | [#5] yui.html Cycles:180 Average:23084.26 Median:13006.70 stddev:34855.74 (268.0%) stddev-sans-first:34913.38 19:28:42 INFO - PROCESS | 4887 | Values: 807.0 811.2 856.3 876.6 925.9 14334.6 14987.4 15034.8 15151.9 15292.0 15008.8 15137.1 15210.3 15224.1 15244.1 739.7 872.0 881.6 892.9 899.6 901.3 901.9 902.7 904.1 905.2 942.3 948.0 950.5 951.5 951.6 763.6 783.5 785.7 894.9 946.9 672.4 676.1 676.2 676.6 676.9 15383.6 15403.0 15425.2 15426.5 15447.3 15701.8 15707.1 15909.4 15938.5 16157.3 14269.8 14492.6 14531.9 14660.3 14707.6 4802.4 4842.3 4866.6 4952.1 4964.1 87860.6 88121.0 88802.6 90731.5 91541.0 11602.2 11746.3 11816.8 11818.3 12137.4 21918.0 22198.9 22238.6 22298.7 22300.4 8479.1 8877.2 9043.1 9114.8 9181.0 4913.6 5085.5 5104.3 5198.6 5286.1 3713.0 3838.1 3838.4 3839.8 3841.5 648.8 664.4 665.3 680.8 688.7 615.7 673.6 702.7 706.1 747.9 9482.6 9744.0 10273.8 10302.6 10380.3 674.9 676.6 709.5 716.1 763.3 4786.8 4860.7 4910.1 4922.4 4976.2 114665.0 129959.0 131192.0 132724.0 137670.0 112530.4 115427.0 120631.0 120732.4 120808.0 103053.0 104462.0 104493.5 104517.5 105647.0 9599.1 10055.8 10148.8 10193.5 10211.9 57131.7 57329.0 57519.4 58684.0 60414.4 77889.6 78513.0 80763.2 81187.0 82609.0 12388.7 12449.7 12582.2 12618.7 12716.0 12562.6 12635.3 12666.0 12849.7 12992.1 12013.0 12471.3 13389.6 13474.1 13680.3 12757.3 12881.9 13002.7 13074.2 13169.0 12988.8 13038.3 13908.0 13967.0 13998.1 9963.4 10226.1 10688.5 10719.6 10765.5 11088.4 11235.4 11350.7 11517.0 11526.4 19:28:42 INFO - PROCESS | 4887 | -------- Summary: end -------- 19:28:42 INFO - PROCESS | 4887 | 19:28:42 INFO - TEST-INFO | 4887: exit 0 19:28:42 INFO - TEST-OK | dromaeo_css | took 1112953ms 19:28:42 INFO - TEST-START | kraken 19:28:42 INFO - operating with platform_type : linux_ 19:28:42 INFO - Initialising browser for kraken test... 19:28:42 INFO - TEST-INFO | started process 5099 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8EEo7d/profile http://localhost:34812/getInfo.html) 19:28:46 INFO - PROCESS | 5099 | __metrics Screen width/height:1600/1200 19:28:46 INFO - PROCESS | 5099 | colorDepth:24 19:28:46 INFO - PROCESS | 5099 | Browser inner width/height: 1024/697 19:28:46 INFO - PROCESS | 5099 | __metrics 19:28:47 INFO - PROCESS | 5099 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:28:47 INFO - PROCESS | 5099 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:28:48 INFO - TEST-INFO | 5099: exit 0 19:28:48 INFO - Browser initialized. 19:28:48 INFO - Running cycle 1/1 for kraken test... 19:28:48 INFO - TEST-INFO | started process 5189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8EEo7d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:29:00 INFO - PROCESS | 5189 | RSS: Main: 145448960 19:29:00 INFO - PROCESS | 5189 | 19:29:02 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34812/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:03 INFO - PROCESS | 5189 | RSS: Main: 191283200 19:29:03 INFO - PROCESS | 5189 | 19:29:06 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34812/tests/kraken/driver.html?testName=audio-dft) 19:29:06 INFO - PROCESS | 5189 | RSS: Main: 181878784 19:29:06 INFO - PROCESS | 5189 | 19:29:09 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34812/tests/kraken/driver.html?testName=audio-fft) 19:29:10 INFO - PROCESS | 5189 | RSS: Main: 270934016 19:29:10 INFO - PROCESS | 5189 | 19:29:12 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34812/tests/kraken/driver.html?testName=audio-oscillator) 19:29:12 INFO - PROCESS | 5189 | RSS: Main: 175783936 19:29:12 INFO - PROCESS | 5189 | 19:29:15 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34812/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:15 INFO - PROCESS | 5189 | RSS: Main: 176852992 19:29:15 INFO - PROCESS | 5189 | 19:29:18 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34812/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:19 INFO - PROCESS | 5189 | RSS: Main: 212815872 19:29:19 INFO - PROCESS | 5189 | 19:29:22 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34812/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:23 INFO - PROCESS | 5189 | RSS: Main: 211701760 19:29:23 INFO - PROCESS | 5189 | 19:29:26 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34812/tests/kraken/driver.html?testName=json-parse-financial) 19:29:26 INFO - PROCESS | 5189 | RSS: Main: 211517440 19:29:26 INFO - PROCESS | 5189 | 19:29:28 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34812/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:28 INFO - PROCESS | 5189 | RSS: Main: 175661056 19:29:28 INFO - PROCESS | 5189 | 19:29:30 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:31 INFO - PROCESS | 5189 | RSS: Main: 182317056 19:29:31 INFO - PROCESS | 5189 | 19:29:33 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:33 INFO - PROCESS | 5189 | RSS: Main: 186994688 19:29:33 INFO - PROCESS | 5189 | 19:29:36 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:36 INFO - PROCESS | 5189 | RSS: Main: 180899840 19:29:36 INFO - PROCESS | 5189 | 19:29:39 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:40 INFO - PROCESS | 5189 | RSS: Main: 184336384 19:29:40 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | Cycle 1(1): loaded http://localhost:34812/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34812/tests/kraken/driver.html?testName=ai-astar) 19:29:41 INFO - PROCESS | 5189 | RSS: Main: 235929600 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | __start_tp_report 19:29:41 INFO - PROCESS | 5189 | _x_x_mozilla_page_load 19:29:41 INFO - PROCESS | 5189 | _x_x_mozilla_page_load_details 19:29:41 INFO - PROCESS | 5189 | |i|pagename|runs| 19:29:41 INFO - PROCESS | 5189 | |0;ai-astar;101;110;111;111;112;111;110;111;112;111 19:29:41 INFO - PROCESS | 5189 | |1;audio-beat-detection;139;119;116;176;117;117;150;115;149;117 19:29:41 INFO - PROCESS | 5189 | |2;audio-dft;159;161;160;161;160;163;162;163;162;159 19:29:41 INFO - PROCESS | 5189 | |3;audio-fft;75;75;74;114;75;77;73;75;97;74 19:29:41 INFO - PROCESS | 5189 | |4;audio-oscillator;83;83;134;83;82;83;131;85;83;122 19:29:41 INFO - PROCESS | 5189 | |5;imaging-gaussian-blur;118;117;116;116;117;116;116;116;116;117 19:29:41 INFO - PROCESS | 5189 | |6;imaging-darkroom;147;146;146;146;147;146;146;146;148;147 19:29:41 INFO - PROCESS | 5189 | |7;imaging-desaturate;95;94;94;95;95;96;95;95;94;95 19:29:41 INFO - PROCESS | 5189 | |8;json-parse-financial;52;52;54;51;50;52;51;50;51;51 19:29:41 INFO - PROCESS | 5189 | |9;json-stringify-tinderbox;61;59;60;59;60;58;59;60;59;60 19:29:41 INFO - PROCESS | 5189 | |10;stanford-crypto-aes;72;73;81;72;83;73;72;72;72;76 19:29:41 INFO - PROCESS | 5189 | |11;stanford-crypto-ccm;123;121;125;127;119;117;119;131;165;126 19:29:41 INFO - PROCESS | 5189 | |12;stanford-crypto-pbkdf2;155;153;158;152;157;151;154;153;154;152 19:29:41 INFO - PROCESS | 5189 | |13;stanford-crypto-sha256-iterative;66;62;63;62;62;62;65;62;64;62 19:29:41 INFO - PROCESS | 5189 | __end_tp_report 19:29:41 INFO - PROCESS | 5189 | __start_cc_report 19:29:41 INFO - PROCESS | 5189 | _x_x_mozilla_cycle_collect,1622 19:29:41 INFO - PROCESS | 5189 | __end_cc_report 19:29:41 INFO - PROCESS | 5189 | __startTimestamp1471660181933__endTimestamp 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | ------- Summary: start ------- 19:29:41 INFO - PROCESS | 5189 | Number of tests: 14 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:3.23 (2.9%) stddev-sans-first:0.71 19:29:41 INFO - PROCESS | 5189 | Values: 101.0 110.0 111.0 111.0 112.0 111.0 110.0 111.0 112.0 111.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:129.00 stddev:21.04 (16.3%) stddev-sans-first:22.14 19:29:41 INFO - PROCESS | 5189 | Values: 139.0 119.0 116.0 176.0 117.0 117.0 150.0 115.0 149.0 117.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.49 (0.9%) stddev-sans-first:1.39 19:29:41 INFO - PROCESS | 5189 | Values: 159.0 161.0 160.0 161.0 160.0 163.0 162.0 163.0 162.0 159.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:13.61 (18.1%) stddev-sans-first:14.27 19:29:41 INFO - PROCESS | 5189 | Values: 75.0 75.0 74.0 114.0 75.0 77.0 73.0 75.0 97.0 74.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:83.00 stddev:22.36 (26.9%) stddev-sans-first:23.14 19:29:41 INFO - PROCESS | 5189 | Values: 83.0 83.0 134.0 83.0 82.0 83.0 131.0 85.0 83.0 122.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.50 19:29:41 INFO - PROCESS | 5189 | Values: 118.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 19:29:41 INFO - PROCESS | 5189 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 148.0 147.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 19:29:41 INFO - PROCESS | 5189 | Values: 95.0 94.0 94.0 95.0 95.0 96.0 95.0 95.0 94.0 95.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:1.17 (2.3%) stddev-sans-first:1.22 19:29:41 INFO - PROCESS | 5189 | Values: 52.0 52.0 54.0 51.0 50.0 52.0 51.0 50.0 51.0 51.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.71 19:29:41 INFO - PROCESS | 5189 | Values: 61.0 59.0 60.0 59.0 60.0 58.0 59.0 60.0 59.0 60.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:73.00 stddev:4.12 (5.6%) stddev-sans-first:4.26 19:29:41 INFO - PROCESS | 5189 | Values: 72.0 73.0 81.0 72.0 83.0 73.0 72.0 72.0 72.0 76.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:125.50 stddev:13.92 (11.1%) stddev-sans-first:14.68 19:29:41 INFO - PROCESS | 5189 | Values: 123.0 121.0 125.0 127.0 119.0 117.0 119.0 131.0 165.0 126.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:154.00 stddev:2.23 (1.5%) stddev-sans-first:2.33 19:29:41 INFO - PROCESS | 5189 | Values: 155.0 153.0 158.0 152.0 157.0 151.0 154.0 153.0 154.0 152.0 19:29:41 INFO - PROCESS | 5189 | 19:29:41 INFO - PROCESS | 5189 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:62.50 stddev:1.49 (2.4%) stddev-sans-first:1.12 19:29:41 INFO - PROCESS | 5189 | Values: 66.0 62.0 63.0 62.0 62.0 62.0 65.0 62.0 64.0 62.0 19:29:41 INFO - PROCESS | 5189 | -------- Summary: end -------- 19:29:41 INFO - PROCESS | 5189 | 19:29:42 INFO - TEST-INFO | 5189: exit 0 19:29:42 INFO - TEST-OK | kraken | took 59978ms 19:29:42 INFO - SUITE-END | took 1172s 19:29:43 INFO - Completed test suite (00:19:33) 19:29:43 INFO - javascript benchmark 19:29:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.089694885432, 3261.8042917424964, 3268.7385009199265, 3296.357210343981, 3313.3704596126736, 5584.078627026541, 5680.801171958982, 5684.261046064016, 5684.914726279109, 5690.886182276352, 5692.516136128424, 5759.971200143993, 5767.423257674231, 5777.740001699923, 5785.045467497868, 5032.5470707636305, 5096.413912400066, 5229.50319719626, 5230.450802665703, 5277.973610131944, 5110.7700153493, 5132.332796736438, 5143.331366922295, 5166.43169251382, 5181.119209734355, 5168.431472538018, 5264.026155161288, 5265.315508983846, 5277.577793776508, 5278.155495120782, 5194.558462530652, 5217.947820521822, 5251.081060814342, 5266.5523430508265, 5287.894242115155, 4855.393075865517, 4991.201407774739, 5147.948520514784, 5149.2791009258735, 5249.42256351801, 5743.0811070228565, 5753.424657534213, 5755.107958266433, 5758.136279558058, 5784.045632470637, 6114.633122012693, 6116.174316467264, 6163.383661633846, 6173.938260617381, 6182.1345011698395, 5936.228290322274, 6015.3383127855905, 6066.059760737093, 6078.726457309387, 6210.844728881769, 5721.199032135504, 5727.341355744059, 5727.541796656299, 5738.7991420300505, 5748.223989761371, 6227.346128656516, 6233.968830155911, 6241.21984751906, 6241.438270555672, 6247.96876015617, 5815.2149459822385, 5828.6211396259105, 5852.736626851802, 5888.263967004124, 6026.126211699279, 6035.124906888476, 6085.726142323606, 6089.725962331617, 6089.939100609025, 6093.086037094471, 5963.642181469126, 5990.760369585255, 6016.639001659914, 6021.0, 6022.759089636453, 5832.358440571534, 5881.911771323434, 5969.641821490724, 5975.52195824333, 6023.608465449732, 4714.0, 4716.221823399169, 4720.622350211975, 4736.881577960509, 4739.052189562032, 4176.206520761046, 4179.9791001045405, 4187.790610469464, 4190.622843944059, 4205.495340559091, 5722.37053924068, 5765.625234359753, 5839.824805255849, 5842.649441033466, 5846.269216347957, 6155.261368635793, 6156.138140660311, 6190.876182476325, 6200.038993955944, 6209.813705588839, 5713.942860571424, 5729.398413166641, 5733.598648094593, 5754.251947246831, 5777.2200752897825, 5120.18077107661, 5177.534021938043, 5299.231611416323, 5300.0, 5301.1783173608155, 6455.9677201613695, 6458.612483250926, 6464.062710906892, 6470.12653291809, 6476.190476190476, 6083.0, 6135.63186208811, 6155.107509411021, 6158.599691020069, 6171.981623032149, 6148.323684394623, 6292.528060395397, 6306.337834527309, 6320.1151838743535, 6466.773662921775, 6050.273967123973, 6051.3041000283765, 6055.9091613626715, 6062.666553339609, 6091.7563297467605, 6113.877722445615, 6123.8162855114415, 6127.816165515042, 6130.908036379368, 6136.539759517965, 5777.94222057774, 5885.352611212677, 5958.433948774859, 5968.313643931004, 6016.0, 5965.164876917148, 5977.491913187254, 5984.640921544721, 5984.910226346521, 5995.430434108753, 5903.26209223847, 5911.0, 5950.761969521171, 5968.970155149196, 5981.551383646157, 5782.913256301243, 5834.18321435008, 5914.083317085859, 5941.851453713692, 6052.515798736003, 5890.204822348928, 5909.202257695156, 5917.260342457193, 5931.614445061057, 5951.791687290924], "value": 5625.484633578745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.871457636608, 628.5819929746718, 642.2389468479852, 649.4641920415652, 655.3512023097134, 34812.477812832825, 38746.0, 47032.5296747032, 47981.520184798144, 48052.0, 48003.75998120005, 48464.515354846444, 48465.51534484654, 48475.03049938999, 48546.27180592294, 9289.396189247695, 9449.480278584675, 9454.716358509222, 9460.385074970105, 9462.29032822541, 8494.447860889053, 11049.723756906062, 11324.943375283112, 11331.093512518979, 11737.295762254249, 11456.770864582704, 11494.310341379502, 11533.0, 11538.826917596201, 11558.30650160993, 11130.38782866942, 11225.887741122566, 11281.210315277935, 11291.717707057307, 11294.94352528232, 11167.050800681951, 11242.887571124265, 11243.83134252987, 11259.774804503946, 11266.549338026469, 46045.53954460446, 46190.76904615489, 46243.30635040477, 46433.30350044752, 46576.7671161642, 3851.287511810296, 3910.354791459406, 3967.4247234151176, 3968.226195891802, 4042.898927526806, 34932.6506734932, 35216.295674086374, 35251.82374088139, 35532.82233588841, 35802.46296305557, 4707.152712511746, 4766.237402015661, 4790.592799612037, 4791.113643975876, 4812.566868981773, 398944.0, 402206.0, 402363.0, 402666.9999999971, 403136.0, 66021.33978660151, 66808.0, 66848.33151668518, 66873.66563167152, 66939.33060669429, 39377.40933885994, 40184.799076004434, 40188.39717404241, 71666.283337167, 71825.6408717953, 19058.332958346393, 19513.414597562096, 19529.414117576496, 19533.90233048854, 19595.11821968015, 12924.741505169844, 14504.202268875102, 14862.628434289012, 14973.925130374497, 14974.47589334368, 20254.392368228975, 21058.89470552668, 21169.15323387078, 21207.575848482946, 21277.68083478749, 7466.290702383265, 7482.5884576347735, 7571.0, 7577.734779282699, 7607.869796457001, 1085.5115198160859, 1089.1341383600056, 1125.431657013203, 1129.1474936422949, 1185.3421351150193, 35924.820375898475, 36209.094772630895, 36352.0, 36428.98921677894, 36557.817210913774, 1162.3548930343572, 1175.8177482490228, 1232.5193174661888, 1272.1540175783186, 1291.6189724031433, 17815.46553603394, 18525.81474185241, 18783.436496905113, 18927.0, 18930.148143333307, 173938.0, 174741.0, 175359.0, 175419.12290438722, 175826.0, 65742.67128664326, 66463.66768166129, 66641.33358666448, 66729.0, 66819.33180668131, 1329.8803107720353, 1437.1879887863324, 1446.3780574352918, 1451.1293224065687, 1465.2234315812636, 34643.4803477943, 36797.81601091977, 37240.441393378576, 37378.813105935384, 37671.0, 55469.0, 56310.7184464075, 56404.43595563992, 56489.0, 56519.0, 425195.0, 426631.0, 427547.0, 429636.0, 430316.0, 30725.692743072283, 31773.20566985844, 32055.198620034687, 33396.66603333935, 33411.164720882174, 52652.47347526476, 53026.469735302155, 53756.46243537671, 53784.19323710069, 54083.0, 43318.0, 44753.55246447494, 45581.77209113933, 46663.0, 48444.273335900696, 45878.08243835171, 45964.0, 47046.0, 47070.05859882715, 48026.27960580664, 46519.246519246466, 47252.29121563111, 47253.7637311825, 47515.76242118906, 48097.5190248107, 38555.22889542249, 40480.59519404768, 40501.39247911343, 40858.38712419375, 41386.0, 31190.22024449407, 31319.671517359795, 31675.366492670477, 31831.681683182873, 31857.0], "value": 21634.77648335291, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.7821187452838, 419.55736697783834, 419.68733293696204, 422.8646833013436, 442.37845826613597, 15004.624884377883, 19913.900430497844, 20247.696284555743, 20427.48931276717, 20988.58022839542, 20612.89693551534, 20770.480737981557, 20790.792092079075, 20837.270695525658, 21031.47421314464, 5894.764209431618, 5896.675682837442, 5948.16725658406, 5981.252343457068, 6060.848478788043, 5969.253843269591, 5989.760409583611, 5999.6700181490005, 6008.128821320927, 6012.398760123996, 5845.7953971611005, 5907.202527658776, 5959.284885813709, 5973.044312909935, 5982.491488223484, 5911.408859114097, 5961.403859614025, 5965.433283838029, 5967.820965371024, 5984.970075149618, 5245.924585459971, 5357.839264822022, 5359.38367087786, 5360.2763626910255, 5360.463953604647, 15897.841021589751, 22256.221032263795, 23147.7685223149, 23168.18911338095, 24015.879920600284, 2377.7859992600747, 2546.490701859617, 2638.6965498967675, 2649.5363311420615, 3178.475551533994, 10733.0, 11129.554817807279, 11182.32906025641, 11409.315441073479, 12862.807057894139, 2005.8896760678158, 2014.8388128949648, 2045.5499790046167, 2077.0549400022956, 2079.916803327865, 72383.0, 80842.59578702127, 84824.57587712083, 86121.56939215264, 86168.56915715382, 11413.086953043736, 11543.307401555765, 11809.346691463203, 11876.88123118775, 11972.461239244254, 16698.415555455744, 16721.74917376216, 16797.41209057677, 16814.66370672604, 16890.83109168917, 7598.240175982468, 8252.33981281496, 8270.958645206736, 8407.8318433631, 8460.196281353261, 7623.275788800055, 7791.003398759904, 7826.099998500245, 7857.646405911748, 7979.3616510679, 4999.5500404963, 5016.949830501721, 5032.32063671407, 5145.691258524501, 5217.530422262014, 2001.7297664815355, 2088.488320361491, 2105.147415296807, 2123.193186589086, 2151.5804418138314, 959.9856002159974, 1047.8061558611682, 1072.9892701073045, 1096.3915027159844, 1139.6353166986485, 20953.476163096046, 21055.368338949855, 21291.0, 21381.144754209967, 21409.143634254455, 1064.0689396777818, 1081.6917178604217, 1099.5217080569978, 1122.9719257018476, 1149.1438878035917, 11514.827277590843, 12470.43883025266, 12486.750264994831, 12770.68073298175, 12885.613431597067, 492963.0, 499143.0, 503724.0, 508308.0, 511685.0, 79240.60379698065, 81713.18286817175, 96044.0, 99571.0, 99716.50141749244, 1068.4016950507585, 1078.2991055813627, 1098.3629494893103, 1124.241137232365, 1127.8139107047245, 18283.634327313644, 18509.444716658523, 19014.619707605492, 19038.143283551995, 19320.0, 42485.0, 42858.35712464378, 43524.0, 43579.782101089295, 44345.77827110844, 60254.397456024875, 61515.692421537606, 61598.0, 61928.690356549734, 62485.375146247956, 9032.774180645536, 9033.59348829291, 9111.498867562348, 9140.360174787702, 9349.0, 10441.947790261303, 13941.58175254744, 14097.295135243401, 14204.720343947998, 14408.927955360155, 11875.406229688653, 12324.938375308066, 12401.193922395016, 12562.246265224116, 12759.106862519906, 12009.039276857655, 12015.339156346485, 12199.695007624525, 12374.257544547356, 12482.625521234377, 12414.751704965669, 12427.254364738144, 12496.158764460291, 12830.358482076046, 12952.222866628032, 8771.764705882353, 8947.194752472049, 9049.366544341832, 9176.311776616914, 9329.673461428818, 11999.76000480003, 12004.0, 12219.45012474423, 12523.561675341683, 13108.541201057917], "value": 9715.473918727172, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.5872814340132, 1571.5206861907118, 1715.5282297368221, 1867.729179269006, 1874.9906250468746, 9409.576569054392, 10154.74613134671, 10158.54286557105, 10160.339577927449, 10169.644062457815, 8668.39321247513, 8860.246879015276, 8864.46813191209, 9263.722088337343, 9950.402975821456, 2485.4407758254383, 2541.987290063547, 2557.5907854743245, 2563.256655569892, 2563.5641940870005, 2536.581464058428, 2564.358910272432, 2599.1033093582682, 2603.609458581209, 2605.947881042378, 2525.1162093267394, 2533.40464990727, 2550.4516528946274, 2581.367564946591, 2594.9740502594923, 2446.8409553378974, 2455.7789798918184, 2456.7297597264287, 2487.092211342865, 2545.8599777012255, 2510.184190138212, 2511.4977004599154, 2512.133314006683, 2516.7609077137645, 2522.6846644169477, 9693.806123877554, 9765.16996055329, 9796.118349348522, 9889.851652225223, 9922.0, 5584.46947539983, 5733.627314224562, 5734.311882574076, 5750.971245143747, 5752.482276595127, 3796.52543432071, 3821.9235615287816, 3841.4045822897497, 3851.4607954886337, 3872.961270387288, 1226.0988173692417, 1234.802431610947, 1237.6472705278952, 1257.792464243408, 1261.9495220191181, 14193.858061419356, 14375.0, 14387.352569134415, 14449.855501445061, 14456.0, 4902.142125128088, 4980.90038199234, 5188.766505507261, 5196.376434827845, 5218.399884013311, 12162.452689628988, 12165.148439609142, 12183.269003859798, 12191.207571507824, 12191.695207619881, 5853.385394533512, 5931.347551769302, 5937.287525496968, 5937.554683398763, 5937.584369094208, 4426.667999900021, 5361.0, 5364.0, 5437.782488700487, 5443.101888188484, 4390.47314322277, 4397.31841564558, 4474.552544745499, 4502.909941801146, 4542.409486766699, 1337.625464869838, 1372.8695773901463, 1386.2999185411302, 1390.5480718766441, 1397.9301034948212, 1084.8698156221303, 1088.6625146204701, 1089.2810744908481, 1098.1873413673825, 1106.2422240765102, 6644.933550664528, 6694.364035416627, 6716.194056713226, 6910.44716422685, 7011.123609548807, 492.95070492951135, 505.4339140163041, 508.4204007431531, 511.94624564420945, 519.3559985617807, 4583.564561366665, 4623.237165867661, 4701.7414042228, 4750.476039352622, 4777.593904518086, 12323.507059717691, 12691.555795547112, 12803.61589152327, 12817.743645127232, 12826.61520154397, 11798.41007949599, 12571.93714031424, 12702.36488175606, 12711.872881271254, 12727.0, 8876.378653494194, 9061.77345566366, 9080.909190908138, 9085.591148398338, 9117.225035872025, 6746.325367463214, 6829.863402732017, 6865.828354291183, 6874.759383421756, 6960.547564408297, 11115.610953616908, 11389.373584452935, 11396.487158078073, 11417.029552488131, 11446.771064578828, 11047.558097676003, 11754.118441117122, 11760.823587646364, 11772.705682357668, 11851.822222666495, 7846.764597062097, 8505.234528892182, 8642.740717778477, 8947.776305592412, 8979.910200898168, 9308.44149351041, 9676.22590192797, 9678.370905891095, 9680.0, 9685.70942871715, 8027.317677997436, 8031.59842007909, 8299.543525106177, 8365.581720913808, 8447.0, 8380.706675266336, 8392.412531122764, 8451.32389408858, 8477.109903460294, 8609.569521524025, 7726.575038372944, 7798.142204357403, 7809.609519523888, 7827.569483678452, 7874.330681892104, 5649.71751412436, 5663.377028526941, 5687.374388817309, 5693.9430605693415, 5850.0, 5756.94243057564, 5849.356570777126, 5900.793472228452, 5950.553708471968, 5979.431953964367], "value": 5079.550005744095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.57875759709555, 358.54643875497516, 361.1693105856531, 361.3567849228373, 362.8657396763197, 5414.187871819229, 5470.617056806015, 5487.725613719313, 5490.752916118774, 5507.311586051744, 5430.321209848769, 5435.1847222916585, 5439.401665816767, 5439.972800136004, 5457.781688732446, 3819.2743378758087, 4084.019835239543, 4283.400323954649, 4316.395704601358, 4325.113351762897, 4274.29474136767, 4277.443932288798, 4283.850065247717, 4285.250081235787, 4291.313389857623, 4082.5713300103507, 4267.274563324242, 4274.4229529013655, 4279.914401711964, 4293.840663020983, 3992.7205095643026, 4205.705600607958, 4235.597618226264, 4297.656187504992, 4320.524742278347, 3810.733248672594, 3814.141818090935, 3817.4846395736777, 3841.135744457503, 3861.2663593917064, 5238.511706939212, 5286.365636123652, 5291.777453469552, 5323.813666521692, 5332.693490094913, 4890.510948905081, 4901.8529444116375, 4914.6068314535105, 4929.950700493021, 4949.158643030693, 5619.0, 5619.522340601054, 5629.971850140764, 5645.2096706461125, 5665.688387138705, 4130.6282434580735, 4186.455760751083, 4216.831326746927, 4217.683673724483, 4219.556946520633, 5531.7234138293325, 5680.971595142039, 5835.970820145914, 5846.619969701998, 5876.2067120938555, 4250.681198910094, 4250.936235956463, 4270.551592082787, 4296.205202037633, 4297.290946993773, 4336.132773445323, 4357.8256869724855, 4358.934615980763, 4373.562643735665, 4378.168148051924, 4804.735739534359, 4839.872514555934, 4950.108980383566, 4951.90096198074, 4972.90054198914, 5631.465010823965, 5647.858803529944, 5677.034904066319, 5678.744456499468, 5686.9715651421475, 1487.315834716021, 1509.3208056374676, 1511.3878879054, 1511.5314252581516, 1511.9697606047816, 940.5374642430949, 981.8723565936383, 992.712113487095, 995.7560397702609, 1005.3967619428431, 4879.658423910292, 4936.481669424658, 5001.0998020355955, 5005.299258103868, 5042.722650254272, 5090.363704536933, 5101.795928162832, 5131.332926719577, 5159.922601160985, 5167.442116152529, 5019.422766381913, 5056.241563765465, 5063.493650634907, 5264.236685680555, 5695.601307908407, 3126.9530957035663, 3135.357251763383, 3136.4944826483415, 3148.843042515884, 3152.7004934531183, 6034.922189683293, 6181.227346581677, 6190.0, 6193.213054125661, 6203.627782333075, 5930.525557955353, 5940.0, 5950.196723442366, 5952.755748117286, 5985.6408615483215, 6064.575479716465, 6124.601900876429, 6164.291106522717, 6182.536309776785, 6195.473384762346, 5599.412061733622, 5811.912821307599, 5872.236609240771, 5912.408759124053, 5931.792387266425, 5795.478406943395, 5846.386129456346, 5869.354371019099, 5906.616069955439, 5911.172435858897, 5971.701414929323, 6072.180255665428, 6092.847678808065, 6126.142340072304, 6264.937350626436, 4309.267424537773, 4377.496587892306, 4389.978050109729, 4399.912001760011, 4404.603585677304, 5712.742926568231, 5908.497777688945, 5929.644221346733, 5933.525317974638, 5934.703264836828, 5661.858453538694, 5678.290213723285, 5693.601447898607, 5702.6293290935955, 5722.713864306685, 5646.971765141148, 5651.124075768263, 5652.558597557597, 5676.460736230051, 5693.259876216065, 5413.160960051198, 5616.494515493625, 5619.157126431068, 5649.406812284651, 5695.943040569541, 5686.488216060575, 5695.401982791747, 5695.430456954271, 5702.258706368311, 5719.971400142973, 5550.8057217997175, 5555.888702326549, 5564.582656300713, 5580.525655319181, 5606.327240730975], "value": 4357.48299929574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.0, 811.1604489353521, 856.327782690588, 876.631814637852, 925.9489484860087, 14334.569962901116, 14987.400503979828, 15034.77447838282, 15151.924240378796, 15292.0, 15008.849911500882, 15137.091774493538, 15210.315535800888, 15224.08655480672, 15244.085354878714, 739.7447880481226, 872.0320444306815, 881.5900606218107, 892.8973168085656, 899.6266549381999, 901.33301356996, 901.8962819275769, 902.6975963052386, 904.055262250946, 905.2395987370608, 942.3309450290268, 948.042477098129, 950.5294879034888, 951.4719330771419, 951.5717926932873, 763.6410886883156, 783.5416281475344, 785.7289235213899, 894.8612964990436, 946.8627049077879, 672.4015626092781, 676.0737789228739, 676.2155899156954, 676.5805200775551, 676.9356911093438, 15383.615409614737, 15403.0, 15425.228738563026, 15426.460073897471, 15447.30487128082, 15701.842981570153, 15707.136107514083, 15909.363625454967, 15938.521844344688, 16157.272922718506, 14269.78595321071, 14492.637684057878, 14531.927340363336, 14660.266986650624, 14707.558773236713, 4802.35168252285, 4842.346283251751, 4866.6106711463, 4952.133376659106, 4964.131277026542, 87860.56069719611, 88121.0, 88802.55598721965, 90731.54634226918, 91541.0, 11602.18784685064, 11746.29522228669, 11816.763664726657, 11818.290902545874, 12137.393130343447, 21918.0, 22198.889005555193, 22238.555228895333, 22298.665520017214, 22300.442488937908, 8479.06730259671, 8877.20105190536, 9043.095690430904, 9114.772130696652, 9180.954095229481, 4913.606911447076, 5085.466026067284, 5104.3109180260935, 5198.636095473281, 5286.127788914786, 3712.9628703712615, 3838.05967537955, 3838.443425703257, 3839.788811615332, 3841.5390153181797, 648.7891435283552, 664.4153145232165, 665.3446355339986, 680.7583307925714, 688.7245101959256, 615.7383112177307, 673.6497021548865, 702.7154002628944, 706.057413353169, 747.8952946587482, 9482.620695172116, 9743.951280243553, 10273.794524109477, 10302.639407620698, 10380.32527885685, 674.8515326628134, 676.6008055247329, 709.4714437743913, 716.0941409117471, 763.3015790551694, 4786.760661966888, 4860.708357498562, 4910.116179087799, 4922.4339200992345, 4976.2038073908, 114665.0, 129959.0, 131192.0, 132724.0, 137670.0, 112530.43734781437, 115427.0, 120631.0, 120732.39633801774, 120808.0, 103053.0, 104462.0, 104493.47753261337, 104517.47741261245, 105647.0, 9599.136077753035, 10055.798884022424, 10148.797024059626, 10193.541290641791, 10211.897881021094, 57131.71473141028, 57329.0, 57519.42480575308, 58684.0, 60414.39585604263, 77889.61055194688, 78513.0, 80763.19236807557, 81187.0, 82609.0, 12388.690282743004, 12449.688757781129, 12582.18215815969, 12618.747625047632, 12716.0, 12562.560310389092, 12635.305058221518, 12666.0, 12849.743005140032, 12992.090553661154, 12013.0, 12471.314077725448, 13389.608065382106, 13474.056816022785, 13680.315984200552, 12757.298348590546, 12881.871181288067, 13002.674933126747, 13074.215547067206, 13168.996690213977, 12988.805167922677, 13038.348082595643, 13908.0, 13967.0, 13998.090124141898, 9963.402195868272, 10226.130778883879, 10688.519016644113, 10719.624813131815, 10765.46172691378, 11088.390138542134, 11235.4382280884, 11350.716232094264, 11517.0, 11526.423678816192], "value": 7983.491516007939, "unit": "score"}], "name": "dromaeo_css", "value": 7703.337104391975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 111.0, 111.0, 112.0, 111.0, 110.0, 111.0, 112.0, 111.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 119.0, 116.0, 176.0, 117.0, 117.0, 150.0, 115.0, 149.0, 117.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 160.0, 163.0, 162.0, 163.0, 162.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 114.0, 75.0, 77.0, 73.0, 75.0, 97.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 134.0, 83.0, 82.0, 83.0, 131.0, 85.0, 83.0, 122.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 148.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 54.0, 51.0, 50.0, 52.0, 51.0, 50.0, 51.0, 51.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 59.0, 60.0, 58.0, 59.0, 60.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 81.0, 72.0, 83.0, 73.0, 72.0, 72.0, 72.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 125.0, 127.0, 119.0, 117.0, 119.0, 131.0, 165.0, 126.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 153.0, 158.0, 152.0, 157.0, 151.0, 154.0, 153.0, 154.0, 152.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 63.0, 62.0, 62.0, 62.0, 65.0, 62.0, 64.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1467.8, "alertThreshold": 2.0}]} 19:29:43 INFO - Return code: 0 19:29:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:29:43 INFO - # TBPL SUCCESS # 19:29:43 INFO - Running post-action listener: _resource_record_post_action 19:29:43 INFO - [mozharness: 2016-08-20 02:29:43.250007Z] Finished run-tests step (success) 19:29:43 INFO - Running post-run listener: _resource_record_post_run 19:29:43 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328945664; Read time: 36; Write time: 190980 19:29:43 INFO - TinderboxPrint: CPU usage
12.9% 19:29:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 36 19:29:43 INFO - TinderboxPrint: I/O write bytes / time
328,945,664 / 190,980 19:29:43 INFO - TinderboxPrint: CPU idle
8,262.4 (87.1%) 19:29:43 INFO - TinderboxPrint: CPU user
1,183.5 (12.5%) 19:29:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77070336; Read time: 0; Write time: 38884 19:29:43 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 251383808; Read time: 0; Write time: 152016 19:29:43 INFO - Running post-run listener: _upload_blobber_files 19:29:43 INFO - Blob upload gear active. 19:29:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:29:43 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'] 19:29:43 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 19:29:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:45 INFO - (blobuploader) - INFO - Done attempting. 19:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:46 INFO - (blobuploader) - INFO - Done attempting. 19:29:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:50 INFO - (blobuploader) - INFO - Done attempting. 19:29:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:50 INFO - Return code: 0 19:29:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/536cb66e70604f6d9f98e836b4b726b167a4d42c5f87b4f6eda67c0692a4180348a2558b69d81d217f44fe5ff4dd01b7ee44ee8f8fd1f0ac166a0d4c58bbf5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9c33b7748946a388ec343e045bc721415af5999ef32bf10070a572ccefdd46ee492e58d69e61f210c736459b056e195ba0fe4b835860da93ea2f429c7a9763c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac0dc28bb56bda516204b0610d982d703ad59093995ebbb9b84f5e8f5db1d663366019ccf6b94873a48bf08a9e6010339e93027be0c91035f9f798d32b9bcf8b"} 19:29:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:50 INFO - Contents: 19:29:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/536cb66e70604f6d9f98e836b4b726b167a4d42c5f87b4f6eda67c0692a4180348a2558b69d81d217f44fe5ff4dd01b7ee44ee8f8fd1f0ac166a0d4c58bbf5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9c33b7748946a388ec343e045bc721415af5999ef32bf10070a572ccefdd46ee492e58d69e61f210c736459b056e195ba0fe4b835860da93ea2f429c7a9763c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac0dc28bb56bda516204b0610d982d703ad59093995ebbb9b84f5e8f5db1d663366019ccf6b94873a48bf08a9e6010339e93027be0c91035f9f798d32b9bcf8b"} 19:29:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:50 INFO - Copying logs to upload dir... 19:29:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.551211 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-19 19:29:50.493293) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-19 19:29:50.496514) ========= 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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 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/536cb66e70604f6d9f98e836b4b726b167a4d42c5f87b4f6eda67c0692a4180348a2558b69d81d217f44fe5ff4dd01b7ee44ee8f8fd1f0ac166a0d4c58bbf5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9c33b7748946a388ec343e045bc721415af5999ef32bf10070a572ccefdd46ee492e58d69e61f210c736459b056e195ba0fe4b835860da93ea2f429c7a9763c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac0dc28bb56bda516204b0610d982d703ad59093995ebbb9b84f5e8f5db1d663366019ccf6b94873a48bf08a9e6010339e93027be0c91035f9f798d32b9bcf8b"} build_url:https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/T0IkbK3NTECacQ_W7ljJAQ/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/536cb66e70604f6d9f98e836b4b726b167a4d42c5f87b4f6eda67c0692a4180348a2558b69d81d217f44fe5ff4dd01b7ee44ee8f8fd1f0ac166a0d4c58bbf5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9c33b7748946a388ec343e045bc721415af5999ef32bf10070a572ccefdd46ee492e58d69e61f210c736459b056e195ba0fe4b835860da93ea2f429c7a9763c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac0dc28bb56bda516204b0610d982d703ad59093995ebbb9b84f5e8f5db1d663366019ccf6b94873a48bf08a9e6010339e93027be0c91035f9f798d32b9bcf8b"}' ========= master_lag: 1.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-19 19:29:51.727710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:29:51.728114) ========= 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=337fe3f4b1febe37b85fccc40000019e-1471658468.479330-1333236263 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 19:29:51.745049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 19:29:51.745367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 19:29:51.745654) ========= ========= Total master_lag: 1.31 =========