builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1474281883.21 results: success (0) buildid: 20160919004008 builduid: 22ef26731428467e8bae05de0b668895 revision: c174d0c3195371011b3bf8044512c06c26f5c741 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.205964) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.206409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.206697) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.221075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.221389) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 03:44:44-- 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% 45.8M=0s 2016-09-19 03:44:44 (45.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110961 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.345668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.345978) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019806 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:43.395487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 03:44:43.395846) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c174d0c3195371011b3bf8044512c06c26f5c741 --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 c174d0c3195371011b3bf8044512c06c26f5c741 --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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 03:44:44,230 truncating revision to first 12 chars 2016-09-19 03:44:44,230 Setting DEBUG logging. 2016-09-19 03:44:44,230 attempt 1/10 2016-09-19 03:44:44,230 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c174d0c31953?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 03:44:45,266 unpacking tar archive at: mozilla-aurora-c174d0c31953/testing/mozharness/ program finished with exit code 0 elapsedTime=1.449704 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 03:44:44.859415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:44.859752) ========= script_repo_revision: c174d0c3195371011b3bf8044512c06c26f5c741 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:44.860190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:44.860458) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 03:44:44.869967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-19 03:44:44.870243) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:44:45 INFO - MultiFileLogger online at 20160919 03:44:45 in /builds/slave/test 03:44:45 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 03:44:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:44:45 INFO - {'append_to_log': False, 03:44:45 INFO - 'base_work_dir': '/builds/slave/test', 03:44:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:44:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:44:45 INFO - 'branch': 'Mozilla-Aurora', 03:44:45 INFO - 'buildbot_json_path': 'buildprops.json', 03:44:45 INFO - 'config_files': ('talos/linux_config.py',), 03:44:45 INFO - 'default_actions': ('clobber', 03:44:45 INFO - 'read-buildbot-config', 03:44:45 INFO - 'download-and-extract', 03:44:45 INFO - 'populate-webroot', 03:44:45 INFO - 'create-virtualenv', 03:44:45 INFO - 'install', 03:44:45 INFO - 'run-tests'), 03:44:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:44:45 INFO - 'download_minidump_stackwalk': True, 03:44:45 INFO - 'download_symbols': 'ondemand', 03:44:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:44:45 INFO - 'tooltool.py': '/tools/tooltool.py', 03:44:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:44:45 INFO - '/tools/misc-python/virtualenv.py')}, 03:44:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:44:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:44:45 INFO - 'installer_path': 'installer.exe', 03:44:45 INFO - 'log_level': 'info', 03:44:45 INFO - 'log_name': 'talos', 03:44:45 INFO - 'log_to_console': True, 03:44:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:44:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:44:45 INFO - 'opt_config_files': (), 03:44:45 INFO - 'pip_index': False, 03:44:45 INFO - 'sps_profile': False, 03:44:45 INFO - 'sps_profile_interval': 0, 03:44:45 INFO - 'suite': 'dromaeojs', 03:44:45 INFO - 'system_bits': '32', 03:44:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:44:45 INFO - 'title': 'talos-linux64-ix-029', 03:44:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:44:45 INFO - 'use_talos_json': True, 03:44:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:44:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:44:45 INFO - 'work_dir': 'build'} 03:44:45 INFO - [mozharness: 2016-09-19 10:44:45.795144Z] Running clobber step. 03:44:45 INFO - Running pre-action listener: _resource_record_pre_action 03:44:45 INFO - Running main action method: clobber 03:44:45 INFO - rmtree: /builds/slave/test/build 03:44:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:44:46 INFO - Running post-action listener: _resource_record_post_action 03:44:46 INFO - [mozharness: 2016-09-19 10:44:46.258163Z] Finished clobber step (success) 03:44:46 INFO - [mozharness: 2016-09-19 10:44:46.258264Z] Running read-buildbot-config step. 03:44:46 INFO - Running pre-action listener: _resource_record_pre_action 03:44:46 INFO - Running main action method: read_buildbot_config 03:44:46 INFO - Using buildbot properties: 03:44:46 INFO - { 03:44:46 INFO - "project": "", 03:44:46 INFO - "product": "firefox", 03:44:46 INFO - "script_repo_revision": "production", 03:44:46 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:44:46 INFO - "repository": "", 03:44:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:44:46 INFO - "buildid": "20160919004008", 03:44:46 INFO - "stage_platform": "linux64-pgo", 03:44:46 INFO - "basedir": "/builds/slave/test", 03:44:46 INFO - "nightly_build": "True", 03:44:46 INFO - "buildnumber": 757, 03:44:46 INFO - "slavename": "talos-linux64-ix-029", 03:44:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:44:46 INFO - "platform": "ubuntu64_hw", 03:44:46 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:44:46 INFO - "branch": "mozilla-aurora", 03:44:46 INFO - "repo_path": "releases/mozilla-aurora", 03:44:46 INFO - "revision": "c174d0c3195371011b3bf8044512c06c26f5c741", 03:44:46 INFO - "pgo_build": "True", 03:44:46 INFO - "builduid": "22ef26731428467e8bae05de0b668895", 03:44:46 INFO - "slavebuilddir": "test" 03:44:46 INFO - } 03:44:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:44:46 INFO - Running post-action listener: _resource_record_post_action 03:44:46 INFO - [mozharness: 2016-09-19 10:44:46.261097Z] Finished read-buildbot-config step (success) 03:44:46 INFO - [mozharness: 2016-09-19 10:44:46.261187Z] Running download-and-extract step. 03:44:46 INFO - Running pre-action listener: _resource_record_pre_action 03:44:46 INFO - Running main action method: download_and_extract 03:44:46 INFO - mkdir: /builds/slave/test/build/tests 03:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:46 INFO - https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:44:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:44:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:44:46 INFO - retry: Failed, sleeping 30 seconds before retrying 03:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:45:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:45:16 INFO - retry: Failed, sleeping 60 seconds before retrying 03:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:46:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:46:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:46:16 INFO - Caught exception: HTTP Error 404: Not Found 03:46:16 INFO - Caught exception: HTTP Error 404: Not Found 03:46:16 INFO - Caught exception: HTTP Error 404: Not Found 03:46:16 INFO - trying https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:16 INFO - Downloading https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:46:16 INFO - Downloaded 1361 bytes. 03:46:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:16 INFO - Using the following test package requirements: 03:46:16 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:16 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:16 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:46:16 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'jsshell-linux-x86_64.zip'], 03:46:16 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:16 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:16 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:46:16 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:46:16 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:16 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:16 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:16 INFO - https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:46:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:46:16 INFO - retry: Failed, sleeping 30 seconds before retrying 03:46:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:46:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:46:46 INFO - retry: Failed, sleeping 60 seconds before retrying 03:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:47:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:47:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:47:47 INFO - Caught exception: HTTP Error 404: Not Found 03:47:47 INFO - Caught exception: HTTP Error 404: Not Found 03:47:47 INFO - Caught exception: HTTP Error 404: Not Found 03:47:47 INFO - trying https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:47 INFO - Downloading https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:47:47 INFO - Downloaded 21771047 bytes. 03:47:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:47:49 INFO - Return code: 0 03:47:49 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:47: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')]} 03:47:49 INFO - https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:47:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:47:49 INFO - retry: Failed, sleeping 30 seconds before retrying 03:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:48:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:48:19 INFO - retry: Failed, sleeping 60 seconds before retrying 03:49:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:49:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:49:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:49:19 INFO - Caught exception: HTTP Error 404: Not Found 03:49:19 INFO - Caught exception: HTTP Error 404: Not Found 03:49:19 INFO - Caught exception: HTTP Error 404: Not Found 03:49:19 INFO - trying https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:19 INFO - Downloading https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:49:19 INFO - Downloaded 21771047 bytes. 03:49:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:49:21 INFO - Return code: 0 03:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:21 INFO - https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:49:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:49:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:21 INFO - retry: Failed, sleeping 30 seconds before retrying 03:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:49:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:49:51 INFO - retry: Failed, sleeping 60 seconds before retrying 03:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:50:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:50:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:50:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:50:51 INFO - Caught exception: HTTP Error 404: Not Found 03:50:51 INFO - Caught exception: HTTP Error 404: Not Found 03:50:51 INFO - Caught exception: HTTP Error 404: Not Found 03:50:51 INFO - trying https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:50:51 INFO - Downloading https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:50:52 INFO - Downloaded 14413740 bytes. 03:50:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:50:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:50:52 INFO - Return code: 0 03:50: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')]} 03:50:52 INFO - https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:50:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:50:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:50:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:50:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:50:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:50:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:50:52 INFO - retry: Failed, sleeping 30 seconds before retrying 03:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:51:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:51:22 INFO - retry: Failed, sleeping 60 seconds before retrying 03:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/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 03:52:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:52:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:52:22 INFO - Caught exception: HTTP Error 404: Not Found 03:52:22 INFO - Caught exception: HTTP Error 404: Not Found 03:52:22 INFO - Caught exception: HTTP Error 404: Not Found 03:52:22 INFO - trying https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:22 INFO - Downloading https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:52:23 INFO - Downloaded 63918423 bytes. 03:52:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:23 INFO - mkdir: /builds/slave/test/properties 03:52:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:23 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:23 INFO - Contents: 03:52:23 INFO - build_url:https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:24 INFO - Running post-action listener: _resource_record_post_action 03:52:24 INFO - Running post-action listener: set_extra_try_arguments 03:52:24 INFO - [mozharness: 2016-09-19 10:52:24.687205Z] Finished download-and-extract step (success) 03:52:24 INFO - [mozharness: 2016-09-19 10:52:24.687438Z] Running populate-webroot step. 03:52:24 INFO - Running pre-action listener: _resource_record_pre_action 03:52:24 INFO - Running main action method: populate_webroot 03:52:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:24 INFO - '--tppagecycles', 03:52:24 INFO - '7'], 03:52:24 INFO - 'tests': ['tsvgm']}}, 03:52:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['damp', 'tps']}, 03:52:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'tests': ['damp', 'tps']}, 03:52:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['dromaeo_dom']}, 03:52:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['basic_compositor_video']}, 03:52:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['a11yr', 03:52:24 INFO - 'ts_paint', 03:52:24 INFO - 'tpaint', 03:52:24 INFO - 'sessionrestore', 03:52:24 INFO - 'sessionrestore_no_auto_restore', 03:52:24 INFO - 'tabpaint']}, 03:52:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['tsvgx', 03:52:24 INFO - 'tsvgr_opacity', 03:52:24 INFO - 'tart', 03:52:24 INFO - 'tscrollx', 03:52:24 INFO - 'cart']}, 03:52:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:24 INFO - 'tsvgr_opacity', 03:52:24 INFO - 'tart', 03:52:24 INFO - 'tscrollx', 03:52:24 INFO - 'cart']}, 03:52:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'talos_options': ['--disable-e10s'], 03:52:24 INFO - 'tests': ['tp5o']}, 03:52:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'tests': ['tp5o']}, 03:52:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'talos_options': ['--disable-e10s', 03:52:24 INFO - '--xperf_path', 03:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:24 INFO - 'tests': ['tp5n']}, 03:52:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:24 INFO - 'talos_options': ['--xperf_path', 03:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:24 INFO - 'tests': ['tp5n']}}, 03:52:24 INFO - 'talos.zip': {'path': '', 03:52:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:24 INFO - Running post-action listener: _resource_record_post_action 03:52:24 INFO - [mozharness: 2016-09-19 10:52:24.705307Z] Finished populate-webroot step (success) 03:52:24 INFO - [mozharness: 2016-09-19 10:52:24.705396Z] Running create-virtualenv step. 03:52:24 INFO - Running pre-action listener: _resource_record_pre_action 03:52:24 INFO - Running main action method: create_virtualenv 03:52:24 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:24 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 03:52:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:24 INFO - Using real prefix '/usr' 03:52:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:25 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:27 INFO - Installing pip.................done. 03:52:27 INFO - Return code: 0 03:52:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:27 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:27 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 03:52:27 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 03:52:27 INFO - Using env: {'DISPLAY': ':0', 03:52:27 INFO - 'HOME': '/home/cltbld', 03:52:27 INFO - 'LANG': 'en_US.UTF-8', 03:52:27 INFO - 'LANGUAGE': 'en_US:en', 03:52:27 INFO - 'LOGNAME': 'cltbld', 03:52:27 INFO - 'MAIL': '/var/mail/cltbld', 03:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:27 INFO - 'MOZ_NO_REMOTE': '1', 03:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:27 INFO - 'NO_EM_RESTART': '1', 03:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:27 INFO - 'PWD': '/builds/slave/test', 03:52:27 INFO - 'SHELL': '/bin/bash', 03:52:27 INFO - 'SHLVL': '1', 03:52:27 INFO - 'TERM': 'linux', 03:52:27 INFO - 'TMOUT': '86400', 03:52:27 INFO - 'USER': 'cltbld', 03:52:27 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:27 INFO - '_': '/tools/buildbot/bin/python'} 03:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:27 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 03:52:27 INFO - Cleaning up... 03:52:27 INFO - Return code: 0 03:52:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:27 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:27 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 03:52:27 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 03:52:27 INFO - Using env: {'DISPLAY': ':0', 03:52:27 INFO - 'HOME': '/home/cltbld', 03:52:27 INFO - 'LANG': 'en_US.UTF-8', 03:52:27 INFO - 'LANGUAGE': 'en_US:en', 03:52:27 INFO - 'LOGNAME': 'cltbld', 03:52:27 INFO - 'MAIL': '/var/mail/cltbld', 03:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:27 INFO - 'MOZ_NO_REMOTE': '1', 03:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:27 INFO - 'NO_EM_RESTART': '1', 03:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:27 INFO - 'PWD': '/builds/slave/test', 03:52:27 INFO - 'SHELL': '/bin/bash', 03:52:27 INFO - 'SHLVL': '1', 03:52:27 INFO - 'TERM': 'linux', 03:52:27 INFO - 'TMOUT': '86400', 03:52:27 INFO - 'USER': 'cltbld', 03:52:27 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:27 INFO - '_': '/tools/buildbot/bin/python'} 03:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:27 INFO - Downloading/unpacking psutil>=3.1.1 03:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:29 INFO - Installing collected packages: psutil 03:52:29 INFO - Running setup.py install for psutil 03:52:29 INFO - building 'psutil._psutil_linux' extension 03:52:29 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 03:52:29 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 03:52:29 INFO - building 'psutil._psutil_posix' extension 03:52:29 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 03:52:30 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 03:52:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:30 INFO - Successfully installed psutil 03:52:30 INFO - Cleaning up... 03:52:30 INFO - Return code: 0 03:52:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:30 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:30 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 03:52:30 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 03:52:30 INFO - Using env: {'DISPLAY': ':0', 03:52:30 INFO - 'HOME': '/home/cltbld', 03:52:30 INFO - 'LANG': 'en_US.UTF-8', 03:52:30 INFO - 'LANGUAGE': 'en_US:en', 03:52:30 INFO - 'LOGNAME': 'cltbld', 03:52:30 INFO - 'MAIL': '/var/mail/cltbld', 03:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:30 INFO - 'MOZ_NO_REMOTE': '1', 03:52:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:30 INFO - 'NO_EM_RESTART': '1', 03:52:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:30 INFO - 'PWD': '/builds/slave/test', 03:52:30 INFO - 'SHELL': '/bin/bash', 03:52:30 INFO - 'SHLVL': '1', 03:52:30 INFO - 'TERM': 'linux', 03:52:30 INFO - 'TMOUT': '86400', 03:52:30 INFO - 'USER': 'cltbld', 03:52:30 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:30 INFO - '_': '/tools/buildbot/bin/python'} 03:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:30 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 03:52:30 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 03:52:30 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 03:52:30 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 03:52:30 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 03:52:30 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 03:52:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:52:32 INFO - Installing collected packages: mozsystemmonitor 03:52:32 INFO - Running setup.py install for mozsystemmonitor 03:52:32 INFO - Successfully installed mozsystemmonitor 03:52:32 INFO - Cleaning up... 03:52:32 INFO - Return code: 0 03:52:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:32 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:32 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 03:52:32 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 03:52:32 INFO - Using env: {'DISPLAY': ':0', 03:52:32 INFO - 'HOME': '/home/cltbld', 03:52:32 INFO - 'LANG': 'en_US.UTF-8', 03:52:32 INFO - 'LANGUAGE': 'en_US:en', 03:52:32 INFO - 'LOGNAME': 'cltbld', 03:52:32 INFO - 'MAIL': '/var/mail/cltbld', 03:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:32 INFO - 'MOZ_NO_REMOTE': '1', 03:52:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:32 INFO - 'NO_EM_RESTART': '1', 03:52:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:32 INFO - 'PWD': '/builds/slave/test', 03:52:32 INFO - 'SHELL': '/bin/bash', 03:52:32 INFO - 'SHLVL': '1', 03:52:32 INFO - 'TERM': 'linux', 03:52:32 INFO - 'TMOUT': '86400', 03:52:32 INFO - 'USER': 'cltbld', 03:52:32 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:32 INFO - '_': '/tools/buildbot/bin/python'} 03:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:32 INFO - Downloading/unpacking jsonschema==2.5.1 03:52:32 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 03:52:32 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 03:52:32 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 03:52:32 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 03:52:32 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 03:52:32 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 03:52:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:52:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:34 INFO - Downloading functools32-3.2.3-2.tar.gz 03:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:52:34 INFO - warning: no files found matching '*.txt' 03:52:34 INFO - no previously-included directories found matching 'build' 03:52:34 INFO - no previously-included directories found matching 'dist' 03:52:34 INFO - no previously-included directories found matching '.git*' 03:52:34 INFO - Installing collected packages: jsonschema, functools32 03:52:35 INFO - Running setup.py install for functools32 03:52:35 INFO - warning: no files found matching '*.txt' 03:52:35 INFO - no previously-included directories found matching 'build' 03:52:35 INFO - no previously-included directories found matching 'dist' 03:52:35 INFO - no previously-included directories found matching '.git*' 03:52:35 INFO - Successfully installed jsonschema functools32 03:52:35 INFO - Cleaning up... 03:52:35 INFO - Return code: 0 03:52:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:52:35 INFO - Using env: {'DISPLAY': ':0', 03:52:35 INFO - 'HOME': '/home/cltbld', 03:52:35 INFO - 'LANG': 'en_US.UTF-8', 03:52:35 INFO - 'LANGUAGE': 'en_US:en', 03:52:35 INFO - 'LOGNAME': 'cltbld', 03:52:35 INFO - 'MAIL': '/var/mail/cltbld', 03:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:35 INFO - 'MOZ_NO_REMOTE': '1', 03:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:35 INFO - 'NO_EM_RESTART': '1', 03:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:35 INFO - 'PWD': '/builds/slave/test', 03:52:35 INFO - 'SHELL': '/bin/bash', 03:52:35 INFO - 'SHLVL': '1', 03:52:35 INFO - 'TERM': 'linux', 03:52:35 INFO - 'TMOUT': '86400', 03:52:35 INFO - 'USER': 'cltbld', 03:52:35 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:35 INFO - '_': '/tools/buildbot/bin/python'} 03:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:52:35 INFO - Cleaning up... 03:52:35 INFO - Return code: 0 03:52:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:52:35 INFO - Using env: {'DISPLAY': ':0', 03:52:35 INFO - 'HOME': '/home/cltbld', 03:52:35 INFO - 'LANG': 'en_US.UTF-8', 03:52:35 INFO - 'LANGUAGE': 'en_US:en', 03:52:35 INFO - 'LOGNAME': 'cltbld', 03:52:35 INFO - 'MAIL': '/var/mail/cltbld', 03:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:35 INFO - 'MOZ_NO_REMOTE': '1', 03:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:35 INFO - 'NO_EM_RESTART': '1', 03:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:35 INFO - 'PWD': '/builds/slave/test', 03:52:35 INFO - 'SHELL': '/bin/bash', 03:52:35 INFO - 'SHLVL': '1', 03:52:35 INFO - 'TERM': 'linux', 03:52:35 INFO - 'TMOUT': '86400', 03:52:35 INFO - 'USER': 'cltbld', 03:52:35 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:35 INFO - '_': '/tools/buildbot/bin/python'} 03:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:35 INFO - Downloading/unpacking blobuploader==1.2.4 03:52:35 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 03:52:35 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 03:52:35 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 03:52:35 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 03:52:35 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 03:52:35 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 03:52:37 INFO - Downloading blobuploader-1.2.4.tar.gz 03:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:52:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:52:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:38 INFO - Downloading docopt-0.6.1.tar.gz 03:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:52:38 INFO - Installing collected packages: blobuploader, requests, docopt 03:52:38 INFO - Running setup.py install for blobuploader 03:52:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:52:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:52:38 INFO - Running setup.py install for requests 03:52:38 INFO - Running setup.py install for docopt 03:52:38 INFO - Successfully installed blobuploader requests docopt 03:52:38 INFO - Cleaning up... 03:52:38 INFO - Return code: 0 03:52:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:38 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:38 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 03:52:38 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 03:52:38 INFO - Using env: {'DISPLAY': ':0', 03:52:38 INFO - 'HOME': '/home/cltbld', 03:52:38 INFO - 'LANG': 'en_US.UTF-8', 03:52:38 INFO - 'LANGUAGE': 'en_US:en', 03:52:38 INFO - 'LOGNAME': 'cltbld', 03:52:38 INFO - 'MAIL': '/var/mail/cltbld', 03:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:38 INFO - 'MOZ_NO_REMOTE': '1', 03:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:38 INFO - 'NO_EM_RESTART': '1', 03:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:38 INFO - 'PWD': '/builds/slave/test', 03:52:38 INFO - 'SHELL': '/bin/bash', 03:52:38 INFO - 'SHLVL': '1', 03:52:38 INFO - 'TERM': 'linux', 03:52:38 INFO - 'TMOUT': '86400', 03:52:38 INFO - 'USER': 'cltbld', 03:52:38 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:38 INFO - '_': '/tools/buildbot/bin/python'} 03:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:39 INFO - Running setup.py (path:/tmp/pip-Icci0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:39 INFO - Running setup.py (path:/tmp/pip-RG0C72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:39 INFO - Running setup.py (path:/tmp/pip-ONCpkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:39 INFO - Running setup.py (path:/tmp/pip-KbXRth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:39 INFO - Running setup.py (path:/tmp/pip-bMQhdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:39 INFO - Running setup.py (path:/tmp/pip-wxYotc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:39 INFO - Running setup.py (path:/tmp/pip-CKT8zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:39 INFO - Running setup.py (path:/tmp/pip-pFCX3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:39 INFO - Running setup.py (path:/tmp/pip-0l5HVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:39 INFO - Running setup.py (path:/tmp/pip-26CIde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:39 INFO - Running setup.py (path:/tmp/pip-SiSEUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:39 INFO - Running setup.py (path:/tmp/pip-jkNFIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:39 INFO - Running setup.py (path:/tmp/pip-CrsmvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:40 INFO - Running setup.py (path:/tmp/pip-1wUhWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:40 INFO - Running setup.py (path:/tmp/pip-KDIp5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:40 INFO - Running setup.py (path:/tmp/pip-cmDtdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:40 INFO - Running setup.py (path:/tmp/pip-33hNZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:52:40 INFO - Running setup.py install for manifestparser 03:52:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:52:40 INFO - Running setup.py install for mozcrash 03:52:40 INFO - Running setup.py install for mozdebug 03:52:40 INFO - Running setup.py install for mozdevice 03:52:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:52:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:52:40 INFO - Running setup.py install for mozfile 03:52:40 INFO - Running setup.py install for mozhttpd 03:52:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:52:40 INFO - Running setup.py install for mozinfo 03:52:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for mozInstall 03:52:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for mozleak 03:52:41 INFO - Running setup.py install for mozlog 03:52:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for moznetwork 03:52:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for mozprocess 03:52:41 INFO - Running setup.py install for mozprofile 03:52:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for mozrunner 03:52:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:52:41 INFO - Running setup.py install for mozscreenshot 03:52:41 INFO - Running setup.py install for moztest 03:52:42 INFO - Running setup.py install for mozversion 03:52:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:52:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:52:42 INFO - Cleaning up... 03:52:42 INFO - Return code: 0 03:52:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:42 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:42 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 03:52:42 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 03:52:42 INFO - Using env: {'DISPLAY': ':0', 03:52:42 INFO - 'HOME': '/home/cltbld', 03:52:42 INFO - 'LANG': 'en_US.UTF-8', 03:52:42 INFO - 'LANGUAGE': 'en_US:en', 03:52:42 INFO - 'LOGNAME': 'cltbld', 03:52:42 INFO - 'MAIL': '/var/mail/cltbld', 03:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:42 INFO - 'MOZ_NO_REMOTE': '1', 03:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:42 INFO - 'NO_EM_RESTART': '1', 03:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:42 INFO - 'PWD': '/builds/slave/test', 03:52:42 INFO - 'SHELL': '/bin/bash', 03:52:42 INFO - 'SHLVL': '1', 03:52:42 INFO - 'TERM': 'linux', 03:52:42 INFO - 'TMOUT': '86400', 03:52:42 INFO - 'USER': 'cltbld', 03:52:42 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:42 INFO - '_': '/tools/buildbot/bin/python'} 03:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:42 INFO - Running setup.py (path:/tmp/pip-l9YFDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:42 INFO - Running setup.py (path:/tmp/pip-55gkPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:42 INFO - Running setup.py (path:/tmp/pip-976jsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:42 INFO - Running setup.py (path:/tmp/pip-pY_OmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:42 INFO - Running setup.py (path:/tmp/pip-NBKdbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:42 INFO - Running setup.py (path:/tmp/pip-IzLew5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:42 INFO - Running setup.py (path:/tmp/pip-7YP0X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:42 INFO - Running setup.py (path:/tmp/pip-RkkmpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:42 INFO - Running setup.py (path:/tmp/pip-wmaVQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:42 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)) 03:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:42 INFO - Running setup.py (path:/tmp/pip-BtVUr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:43 INFO - Running setup.py (path:/tmp/pip-XaxSFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:43 INFO - Running setup.py (path:/tmp/pip-dIYCHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:43 INFO - Running setup.py (path:/tmp/pip-vSD4ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:43 INFO - Running setup.py (path:/tmp/pip-HcpKr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:43 INFO - Running setup.py (path:/tmp/pip-48DteO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:43 INFO - Running setup.py (path:/tmp/pip-IzRC_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:43 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)) 03:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:43 INFO - Running setup.py (path:/tmp/pip-8AmeMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:43 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)) 03:52:43 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)) 03:52:43 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)) 03:52:43 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)) 03:52:43 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)) 03:52:43 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)) 03:52:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:52:43 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 03:52:43 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 03:52:43 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 03:52:43 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 03:52:43 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 03:52:43 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 03:52:45 INFO - Downloading blessings-1.6.tar.gz 03:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:52:45 INFO - Installing collected packages: blessings 03:52:45 INFO - Running setup.py install for blessings 03:52:45 INFO - Successfully installed blessings 03:52:45 INFO - Cleaning up... 03:52:45 INFO - Return code: 0 03:52:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:45 INFO - Reading from file tmpfile_stdout 03:52:45 INFO - Current package versions: 03:52:45 INFO - argparse == 1.2.1 03:52:45 INFO - blessings == 1.6 03:52:45 INFO - blobuploader == 1.2.4 03:52:45 INFO - docopt == 0.6.1 03:52:45 INFO - functools32 == 3.2.3-2 03:52:45 INFO - jsonschema == 2.5.1 03:52:45 INFO - manifestparser == 1.1 03:52:45 INFO - mozInstall == 1.12 03:52:45 INFO - mozcrash == 0.17 03:52:45 INFO - mozdebug == 0.1 03:52:45 INFO - mozdevice == 0.48 03:52:45 INFO - mozfile == 1.2 03:52:45 INFO - mozhttpd == 0.7 03:52:45 INFO - mozinfo == 0.9 03:52:45 INFO - mozleak == 0.1 03:52:45 INFO - mozlog == 3.3 03:52:45 INFO - moznetwork == 0.27 03:52:45 INFO - mozprocess == 0.23 03:52:45 INFO - mozprofile == 0.28 03:52:45 INFO - mozrunner == 6.12 03:52:45 INFO - mozscreenshot == 0.1 03:52:45 INFO - mozsystemmonitor == 0.3 03:52:45 INFO - moztest == 0.7 03:52:45 INFO - mozversion == 1.4 03:52:45 INFO - psutil == 3.1.1 03:52:45 INFO - requests == 1.2.3 03:52:45 INFO - wsgiref == 0.1.2 03:52:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:45 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:45 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 03:52:45 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 03:52:45 INFO - Using env: {'DISPLAY': ':0', 03:52:45 INFO - 'HOME': '/home/cltbld', 03:52:45 INFO - 'LANG': 'en_US.UTF-8', 03:52:45 INFO - 'LANGUAGE': 'en_US:en', 03:52:45 INFO - 'LOGNAME': 'cltbld', 03:52:45 INFO - 'MAIL': '/var/mail/cltbld', 03:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:45 INFO - 'MOZ_NO_REMOTE': '1', 03:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:45 INFO - 'NO_EM_RESTART': '1', 03:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:45 INFO - 'PWD': '/builds/slave/test', 03:52:45 INFO - 'SHELL': '/bin/bash', 03:52:45 INFO - 'SHLVL': '1', 03:52:45 INFO - 'TERM': 'linux', 03:52:45 INFO - 'TMOUT': '86400', 03:52:45 INFO - 'USER': 'cltbld', 03:52:45 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:45 INFO - '_': '/tools/buildbot/bin/python'} 03:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 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)) 03:52:46 INFO - Cleaning up... 03:52:46 INFO - Return code: 0 03:52:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:46 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 0x255b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', '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 03:52:46 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 03:52:46 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 03:52:46 INFO - Using env: {'DISPLAY': ':0', 03:52:46 INFO - 'HOME': '/home/cltbld', 03:52:46 INFO - 'LANG': 'en_US.UTF-8', 03:52:46 INFO - 'LANGUAGE': 'en_US:en', 03:52:46 INFO - 'LOGNAME': 'cltbld', 03:52:46 INFO - 'MAIL': '/var/mail/cltbld', 03:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:46 INFO - 'MOZ_NO_REMOTE': '1', 03:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:46 INFO - 'NO_EM_RESTART': '1', 03:52:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:46 INFO - 'PWD': '/builds/slave/test', 03:52:46 INFO - 'SHELL': '/bin/bash', 03:52:46 INFO - 'SHLVL': '1', 03:52:46 INFO - 'TERM': 'linux', 03:52:46 INFO - 'TMOUT': '86400', 03:52:46 INFO - 'USER': 'cltbld', 03:52:46 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:46 INFO - '_': '/tools/buildbot/bin/python'} 03:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:52:46 INFO - Cleaning up... 03:52:46 INFO - Return code: 0 03:52:46 INFO - Running post-action listener: _resource_record_post_action 03:52:46 INFO - Running post-action listener: _start_resource_monitoring 03:52:46 INFO - Starting resource monitoring. 03:52:46 INFO - [mozharness: 2016-09-19 10:52:46.384865Z] Finished create-virtualenv step (success) 03:52:46 INFO - [mozharness: 2016-09-19 10:52:46.385299Z] Running install step. 03:52:46 INFO - Running pre-action listener: _resource_record_pre_action 03:52:46 INFO - Running main action method: install 03:52:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:46 INFO - Reading from file tmpfile_stdout 03:52:46 INFO - Detecting whether we're running mozinstall >=1.0... 03:52:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:52:46 INFO - Reading from file tmpfile_stdout 03:52:46 INFO - Output received: 03:52:46 INFO - Usage: mozinstall [options] installer 03:52:46 INFO - Options: 03:52:46 INFO - -h, --help show this help message and exit 03:52:46 INFO - -d DEST, --destination=DEST 03:52:46 INFO - Directory to install application into. [default: 03:52:46 INFO - "/builds/slave/test"] 03:52:46 INFO - --app=APP Application being installed. [default: firefox] 03:52:46 INFO - mkdir: /builds/slave/test/build/application 03:52:46 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'] 03:52:46 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 03:53:03 INFO - Reading from file tmpfile_stdout 03:53:03 INFO - Output received: 03:53:03 INFO - /builds/slave/test/build/application/firefox/firefox 03:53:03 INFO - Running post-action listener: _resource_record_post_action 03:53:03 INFO - [mozharness: 2016-09-19 10:53:03.574603Z] Finished install step (success) 03:53:03 INFO - [mozharness: 2016-09-19 10:53:03.574890Z] Running run-tests step. 03:53:03 INFO - Running pre-action listener: _resource_record_pre_action 03:53:03 INFO - Running main action method: run_tests 03:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:03 INFO - Python 2.7.3 03:53:03 INFO - Return code: 0 03:53:03 INFO - grabbing minidump binary from tooltool 03:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:03 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 0x281a3b0>, '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 0x281b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x281b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:03 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 03:53:03 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 03:53:03 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 03:53:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:03 INFO - Return code: 0 03:53:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:53:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:53:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:03 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/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:53:03 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/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:53:03 INFO - Using env: {'DISPLAY': ':0', 03:53:03 INFO - 'HOME': '/home/cltbld', 03:53:03 INFO - 'LANG': 'en_US.UTF-8', 03:53:03 INFO - 'LANGUAGE': 'en_US:en', 03:53:03 INFO - 'LOGNAME': 'cltbld', 03:53:03 INFO - 'MAIL': '/var/mail/cltbld', 03:53:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:03 INFO - 'MOZ_NO_REMOTE': '1', 03:53:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:03 INFO - 'NO_EM_RESTART': '1', 03:53:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:03 INFO - 'PWD': '/builds/slave/test', 03:53:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:03 INFO - 'SHELL': '/bin/bash', 03:53:03 INFO - 'SHLVL': '1', 03:53:03 INFO - 'TERM': 'linux', 03:53:03 INFO - 'TMOUT': '86400', 03:53:03 INFO - 'USER': 'cltbld', 03:53:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940', 03:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:03 INFO - '_': '/tools/buildbot/bin/python'} 03:53:03 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/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:53:03 INFO - mozversion application_buildid: 20160919004008 03:53:03 INFO - mozversion application_changeset: c174d0c3195371011b3bf8044512c06c26f5c741 03:53:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:53:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:03 INFO - mozversion application_name: Firefox 03:53:03 INFO - mozversion application_remotingname: firefox-dev 03:53:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:03 INFO - mozversion application_vendor: Mozilla 03:53:03 INFO - mozversion application_version: 50.0a2 03:53:03 INFO - mozversion platform_buildid: 20160919004008 03:53:03 INFO - mozversion platform_changeset: c174d0c3195371011b3bf8044512c06c26f5c741 03:53:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:03 INFO - mozversion platform_version: 50.0a2 03:53:03 INFO - using testdate: 1474282383 03:53:03 INFO - actual date: 1474282383 03:53:03 INFO - starting webserver on 'localhost:52045' 03:53:03 INFO - SUITE-START | Running 2 tests 03:53:03 INFO - TEST-START | dromaeo_css 03:53:03 INFO - operating with platform_type : linux_ 03:53:03 INFO - Initialising browser for dromaeo_css test... 03:53:03 INFO - TEST-INFO | started process 6694 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaq0yTJ/profile http://localhost:52045/getInfo.html) 03:53:07 INFO - PROCESS | 6694 | __metrics Screen width/height:1600/1200 03:53:07 INFO - PROCESS | 6694 | colorDepth:24 03:53:07 INFO - PROCESS | 6694 | Browser inner width/height: 1024/697 03:53:07 INFO - PROCESS | 6694 | __metrics 03:53:08 INFO - PROCESS | 6694 | 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" 03:53:08 INFO - PROCESS | 6694 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:53:09 INFO - TEST-INFO | 6694: exit 0 03:53:09 INFO - Browser initialized. 03:53:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:09 INFO - TEST-INFO | started process 6786 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaq0yTJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:21 INFO - PROCESS | 6786 | RSS: Main: 146198528 03:53:21 INFO - PROCESS | 6786 | 03:54:12 INFO - PROCESS | 6786 | 1474282452593 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] 03:56:21 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52045/tests/dromaeo/cssquery-ext.html) 03:56:22 INFO - PROCESS | 6786 | RSS: Main: 267857920 03:56:22 INFO - PROCESS | 6786 | 03:59:24 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-ext.html (next: http://localhost:52045/tests/dromaeo/cssquery-jquery.html) 03:59:25 INFO - PROCESS | 6786 | RSS: Main: 227487744 03:59:25 INFO - PROCESS | 6786 | 04:02:27 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52045/tests/dromaeo/cssquery-mootools.html) 04:02:27 INFO - PROCESS | 6786 | RSS: Main: 219181056 04:02:27 INFO - PROCESS | 6786 | 04:05:29 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52045/tests/dromaeo/cssquery-prototype.html) 04:05:30 INFO - PROCESS | 6786 | RSS: Main: 221036544 04:05:30 INFO - PROCESS | 6786 | 04:08:33 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52045/tests/dromaeo/cssquery-yui.html) 04:08:33 INFO - PROCESS | 6786 | RSS: Main: 176676864 04:08:33 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | Cycle 1(1): loaded http://localhost:52045/tests/dromaeo/cssquery-yui.html (next: http://localhost:52045/tests/dromaeo/cssquery-dojo.html) 04:11:36 INFO - PROCESS | 6786 | RSS: Main: 263483392 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | __start_tp_report 04:11:36 INFO - PROCESS | 6786 | _x_x_mozilla_page_load 04:11:36 INFO - PROCESS | 6786 | _x_x_mozilla_page_load_details 04:11:36 INFO - PROCESS | 6786 | |i|pagename|runs| 04:11:36 INFO - PROCESS | 6786 | |0;dojo.html;3201.615806103267;3201.727853132483;3223.6615155408695;3243.270264190559;3257.1694217974414;5465.562754979597;5555.833325000246;5595.21666966625;5603.579731520143;5616.241807356011;5503.504684578387;5559.499645031946;5579.804706835262;5580.609357344987;5596.244506991555;4844.563989240967;4908.9263661045115;5003.649744517876;5024.346834911456;5087.592992560605;4908.092002979442;4949.208126699728;4959.925601115968;4989.800407983676;5017.673851199678;4921.680090794087;4939.135651261033;4954.900901981958;4966.130927087764;4971.428285747131;5001.949980500185;5011.273365362021;5027.748612569393;5028.1200789862;5060.21566657165;4814.374131362901;4833.419989601235;4864.343313652647;4931.926021109686;4964.503549645043;5321.205116964885;5321.255024296602;5338.3593968724;5363.436839131874;5396.19057141428;5800.216970708942;5807.128930660392;5810.767569297223;5813.47678708914;5819.098039803837;5668.659880407147;5835.912461313083;5846.239988801472;5875.059990401558;5884.352721200665;5577.135543990688;5578.330600327987;5586.944130558723;5605.327360716699;5612.326520817487;6084;6091.116788065794;6094.9695251524345;6096.451319381277;6098.176746139215;5545.390007099216;5711.057675483582;5765.394633563499;5769.88460230793;5798.985177593904;5903.822885313448;5913.940860591339;5914.58597898143;5916.053431450947;5943.197668314809;5869.794557190563;5883.058710606282;5893.5579831512805;5894.705264736746;5897.144913987534;5655.349634791969;5706.857328566736;5801.303843538718;5809.099589563624;5888.793892213837;4570.017446249072;4573.085382923428;4573.588377046081;4584.381108550369;4594.035252596926;4074.5721699221153;4093.8362465501045;4098.959010409917;4102.610252026053;4114.033202197481;5338.119210330329;5473.261109750133;5594.832155035356;5602.971985140048;5603.15952607104;5837.708114594253;5845.09401042839;5852.180694702745;5876.030454974967;5883.588148829626;5501.174823776466;5508.697021663847;5509.531689806411;5510.586705997066;5514.145307477347;4974.204127339608;5062.772175252049;5068.391792984792;5082.33929589151;5102.821401250939;6075.908861367083;6140.600860944024;6169.56813023084;6169.81490555284;6170.7223174957235;5890.646561206341;5926.67403292823;5937.732802023833;5939.049752039653;5965.9105113422465;5953.136795164591;5989.760409583568;6143.96928015357;6155.938440615537;6175.44421002112;5701.686407247641;5723.771049157987;5747.597668163188;5794.652320860593;5796.21751063601;5822.76708931655;5831.125331200354;5836.620619659879;5843.912341314969;5848.970755146367;5518.310211223597;5617.073182924772;5730.169125476866;5733;5735.254416925773;5603.075492543848;5655.688937108499;5663.292088488939;5675.801346952837;5695.088785794253;5591.133374326986;5601.7759289629585;5606.523445507014;5620.578456615688;5650.943490565206;5568.359638641608;5659.575531835211;5684.3747187808485;5687.800926967537;5733.111367738007;5656.434356564475;5710.200571919851;5721.542276617944;5733;5752.050911599539 04:11:36 INFO - PROCESS | 6786 | |1;ext.html;587.2629849538828;625.6996641612026;664.2527156948433;667.2693400726206;674.4166296153828;35158.121046973836;47191.76404117979;48472;48540;48647.75676121623;49768;50021.24968125481;50047.24929126066;50395.24407133896;50422.49577504224;9313.487758173282;9448.905510944905;9468.526573671323;9475.384100033476;9478.241740660766;8491.787705307386;11458.656240312763;11725.237859539146;11747.060235181165;11765.529378824836;11428.371439570821;11527.942360288187;11531.481083351227;11532.192746507748;11550.36472993985;11296.548138074468;11366.431678416046;11367.488463019185;11379.601713939972;11382.317060976367;11277.943610281978;11345.886541134565;11350.659480215607;11356.318620882774;11363;48656.513434865556;48873.51126488725;49004.754976225246;49029.50970490285;49070.26394604084;4044.1102957349335;4097.160082183175;4257.531671516131;4331.393604895317;4628.490866004737;36010;36359.63640363589;36640;36706.44940325897;38335;4476.8880777980485;4501.549845015505;4517.864464066083;4525.2533332000185;4546.659000574937;417230;417973;418366;418379;419224.90387548174;38582.61417385846;69930;70410.64794676096;70447.29552704407;70554.64722676354;39987.40018899719;41929.161416771494;42071;42100;42123.368149477785;18073.45779626613;18203.27186912535;18211.63576728458;18213.362532311305;18213.544661383305;12296.262224266573;12928.547500837614;15310.387584496719;15361.692766144615;15382.923085384502;8807.559622018873;8859.158379953895;8986.910130898737;9242;9256.444613323087;3552.786832790041;3565.4651802229355;3593.5687717473547;3615.240799432096;3626.9818650907105;1084.8969347911936;1146.3809542846936;1161.8780028096928;1184.3308530680138;1199.5201919232202;36367;36383.81808090943;37117.81441092831;37644.2471150581;40081.39877901834;1160.8374827524153;1184.579474286633;1199.940002999864;1204.7289359893953;1207.8912897839236;18332.633347333245;19313.90343048276;19395.418137455898;19535.804641953684;19712.70430943537;627142;632242;633205;635011;635878;69591;70059;70093.29906700867;70165.64917175483;70177.64911175513;1375.4223226245;1455.1560095144791;1466.3767898643034;1500.4673340964018;1524.1083965879864;31330;32807;33169;33505.66494335123;34152;59482;60076.699616503385;60151.09773353491;60190.09714854193;60284.09573856308;464345;469837;470887;471536;471642;52025.7398713004;52418;55309.72345138249;55388.72305638446;55723.442765571825;52167;52172.739136305594;56930;57388.71305643445;58335.416645834695;45657.31514027359;46666;48731.26903096386;48763.756181218865;49129.263061053396;47806;48197;49911.25133122934;51744.48255517547;52269;48697.513024869295;49483.7525812383;49859.75070124626;50437.49562504328;50529.74735126301;41013.179736404505;43513.56486435095;43601.127977440905;43829.56170438255;43849.780751097314;32020.35959280755;32025.839870800497;32403.4676104504;32530.83734581312;33015.66984330222 04:11:36 INFO - PROCESS | 6786 | |2;jquery.html;387.25840016368636;393.88971088095343;421.2880232407232;424.2342571658157;425.79349015727365;16294.511164665064;20219.292324768638;20564.691529627067;20872.68690969635;22241.332760017183;20979.16083356668;21458.785412145873;21474.46313842151;21696.240631577897;21760.238391656294;5542.168674698797;5873.412658734113;5901.20333754944;5907.4978626816555;5911.379305172959;4272.743635381871;5838.708064596774;5866.6480011199465;5913.172155898178;6275;6453.580517266386;6608.504362172832;6608.801735947929;6609.173853268341;6616.139901812781;6377.649229292387;6504.837379065514;6551.475881929434;6552.967235163794;6577.177852768404;5628.85927851807;5778.162166485836;5783.306003279591;5784.768609255625;5787.450192231773;25311;25716;25734.742652573423;25743.74256257451;25939.351516212057;2557.360659835041;2602.050251658131;2651.5890037044096;3006.909792706222;3178.411993781158;15379.384824607003;15654.843451565566;15765.211739413098;15765.60585985348;15882.76175857363;1987.3441764217773;2081.396395045435;2086;2099.4751312171957;2130.360891732474;66475;67890;88764.5561772187;90231;90404.09595904022;11017.83473247902;11226.438678066064;11266.943665281786;11305.71735706614;11490.080793536497;15560.610984725472;15576.376944922076;15579.065256084672;15585.844141558438;15590.84409155894;8652.91838520185;8727.825443491221;8877.866831997526;8952.68665596701;9102.180803727697;8772.166644168794;8841.69054083104;8918.1973622373;9037.638494460149;9060.728178154666;6878.243393226841;6921.134858142732;6963.547369420971;6990.335918087773;7042.366187043191;1971.132701611315;1977.6835706286925;1988.2047181127375;1990.9203631854853;2069.9896500517702;1031.3141760729181;1051.558345494894;1083.8320060390652;1099.2360309584762;1138.2316936068287;21633.459163521038;22392.32823015312;22426.775732242793;22539.549209015728;22620.208292709674;1083.2038451737967;1094.9178811589163;1098.6484325016079;1109.5728144664363;1156.4275683536703;12046.518139274505;12496.817893670142;12625.431855566338;12752.872471275168;12752.93623531895;502163;545458;555605;568901;569745;88257;89822;89969;91298;91701.5414922921;1077.3428208792473;1100.7193165742685;1105.4528008635777;1109.4840898982013;1134.9489272982735;19247;19389.709154362412;19771.308004219783;20092.39722808318;20136.697949531062;29155.416891662473;41253;41738.37392439055;45967;46763.29855052109;62851.68574157283;63741;63939.36060639334;64198;64672.35327646663;8500.872486912578;8825.293976481738;8835.911640883765;8902.821943560963;8981.191692747683;11699.590514331958;14100.717985640025;14217.21805300677;14398.85601144017;15113.471028514388;12319.199252048587;12564.560240391542;12665.493380264686;12735.681607959876;12758;11764.764704705687;11865.881341186478;11867.643970680894;11987.22083064598;12835.679108022374;6600.636964966973;10898.618548350769;11149.554017839195;12717.491300347883;13018.2839943804;7474.439417043852;9159.450432974043;9222.538873056454;9533.380330278509;9584.185344245818;10647.840282395926;11392.544298227978;11919.761604767682;11999.340036298088;12182.695432614255 04:11:36 INFO - PROCESS | 6786 | |3;mootools.html;1439.9064060836051;1549.8527639874214;1551.9844801551985;1751.4920673004829;1873.9906300468504;8959.641614335427;9244.39911405759;9299.674511392102;9715.611375544988;9727.805443891119;9559.044095590438;9685.757856053602;9686.564104615292;9690.079442452956;9698.175655069328;2549.3499157714755;2576.8196226264167;2578.0203522661454;2580.213035024317;2580.9096681616147;2530.4306531030556;2603.7656610905015;2604.93487662809;2605.6612640356725;2606.7262937391492;2527.519771243467;2546.745325467457;2609.7781688556497;2612.4122072533623;2613.856237906924;2488.22864911877;2492.5762620354494;2507.1976967370447;2536.936576585582;2538.860362680052;2485.9005639774387;2517.6349429332827;2518.143831097436;2543.9618405723927;2546.5925451927787;9573.090556397201;9675.806483870354;9676.225901927828;9677.12905838471;9706.90293097074;5696.914546281809;5876.941230587725;5883.676397798119;5987.042073268256;5998.460138587505;3621.728370372233;3633.8601532567122;3694.8891533253777;3700.13046933973;3714.7585406948733;1132.0377678972889;1143.5025763792776;1146.7419830538145;1152.6657269391867;1163.1334655681487;13517.32413379327;13530;13562.254076025814;13612.523561675393;13647.931760341135;4835.3714017178245;4882.487338829443;4914.5822605078965;4996.275540046746;5076.340075790124;10637.680869573926;10639.14886809069;10640.095591874622;10641.361518308926;10662.573497060186;5714.714264286769;5740.167675687086;5744.827655170351;5745.712714364265;5747.5114615257335;4731.101090792738;4822;5344.144936810169;5353.81261655848;5381.78472861089;4156.459660244149;4182.5207499556755;4262.062346283875;4278.807453664593;4357.7385356878685;1244.1353259484572;1264.4562837979572;1284.563248495516;1292.0309767674244;1294.2687381629348;1014.1886490807325;1024.2011231239644;1025.415513157493;1028.840529717895;1035.4874337202982;6078.1186727924205;6079.483243924316;6219.657918814418;6229.657368844757;6354.3010268870385;476.80927628948007;483.10143133771857;487.98048078077187;492.7413108118195;510.19644060604696;4169.8957526062095;4304.031592891637;4312.719673221231;4327.896386421442;4368.410264614236;11789.469473873696;12138.939305303593;12218.083643726755;12235;12241.081918856122;11229.438528073564;11715.062794976382;12139.817902731465;12155.513779448724;12166.391680415943;8866.246369058574;9065.456072635663;9093.590788414669;9106.908930910606;9113.088691130833;6071.119687645355;6074.240719910012;6174.845628859314;6268.968655156695;6291.119243306032;11255.268407553483;11286.435678216221;11299.548018079186;11399.715007124889;11442.37066961325;10314;10602.045815876609;10960.725981850517;11509.366984815915;11669.241499302518;7853.371730261451;8094.797630059295;8443.704470343508;8588.527630980107;8600.526971016403;9635.903640963501;9640.276979226726;9653.662121825702;9693.41839489633;9703.466309353053;8055.476394034369;8159.46963447374;8176.7955801105445;8334.749957501284;8446.83106337882;7925.326347260548;8178.427510074475;8192.631331589973;8336.666533338841;8460.450070745628;7401.48189626721;7419.591922444318;7494.6252687366505;7504.587247701429;7507.474476786572;5201.661891977009;5348.171033489815;5380.569554435714;5405.24326594285;5415.160650099241;5406.702631355313;5450.127979523258;5493;5494.313210848644;5537.058700021086 04:11:36 INFO - PROCESS | 6786 | |4;prototype.html;352.83593129194935;360.24169123994;361.6401680328073;363.2063940290467;367.89698884312384;5422.8069824638615;5488.862778430536;5494.148406996922;5495.6702597844105;5556.91664625031;5169.56058735008;5296.848375216749;5309.601779866506;5332.440093790145;5492.368377636573;4171.353440216772;4214.536400995896;4268.743875367472;4289.2922667759785;4293.29160688486;4269.124829409979;4279.673301276614;4308.224519586473;4312.568743125678;4322.589354011379;4047.9392809107885;4217.240896638604;4229.154169166164;4253.085586598881;4264.658827293809;4207.747535147901;4220.87337379876;4245.8513952011535;4252.8511502097435;4261.850835220768;3778.1688028633657;3778.678812300958;3788.1666033472593;3791.620837916214;3799.715021373408;5205.6616319939085;5211.374635043781;5239.318888544503;5300.416954135042;5341.252224688546;4842.539958703917;4856.8300109495995;4863.440704318978;4875.731834749087;4881.780319885614;5600.27196464457;5637.661740295595;5669.26299581056;5669.489745922887;5694.5159661428825;3870.1679138984973;4004.0790618157694;4045.8988525287104;4161.500619925629;4162.500499939996;5747.499837570628;5760.855978600526;5770.134479828017;5781.4507621775865;5832.125181222808;4223.57144641826;4225.19721252961;4258.1696569169335;4258.6380157686945;4277.973286411301;4219.599138081878;4251.893702657397;4269.551697071763;4322.805473753689;4331.761753103609;4805.639577031737;4811.735354555463;4818.494058123917;4827.179379505493;4849.733264670477;5632.155176723442;5645.556210711463;5685.943140568541;5702.344230413472;5713.4000929902595;1408.704172123845;1447.949321773733;1450.9274536273144;1471.2423102102425;1480.0897448069575;932.6501819261953;936.840737074699;940.304174910575;954.2938225712923;964.1708131007329;4877;4894.632902532324;4896.2655601660035;4927.359443272351;4955.702657840541;5186.2479940409185;5191.32512773337;5196.68819870809;5199.750156152405;5206.474859693046;4934.284528743312;4949.851504454872;4953.430355509163;4959.280904268933;5123.231515272739;3047.17726213921;3082.922926926845;3090.1656552731088;3090.922726931845;3096.8451577421038;6042.969785151134;6065;6072.692376896751;6121.908171377433;6151.138840562325;5676.4323567642905;5915.852103697356;5936;5959.195508606369;5963.7912673056235;6083.543734219951;6095.8476038098515;6096.817095487142;6098.939010609926;6125.1731016313115;5620.494155526022;5807.490052586273;5861.267341582302;5894.440028197486;5911.615744976562;5729.197912292199;5739.335592678243;5765.798197063083;5773.336066352424;5784.1034639630925;6094.268687757498;6094.8171554853425;6105.267367915701;6119.326874043941;6131.601445906002;3894.3379625464304;4045.8988525287104;4217.894552636209;4225.704200705921;4249.3413520904305;5663.235463212413;5663.858403539944;5668.121441176624;5685.204071430086;5693.402192769865;5634.154876768517;5688.3742788294085;5699.401562835843;5701.287339082614;5706.457886500776;5445.346558413015;5666.121751128581;5666.773329066956;5675.858103547444;5691.4877661010705;5624.465675760796;5631.915521267102;5645.181448689835;5645.403114567345;5661.575381846295;5581.441855814386;5697.800576979786;5716.456936591184;5789.697318103393;5793.710314484343;5521.5306698931045;5555.944440555542;5560.277163968658;5568.582356323211;5570.359408667893 04:11:36 INFO - PROCESS | 6786 | |5;yui.html;814.1166833985126;830.6760363458252;835.5989125219894;840.8444437779009;902.2646543067395;16152.757708634379;17487.38794142207;17508.21213045413;17529.298828046896;17635.823641763578;15573.922130389361;16056.83943160568;16219.351225950977;16270.918645406786;16297.511074667778;738.4424759306715;859.3941271403645;860.0238729042519;861.1646702698373;865.1002956924791;748.0425055928393;760.7413479416997;760.8288135169598;843.1989609870617;888.9866652000226;923.5197697197453;935.5836652689555;936.69089200564;936.9906300937006;937.1144268666113;924.3113880159257;925.3476299209109;926.1803304075908;926.666400095965;927.3693888156051;549.9175123731472;642.5726891617069;681.608075356667;689.018149137477;690.6753825701912;15428.614284642974;15457.149856757875;15460.304286307031;15462.072275663384;15477.613059673486;14818.184999825005;14987.550373488813;15122.697546049127;15385.846141538554;15589.844101558952;13797.379117939718;13876.653083672989;13909.721805563933;14169.78745318821;14239.359228834728;4648.3259927310355;4752.144613969484;4845.055214233226;4860.3195552622665;4864.708117512961;89871.55064224637;89883;90023;90209;90280;11500.424978751029;11688.532458701557;12198.878011219951;12283.201591896499;12820.102592818414;21714.13143474275;21747;21771.12915483363;21781.673274900888;21849.235276765237;8925.241354484942;8970.237529809909;9207.217386522087;9265.490398028038;9352.859707104399;4913.091078150554;5002.57478114357;5061.544460998528;5104.668196567285;5132.79468821243;3614.2229420674157;3751.4747935289083;3753.3619284721663;3754.0614846288427;3765.8870233893026;634.6953462338012;637.4072112934922;684.8287928017995;726.5676922231264;730.3682314797662;769.5536588778496;787.9448438609357;796.4783067091065;820.1060843680417;838.1869586501206;10225.642102526375;10346.53440595175;10639.946800265949;10830.729231769268;10951.83572246417;656.9507286953498;757.806759276392;785.1755656560589;788.0936922539125;792.4730054513799;5024.899502009939;5073.188289873602;5109.335786347825;5243.501867322674;5307.973460132752;130610.34694826465;133398;133576.33211834074;136394;139763;90108.54945725361;108231;114402;117323;118160;97831;97887;101611.49194253981;101698.25301234853;103050;9760.414375137514;9822.414818591305;10088.54601542947;10129.341592796445;10142.755423660372;48232.51767482421;56452.43547564636;56505.15242271287;56847.71576142093;58768.11847822372;49411.75294123506;51490.22764658458;63348.68325658342;77081;77866;14453.421863125357;14469.421223150955;14689.632759181106;14756.63108422298;14816.185109818633;15794.842051579797;16134.354625814836;16311.59221019484;16673.749893751847;16975.151242438074;14756.188409637143;15075.623109422353;15680.049210987165;16278.837211628206;16376.75434868454;12264.264144151379;12979.935100324437;13485.86514134846;13929.721405571629;14131.15213087218;14198.290085495477;14919;14928.253587320807;15243.16162611067;15426.074435533905;11897.762044758883;12321.281309924772;12494.750104997667;12530.060245481443;12670.176438531465;12577.308248046445;12638;12917.357917010162;12973.545925892926;13207.801882971571 04:11:36 INFO - PROCESS | 6786 | __end_tp_report 04:11:36 INFO - PROCESS | 6786 | __start_cc_report 04:11:36 INFO - PROCESS | 6786 | _x_x_mozilla_cycle_collect,1020 04:11:36 INFO - PROCESS | 6786 | __end_cc_report 04:11:36 INFO - PROCESS | 6786 | __startTimestamp1474283496468__endTimestamp 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | ------- Summary: start ------- 04:11:36 INFO - PROCESS | 6786 | Number of tests: 6 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#0] dojo.html Cycles:165 Average:5448.81 Median:5617.07 stddev:606.84 (10.8%) stddev-sans-first:582.53 04:11:36 INFO - PROCESS | 6786 | Values: 3201.6 3201.7 3223.7 3243.3 3257.2 5465.6 5555.8 5595.2 5603.6 5616.2 5503.5 5559.5 5579.8 5580.6 5596.2 4844.6 4908.9 5003.6 5024.3 5087.6 4908.1 4949.2 4959.9 4989.8 5017.7 4921.7 4939.1 4954.9 4966.1 4971.4 5001.9 5011.3 5027.7 5028.1 5060.2 4814.4 4833.4 4864.3 4931.9 4964.5 5321.2 5321.3 5338.4 5363.4 5396.2 5800.2 5807.1 5810.8 5813.5 5819.1 5668.7 5835.9 5846.2 5875.1 5884.4 5577.1 5578.3 5586.9 5605.3 5612.3 6084.0 6091.1 6095.0 6096.5 6098.2 5545.4 5711.1 5765.4 5769.9 5799.0 5903.8 5913.9 5914.6 5916.1 5943.2 5869.8 5883.1 5893.6 5894.7 5897.1 5655.3 5706.9 5801.3 5809.1 5888.8 4570.0 4573.1 4573.6 4584.4 4594.0 4074.6 4093.8 4099.0 4102.6 4114.0 5338.1 5473.3 5594.8 5603.0 5603.2 5837.7 5845.1 5852.2 5876.0 5883.6 5501.2 5508.7 5509.5 5510.6 5514.1 4974.2 5062.8 5068.4 5082.3 5102.8 6075.9 6140.6 6169.6 6169.8 6170.7 5890.6 5926.7 5937.7 5939.0 5965.9 5953.1 5989.8 6144.0 6155.9 6175.4 5701.7 5723.8 5747.6 5794.7 5796.2 5822.8 5831.1 5836.6 5843.9 5849.0 5518.3 5617.1 5730.2 5733.0 5735.3 5603.1 5655.7 5663.3 5675.8 5695.1 5591.1 5601.8 5606.5 5620.6 5650.9 5568.4 5659.6 5684.4 5687.8 5733.1 5656.4 5710.2 5721.5 5733.0 5752.1 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#1] ext.html Cycles:180 Average:68699.90 Median:23922.48 stddev:136712.47 (571.5%) stddev-sans-first:137000.34 04:11:36 INFO - PROCESS | 6786 | Values: 587.3 625.7 664.3 667.3 674.4 35158.1 47191.8 48472.0 48540.0 48647.8 49768.0 50021.2 50047.2 50395.2 50422.5 9313.5 9448.9 9468.5 9475.4 9478.2 8491.8 11458.7 11725.2 11747.1 11765.5 11428.4 11527.9 11531.5 11532.2 11550.4 11296.5 11366.4 11367.5 11379.6 11382.3 11277.9 11345.9 11350.7 11356.3 11363.0 48656.5 48873.5 49004.8 49029.5 49070.3 4044.1 4097.2 4257.5 4331.4 4628.5 36010.0 36359.6 36640.0 36706.4 38335.0 4476.9 4501.5 4517.9 4525.3 4546.7 417230.0 417973.0 418366.0 418379.0 419224.9 38582.6 69930.0 70410.6 70447.3 70554.6 39987.4 41929.2 42071.0 42100.0 42123.4 18073.5 18203.3 18211.6 18213.4 18213.5 12296.3 12928.5 15310.4 15361.7 15382.9 8807.6 8859.2 8986.9 9242.0 9256.4 3552.8 3565.5 3593.6 3615.2 3627.0 1084.9 1146.4 1161.9 1184.3 1199.5 36367.0 36383.8 37117.8 37644.2 40081.4 1160.8 1184.6 1199.9 1204.7 1207.9 18332.6 19313.9 19395.4 19535.8 19712.7 627142.0 632242.0 633205.0 635011.0 635878.0 69591.0 70059.0 70093.3 70165.6 70177.6 1375.4 1455.2 1466.4 1500.5 1524.1 31330.0 32807.0 33169.0 33505.7 34152.0 59482.0 60076.7 60151.1 60190.1 60284.1 464345.0 469837.0 470887.0 471536.0 471642.0 52025.7 52418.0 55309.7 55388.7 55723.4 52167.0 52172.7 56930.0 57388.7 58335.4 45657.3 46666.0 48731.3 48763.8 49129.3 47806.0 48197.0 49911.3 51744.5 52269.0 48697.5 49483.8 49859.8 50437.5 50529.7 41013.2 43513.6 43601.1 43829.6 43849.8 32020.4 32025.8 32403.5 32530.8 33015.7 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#2] jquery.html Cycles:180 Average:31473.07 Median:14136.68 stddev:90122.43 (637.5%) stddev-sans-first:90345.02 04:11:36 INFO - PROCESS | 6786 | Values: 387.3 393.9 421.3 424.2 425.8 16294.5 20219.3 20564.7 20872.7 22241.3 20979.2 21458.8 21474.5 21696.2 21760.2 5542.2 5873.4 5901.2 5907.5 5911.4 4272.7 5838.7 5866.6 5913.2 6275.0 6453.6 6608.5 6608.8 6609.2 6616.1 6377.6 6504.8 6551.5 6553.0 6577.2 5628.9 5778.2 5783.3 5784.8 5787.5 25311.0 25716.0 25734.7 25743.7 25939.4 2557.4 2602.1 2651.6 3006.9 3178.4 15379.4 15654.8 15765.2 15765.6 15882.8 1987.3 2081.4 2086.0 2099.5 2130.4 66475.0 67890.0 88764.6 90231.0 90404.1 11017.8 11226.4 11266.9 11305.7 11490.1 15560.6 15576.4 15579.1 15585.8 15590.8 8652.9 8727.8 8877.9 8952.7 9102.2 8772.2 8841.7 8918.2 9037.6 9060.7 6878.2 6921.1 6963.5 6990.3 7042.4 1971.1 1977.7 1988.2 1990.9 2070.0 1031.3 1051.6 1083.8 1099.2 1138.2 21633.5 22392.3 22426.8 22539.5 22620.2 1083.2 1094.9 1098.6 1109.6 1156.4 12046.5 12496.8 12625.4 12752.9 12752.9 502163.0 545458.0 555605.0 568901.0 569745.0 88257.0 89822.0 89969.0 91298.0 91701.5 1077.3 1100.7 1105.5 1109.5 1134.9 19247.0 19389.7 19771.3 20092.4 20136.7 29155.4 41253.0 41738.4 45967.0 46763.3 62851.7 63741.0 63939.4 64198.0 64672.4 8500.9 8825.3 8835.9 8902.8 8981.2 11699.6 14100.7 14217.2 14398.9 15113.5 12319.2 12564.6 12665.5 12735.7 12758.0 11764.8 11865.9 11867.6 11987.2 12835.7 6600.6 10898.6 11149.6 12717.5 13018.3 7474.4 9159.5 9222.5 9533.4 9584.2 10647.8 11392.5 11919.8 11999.3 12182.7 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#3] mootools.html Cycles:180 Average:6269.34 Median:2603.96 stddev:3641.78 (139.9%) stddev-sans-first:3633.91 04:11:36 INFO - PROCESS | 6786 | Values: 1439.9 1549.9 1552.0 1751.5 1874.0 8959.6 9244.4 9299.7 9715.6 9727.8 9559.0 9685.8 9686.6 9690.1 9698.2 2549.3 2576.8 2578.0 2580.2 2580.9 2530.4 2603.8 2604.9 2605.7 2606.7 2527.5 2546.7 2609.8 2612.4 2613.9 2488.2 2492.6 2507.2 2536.9 2538.9 2485.9 2517.6 2518.1 2544.0 2546.6 9573.1 9675.8 9676.2 9677.1 9706.9 5696.9 5876.9 5883.7 5987.0 5998.5 3621.7 3633.9 3694.9 3700.1 3714.8 1132.0 1143.5 1146.7 1152.7 1163.1 13517.3 13530.0 13562.3 13612.5 13647.9 4835.4 4882.5 4914.6 4996.3 5076.3 10637.7 10639.1 10640.1 10641.4 10662.6 5714.7 5740.2 5744.8 5745.7 5747.5 4731.1 4822.0 5344.1 5353.8 5381.8 4156.5 4182.5 4262.1 4278.8 4357.7 1244.1 1264.5 1284.6 1292.0 1294.3 1014.2 1024.2 1025.4 1028.8 1035.5 6078.1 6079.5 6219.7 6229.7 6354.3 476.8 483.1 488.0 492.7 510.2 4169.9 4304.0 4312.7 4327.9 4368.4 11789.5 12138.9 12218.1 12235.0 12241.1 11229.4 11715.1 12139.8 12155.5 12166.4 8866.2 9065.5 9093.6 9106.9 9113.1 6071.1 6074.2 6174.8 6269.0 6291.1 11255.3 11286.4 11299.5 11399.7 11442.4 10314.0 10602.0 10960.7 11509.4 11669.2 7853.4 8094.8 8443.7 8588.5 8600.5 9635.9 9640.3 9653.7 9693.4 9703.5 8055.5 8159.5 8176.8 8334.7 8446.8 7925.3 8178.4 8192.6 8336.7 8460.5 7401.5 7419.6 7494.6 7504.6 7507.5 5201.7 5348.2 5380.6 5405.2 5415.2 5406.7 5450.1 5493.0 5494.3 5537.1 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#4] prototype.html Cycles:180 Average:4748.45 Median:5305.01 stddev:1386.05 (26.1%) stddev-sans-first:1350.10 04:11:36 INFO - PROCESS | 6786 | Values: 352.8 360.2 361.6 363.2 367.9 5422.8 5488.9 5494.1 5495.7 5556.9 5169.6 5296.8 5309.6 5332.4 5492.4 4171.4 4214.5 4268.7 4289.3 4293.3 4269.1 4279.7 4308.2 4312.6 4322.6 4047.9 4217.2 4229.2 4253.1 4264.7 4207.7 4220.9 4245.9 4252.9 4261.9 3778.2 3778.7 3788.2 3791.6 3799.7 5205.7 5211.4 5239.3 5300.4 5341.3 4842.5 4856.8 4863.4 4875.7 4881.8 5600.3 5637.7 5669.3 5669.5 5694.5 3870.2 4004.1 4045.9 4161.5 4162.5 5747.5 5760.9 5770.1 5781.5 5832.1 4223.6 4225.2 4258.2 4258.6 4278.0 4219.6 4251.9 4269.6 4322.8 4331.8 4805.6 4811.7 4818.5 4827.2 4849.7 5632.2 5645.6 5685.9 5702.3 5713.4 1408.7 1447.9 1450.9 1471.2 1480.1 932.7 936.8 940.3 954.3 964.2 4877.0 4894.6 4896.3 4927.4 4955.7 5186.2 5191.3 5196.7 5199.8 5206.5 4934.3 4949.9 4953.4 4959.3 5123.2 3047.2 3082.9 3090.2 3090.9 3096.8 6043.0 6065.0 6072.7 6121.9 6151.1 5676.4 5915.9 5936.0 5959.2 5963.8 6083.5 6095.8 6096.8 6098.9 6125.2 5620.5 5807.5 5861.3 5894.4 5911.6 5729.2 5739.3 5765.8 5773.3 5784.1 6094.3 6094.8 6105.3 6119.3 6131.6 3894.3 4045.9 4217.9 4225.7 4249.3 5663.2 5663.9 5668.1 5685.2 5693.4 5634.2 5688.4 5699.4 5701.3 5706.5 5445.3 5666.1 5666.8 5675.9 5691.5 5624.5 5631.9 5645.2 5645.4 5661.6 5581.4 5697.8 5716.5 5789.7 5793.7 5521.5 5555.9 5560.3 5568.6 5570.4 04:11:36 INFO - PROCESS | 6786 | 04:11:36 INFO - PROCESS | 6786 | [#5] yui.html Cycles:180 Average:22827.62 Median:3682.85 stddev:33724.63 (915.7%) stddev-sans-first:33778.73 04:11:36 INFO - PROCESS | 6786 | Values: 814.1 830.7 835.6 840.8 902.3 16152.8 17487.4 17508.2 17529.3 17635.8 15573.9 16056.8 16219.4 16270.9 16297.5 738.4 859.4 860.0 861.2 865.1 748.0 760.7 760.8 843.2 889.0 923.5 935.6 936.7 937.0 937.1 924.3 925.3 926.2 926.7 927.4 549.9 642.6 681.6 689.0 690.7 15428.6 15457.1 15460.3 15462.1 15477.6 14818.2 14987.6 15122.7 15385.8 15589.8 13797.4 13876.7 13909.7 14169.8 14239.4 4648.3 4752.1 4845.1 4860.3 4864.7 89871.6 89883.0 90023.0 90209.0 90280.0 11500.4 11688.5 12198.9 12283.2 12820.1 21714.1 21747.0 21771.1 21781.7 21849.2 8925.2 8970.2 9207.2 9265.5 9352.9 4913.1 5002.6 5061.5 5104.7 5132.8 3614.2 3751.5 3753.4 3754.1 3765.9 634.7 637.4 684.8 726.6 730.4 769.6 787.9 796.5 820.1 838.2 10225.6 10346.5 10639.9 10830.7 10951.8 657.0 757.8 785.2 788.1 792.5 5024.9 5073.2 5109.3 5243.5 5308.0 130610.3 133398.0 133576.3 136394.0 139763.0 90108.5 108231.0 114402.0 117323.0 118160.0 97831.0 97887.0 101611.5 101698.3 103050.0 9760.4 9822.4 10088.5 10129.3 10142.8 48232.5 56452.4 56505.2 56847.7 58768.1 49411.8 51490.2 63348.7 77081.0 77866.0 14453.4 14469.4 14689.6 14756.6 14816.2 15794.8 16134.4 16311.6 16673.7 16975.2 14756.2 15075.6 15680.0 16278.8 16376.8 12264.3 12979.9 13485.9 13929.7 14131.2 14198.3 14919.0 14928.3 15243.2 15426.1 11897.8 12321.3 12494.8 12530.1 12670.2 12577.3 12638.0 12917.4 12973.5 13207.8 04:11:36 INFO - PROCESS | 6786 | -------- Summary: end -------- 04:11:36 INFO - PROCESS | 6786 | 04:11:37 INFO - TEST-INFO | 6786: exit 0 04:11:37 INFO - TEST-OK | dromaeo_css | took 1113337ms 04:11:37 INFO - TEST-START | kraken 04:11:37 INFO - operating with platform_type : linux_ 04:11:37 INFO - Initialising browser for kraken test... 04:11:37 INFO - TEST-INFO | started process 6989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFQt0W/profile http://localhost:52045/getInfo.html) 04:11:41 INFO - PROCESS | 6989 | __metrics Screen width/height:1600/1200 04:11:41 INFO - PROCESS | 6989 | colorDepth:24 04:11:41 INFO - PROCESS | 6989 | Browser inner width/height: 1024/697 04:11:41 INFO - PROCESS | 6989 | __metrics 04:11:42 INFO - PROCESS | 6989 | 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" 04:11:42 INFO - PROCESS | 6989 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:11:43 INFO - TEST-INFO | 6989: exit 0 04:11:43 INFO - Browser initialized. 04:11:43 INFO - Running cycle 1/1 for kraken test... 04:11:43 INFO - TEST-INFO | started process 7074 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFFQt0W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:11:54 INFO - PROCESS | 7074 | RSS: Main: 146071552 04:11:54 INFO - PROCESS | 7074 | 04:11:57 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52045/tests/kraken/driver.html?testName=audio-beat-detection) 04:11:58 INFO - PROCESS | 7074 | RSS: Main: 200450048 04:11:58 INFO - PROCESS | 7074 | 04:12:00 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52045/tests/kraken/driver.html?testName=audio-dft) 04:12:01 INFO - PROCESS | 7074 | RSS: Main: 184471552 04:12:01 INFO - PROCESS | 7074 | 04:12:04 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52045/tests/kraken/driver.html?testName=audio-fft) 04:12:04 INFO - PROCESS | 7074 | RSS: Main: 276221952 04:12:04 INFO - PROCESS | 7074 | 04:12:06 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52045/tests/kraken/driver.html?testName=audio-oscillator) 04:12:07 INFO - PROCESS | 7074 | RSS: Main: 182341632 04:12:07 INFO - PROCESS | 7074 | 04:12:09 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52045/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:10 INFO - PROCESS | 7074 | RSS: Main: 180584448 04:12:10 INFO - PROCESS | 7074 | 04:12:13 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52045/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:13 INFO - PROCESS | 7074 | RSS: Main: 215834624 04:12:13 INFO - PROCESS | 7074 | 04:12:17 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52045/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:17 INFO - PROCESS | 7074 | RSS: Main: 215449600 04:12:17 INFO - PROCESS | 7074 | 04:12:20 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52045/tests/kraken/driver.html?testName=json-parse-financial) 04:12:21 INFO - PROCESS | 7074 | RSS: Main: 215289856 04:12:21 INFO - PROCESS | 7074 | 04:12:22 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52045/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:23 INFO - PROCESS | 7074 | RSS: Main: 177414144 04:12:23 INFO - PROCESS | 7074 | 04:12:24 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:25 INFO - PROCESS | 7074 | RSS: Main: 184078336 04:12:25 INFO - PROCESS | 7074 | 04:12:27 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:28 INFO - PROCESS | 7074 | RSS: Main: 186105856 04:12:28 INFO - PROCESS | 7074 | 04:12:30 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:12:31 INFO - PROCESS | 7074 | RSS: Main: 180989952 04:12:31 INFO - PROCESS | 7074 | 04:12:33 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:12:34 INFO - PROCESS | 7074 | RSS: Main: 185196544 04:12:34 INFO - PROCESS | 7074 | 04:12:35 INFO - PROCESS | 7074 | Cycle 1(1): loaded http://localhost:52045/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52045/tests/kraken/driver.html?testName=ai-astar) 04:12:36 INFO - PROCESS | 7074 | RSS: Main: 235311104 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | __start_tp_report 04:12:36 INFO - PROCESS | 7074 | _x_x_mozilla_page_load 04:12:36 INFO - PROCESS | 7074 | _x_x_mozilla_page_load_details 04:12:36 INFO - PROCESS | 7074 | |i|pagename|runs| 04:12:36 INFO - PROCESS | 7074 | |0;ai-astar;110;111;121;110;111;109;111;112;111;110 04:12:36 INFO - PROCESS | 7074 | |1;audio-beat-detection;145;116;149;168;116;145;145;116;174;119 04:12:36 INFO - PROCESS | 7074 | |2;audio-dft;160;161;160;160;161;160;161;161;160;160 04:12:36 INFO - PROCESS | 7074 | |3;audio-fft;75;75;75;176;75;75;74;76;161;76 04:12:36 INFO - PROCESS | 7074 | |4;audio-oscillator;85;84;149;83;84;85;137;84;84;137 04:12:36 INFO - PROCESS | 7074 | |5;imaging-gaussian-blur;117;116;117;116;116;117;117;117;116;117 04:12:36 INFO - PROCESS | 7074 | |6;imaging-darkroom;145;146;145;146;146;146;145;146;145;146 04:12:36 INFO - PROCESS | 7074 | |7;imaging-desaturate;94;94;95;94;95;95;95;95;95;94 04:12:36 INFO - PROCESS | 7074 | |8;json-parse-financial;50;52;52;50;50;52;50;53;50;50 04:12:36 INFO - PROCESS | 7074 | |9;json-stringify-tinderbox;59;61;59;59;60;59;59;59;59;59 04:12:36 INFO - PROCESS | 7074 | |10;stanford-crypto-aes;73;73;72;72;77;72;73;74;72;73 04:12:36 INFO - PROCESS | 7074 | |11;stanford-crypto-ccm;119;117;121;119;127;123;115;115;158;118 04:12:36 INFO - PROCESS | 7074 | |12;stanford-crypto-pbkdf2;150;150;150;154;153;151;152;155;149;150 04:12:36 INFO - PROCESS | 7074 | |13;stanford-crypto-sha256-iterative;61;62;62;62;65;60;60;61;62;61 04:12:36 INFO - PROCESS | 7074 | __end_tp_report 04:12:36 INFO - PROCESS | 7074 | __start_cc_report 04:12:36 INFO - PROCESS | 7074 | _x_x_mozilla_cycle_collect,1723 04:12:36 INFO - PROCESS | 7074 | __end_cc_report 04:12:36 INFO - PROCESS | 7074 | __startTimestamp1474283556244__endTimestamp 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | ------- Summary: start ------- 04:12:36 INFO - PROCESS | 7074 | Number of tests: 14 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.41 (3.1%) stddev-sans-first:3.56 04:12:36 INFO - PROCESS | 7074 | Values: 110.0 111.0 121.0 110.0 111.0 109.0 111.0 112.0 111.0 110.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:145.00 stddev:21.76 (15.0%) stddev-sans-first:22.98 04:12:36 INFO - PROCESS | 7074 | Values: 145.0 116.0 149.0 168.0 116.0 145.0 145.0 116.0 174.0 119.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 04:12:36 INFO - PROCESS | 7074 | Values: 160.0 161.0 160.0 160.0 161.0 160.0 161.0 161.0 160.0 160.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#3] audio-fft Cycles:10 Average:93.80 Median:75.00 stddev:39.53 (52.7%) stddev-sans-first:41.34 04:12:36 INFO - PROCESS | 7074 | Values: 75.0 75.0 75.0 176.0 75.0 75.0 74.0 76.0 161.0 76.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:84.00 stddev:27.66 (32.9%) stddev-sans-first:28.71 04:12:36 INFO - PROCESS | 7074 | Values: 85.0 84.0 149.0 83.0 84.0 85.0 137.0 84.0 84.0 137.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:12:36 INFO - PROCESS | 7074 | Values: 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:12:36 INFO - PROCESS | 7074 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:12:36 INFO - PROCESS | 7074 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.20 (2.3%) stddev-sans-first:1.22 04:12:36 INFO - PROCESS | 7074 | Values: 50.0 52.0 52.0 50.0 50.0 52.0 50.0 53.0 50.0 50.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 04:12:36 INFO - PROCESS | 7074 | Values: 59.0 61.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:73.00 stddev:1.52 (2.1%) stddev-sans-first:1.62 04:12:36 INFO - PROCESS | 7074 | Values: 73.0 73.0 72.0 72.0 77.0 72.0 73.0 74.0 72.0 73.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#11] stanford-crypto-ccm Cycles:10 Average:123.20 Median:120.00 stddev:12.76 (10.6%) stddev-sans-first:13.44 04:12:36 INFO - PROCESS | 7074 | Values: 119.0 117.0 121.0 119.0 127.0 123.0 115.0 115.0 158.0 118.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:151.50 stddev:2.01 (1.3%) stddev-sans-first:2.07 04:12:36 INFO - PROCESS | 7074 | Values: 150.0 150.0 150.0 154.0 153.0 151.0 152.0 155.0 149.0 150.0 04:12:36 INFO - PROCESS | 7074 | 04:12:36 INFO - PROCESS | 7074 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:1.43 (2.3%) stddev-sans-first:1.50 04:12:36 INFO - PROCESS | 7074 | Values: 61.0 62.0 62.0 62.0 65.0 60.0 60.0 61.0 62.0 61.0 04:12:36 INFO - PROCESS | 7074 | -------- Summary: end -------- 04:12:36 INFO - PROCESS | 7074 | 04:12:37 INFO - TEST-INFO | 7074: exit 0 04:12:37 INFO - TEST-OK | kraken | took 60010ms 04:12:37 INFO - SUITE-END | took 1173s 04:12:37 INFO - Completed test suite (00:19:33) 04:12:37 INFO - javascript benchmark 04:12:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.615806103267, 3201.727853132483, 3223.6615155408695, 3243.270264190559, 3257.1694217974414, 5465.562754979597, 5555.833325000246, 5595.21666966625, 5603.579731520143, 5616.241807356011, 5503.504684578387, 5559.499645031946, 5579.804706835262, 5580.609357344987, 5596.244506991555, 4844.563989240967, 4908.9263661045115, 5003.649744517876, 5024.346834911456, 5087.592992560605, 4908.092002979442, 4949.208126699728, 4959.925601115968, 4989.800407983676, 5017.673851199678, 4921.680090794087, 4939.135651261033, 4954.900901981958, 4966.130927087764, 4971.428285747131, 5001.949980500185, 5011.273365362021, 5027.748612569393, 5028.1200789862, 5060.21566657165, 4814.374131362901, 4833.419989601235, 4864.343313652647, 4931.926021109686, 4964.503549645043, 5321.205116964885, 5321.255024296602, 5338.3593968724, 5363.436839131874, 5396.19057141428, 5800.216970708942, 5807.128930660392, 5810.767569297223, 5813.47678708914, 5819.098039803837, 5668.659880407147, 5835.912461313083, 5846.239988801472, 5875.059990401558, 5884.352721200665, 5577.135543990688, 5578.330600327987, 5586.944130558723, 5605.327360716699, 5612.326520817487, 6084.0, 6091.116788065794, 6094.9695251524345, 6096.451319381277, 6098.176746139215, 5545.390007099216, 5711.057675483582, 5765.394633563499, 5769.88460230793, 5798.985177593904, 5903.822885313448, 5913.940860591339, 5914.58597898143, 5916.053431450947, 5943.197668314809, 5869.794557190563, 5883.058710606282, 5893.5579831512805, 5894.705264736746, 5897.144913987534, 5655.349634791969, 5706.857328566736, 5801.303843538718, 5809.099589563624, 5888.793892213837, 4570.017446249072, 4573.085382923428, 4573.588377046081, 4584.381108550369, 4594.035252596926, 4074.5721699221153, 4093.8362465501045, 4098.959010409917, 4102.610252026053, 4114.033202197481, 5338.119210330329, 5473.261109750133, 5594.832155035356, 5602.971985140048, 5603.15952607104, 5837.708114594253, 5845.09401042839, 5852.180694702745, 5876.030454974967, 5883.588148829626, 5501.174823776466, 5508.697021663847, 5509.531689806411, 5510.586705997066, 5514.145307477347, 4974.204127339608, 5062.772175252049, 5068.391792984792, 5082.33929589151, 5102.821401250939, 6075.908861367083, 6140.600860944024, 6169.56813023084, 6169.81490555284, 6170.7223174957235, 5890.646561206341, 5926.67403292823, 5937.732802023833, 5939.049752039653, 5965.9105113422465, 5953.136795164591, 5989.760409583568, 6143.96928015357, 6155.938440615537, 6175.44421002112, 5701.686407247641, 5723.771049157987, 5747.597668163188, 5794.652320860593, 5796.21751063601, 5822.76708931655, 5831.125331200354, 5836.620619659879, 5843.912341314969, 5848.970755146367, 5518.310211223597, 5617.073182924772, 5730.169125476866, 5733.0, 5735.254416925773, 5603.075492543848, 5655.688937108499, 5663.292088488939, 5675.801346952837, 5695.088785794253, 5591.133374326986, 5601.7759289629585, 5606.523445507014, 5620.578456615688, 5650.943490565206, 5568.359638641608, 5659.575531835211, 5684.3747187808485, 5687.800926967537, 5733.111367738007, 5656.434356564475, 5710.200571919851, 5721.542276617944, 5733.0, 5752.050911599539], "value": 5409.0935427490285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.2629849538828, 625.6996641612026, 664.2527156948433, 667.2693400726206, 674.4166296153828, 35158.121046973836, 47191.76404117979, 48472.0, 48540.0, 48647.75676121623, 49768.0, 50021.24968125481, 50047.24929126066, 50395.24407133896, 50422.49577504224, 9313.487758173282, 9448.905510944905, 9468.526573671323, 9475.384100033476, 9478.241740660766, 8491.787705307386, 11458.656240312763, 11725.237859539146, 11747.060235181165, 11765.529378824836, 11428.371439570821, 11527.942360288187, 11531.481083351227, 11532.192746507748, 11550.36472993985, 11296.548138074468, 11366.431678416046, 11367.488463019185, 11379.601713939972, 11382.317060976367, 11277.943610281978, 11345.886541134565, 11350.659480215607, 11356.318620882774, 11363.0, 48656.513434865556, 48873.51126488725, 49004.754976225246, 49029.50970490285, 49070.26394604084, 4044.1102957349335, 4097.160082183175, 4257.531671516131, 4331.393604895317, 4628.490866004737, 36010.0, 36359.63640363589, 36640.0, 36706.44940325897, 38335.0, 4476.8880777980485, 4501.549845015505, 4517.864464066083, 4525.2533332000185, 4546.659000574937, 417230.0, 417973.0, 418366.0, 418379.0, 419224.90387548174, 38582.61417385846, 69930.0, 70410.64794676096, 70447.29552704407, 70554.64722676354, 39987.40018899719, 41929.161416771494, 42071.0, 42100.0, 42123.368149477785, 18073.45779626613, 18203.27186912535, 18211.63576728458, 18213.362532311305, 18213.544661383305, 12296.262224266573, 12928.547500837614, 15310.387584496719, 15361.692766144615, 15382.923085384502, 8807.559622018873, 8859.158379953895, 8986.910130898737, 9242.0, 9256.444613323087, 3552.786832790041, 3565.4651802229355, 3593.5687717473547, 3615.240799432096, 3626.9818650907105, 1084.8969347911936, 1146.3809542846936, 1161.8780028096928, 1184.3308530680138, 1199.5201919232202, 36367.0, 36383.81808090943, 37117.81441092831, 37644.2471150581, 40081.39877901834, 1160.8374827524153, 1184.579474286633, 1199.940002999864, 1204.7289359893953, 1207.8912897839236, 18332.633347333245, 19313.90343048276, 19395.418137455898, 19535.804641953684, 19712.70430943537, 627142.0, 632242.0, 633205.0, 635011.0, 635878.0, 69591.0, 70059.0, 70093.29906700867, 70165.64917175483, 70177.64911175513, 1375.4223226245, 1455.1560095144791, 1466.3767898643034, 1500.4673340964018, 1524.1083965879864, 31330.0, 32807.0, 33169.0, 33505.66494335123, 34152.0, 59482.0, 60076.699616503385, 60151.09773353491, 60190.09714854193, 60284.09573856308, 464345.0, 469837.0, 470887.0, 471536.0, 471642.0, 52025.7398713004, 52418.0, 55309.72345138249, 55388.72305638446, 55723.442765571825, 52167.0, 52172.739136305594, 56930.0, 57388.71305643445, 58335.416645834695, 45657.31514027359, 46666.0, 48731.26903096386, 48763.756181218865, 49129.263061053396, 47806.0, 48197.0, 49911.25133122934, 51744.48255517547, 52269.0, 48697.513024869295, 49483.7525812383, 49859.75070124626, 50437.49562504328, 50529.74735126301, 41013.179736404505, 43513.56486435095, 43601.127977440905, 43829.56170438255, 43849.780751097314, 32020.35959280755, 32025.839870800497, 32403.4676104504, 32530.83734581312, 33015.66984330222], "value": 22028.41815005287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.25840016368636, 393.88971088095343, 421.2880232407232, 424.2342571658157, 425.79349015727365, 16294.511164665064, 20219.292324768638, 20564.691529627067, 20872.68690969635, 22241.332760017183, 20979.16083356668, 21458.785412145873, 21474.46313842151, 21696.240631577897, 21760.238391656294, 5542.168674698797, 5873.412658734113, 5901.20333754944, 5907.4978626816555, 5911.379305172959, 4272.743635381871, 5838.708064596774, 5866.6480011199465, 5913.172155898178, 6275.0, 6453.580517266386, 6608.504362172832, 6608.801735947929, 6609.173853268341, 6616.139901812781, 6377.649229292387, 6504.837379065514, 6551.475881929434, 6552.967235163794, 6577.177852768404, 5628.85927851807, 5778.162166485836, 5783.306003279591, 5784.768609255625, 5787.450192231773, 25311.0, 25716.0, 25734.742652573423, 25743.74256257451, 25939.351516212057, 2557.360659835041, 2602.050251658131, 2651.5890037044096, 3006.909792706222, 3178.411993781158, 15379.384824607003, 15654.843451565566, 15765.211739413098, 15765.60585985348, 15882.76175857363, 1987.3441764217773, 2081.396395045435, 2086.0, 2099.4751312171957, 2130.360891732474, 66475.0, 67890.0, 88764.5561772187, 90231.0, 90404.09595904022, 11017.83473247902, 11226.438678066064, 11266.943665281786, 11305.71735706614, 11490.080793536497, 15560.610984725472, 15576.376944922076, 15579.065256084672, 15585.844141558438, 15590.84409155894, 8652.91838520185, 8727.825443491221, 8877.866831997526, 8952.68665596701, 9102.180803727697, 8772.166644168794, 8841.69054083104, 8918.1973622373, 9037.638494460149, 9060.728178154666, 6878.243393226841, 6921.134858142732, 6963.547369420971, 6990.335918087773, 7042.366187043191, 1971.132701611315, 1977.6835706286925, 1988.2047181127375, 1990.9203631854853, 2069.9896500517702, 1031.3141760729181, 1051.558345494894, 1083.8320060390652, 1099.2360309584762, 1138.2316936068287, 21633.459163521038, 22392.32823015312, 22426.775732242793, 22539.549209015728, 22620.208292709674, 1083.2038451737967, 1094.9178811589163, 1098.6484325016079, 1109.5728144664363, 1156.4275683536703, 12046.518139274505, 12496.817893670142, 12625.431855566338, 12752.872471275168, 12752.93623531895, 502163.0, 545458.0, 555605.0, 568901.0, 569745.0, 88257.0, 89822.0, 89969.0, 91298.0, 91701.5414922921, 1077.3428208792473, 1100.7193165742685, 1105.4528008635777, 1109.4840898982013, 1134.9489272982735, 19247.0, 19389.709154362412, 19771.308004219783, 20092.39722808318, 20136.697949531062, 29155.416891662473, 41253.0, 41738.37392439055, 45967.0, 46763.29855052109, 62851.68574157283, 63741.0, 63939.36060639334, 64198.0, 64672.35327646663, 8500.872486912578, 8825.293976481738, 8835.911640883765, 8902.821943560963, 8981.191692747683, 11699.590514331958, 14100.717985640025, 14217.21805300677, 14398.85601144017, 15113.471028514388, 12319.199252048587, 12564.560240391542, 12665.493380264686, 12735.681607959876, 12758.0, 11764.764704705687, 11865.881341186478, 11867.643970680894, 11987.22083064598, 12835.679108022374, 6600.636964966973, 10898.618548350769, 11149.554017839195, 12717.491300347883, 13018.2839943804, 7474.439417043852, 9159.450432974043, 9222.538873056454, 9533.380330278509, 9584.185344245818, 10647.840282395926, 11392.544298227978, 11919.761604767682, 11999.340036298088, 12182.695432614255], "value": 10014.606712824525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.9064060836051, 1549.8527639874214, 1551.9844801551985, 1751.4920673004829, 1873.9906300468504, 8959.641614335427, 9244.39911405759, 9299.674511392102, 9715.611375544988, 9727.805443891119, 9559.044095590438, 9685.757856053602, 9686.564104615292, 9690.079442452956, 9698.175655069328, 2549.3499157714755, 2576.8196226264167, 2578.0203522661454, 2580.213035024317, 2580.9096681616147, 2530.4306531030556, 2603.7656610905015, 2604.93487662809, 2605.6612640356725, 2606.7262937391492, 2527.519771243467, 2546.745325467457, 2609.7781688556497, 2612.4122072533623, 2613.856237906924, 2488.22864911877, 2492.5762620354494, 2507.1976967370447, 2536.936576585582, 2538.860362680052, 2485.9005639774387, 2517.6349429332827, 2518.143831097436, 2543.9618405723927, 2546.5925451927787, 9573.090556397201, 9675.806483870354, 9676.225901927828, 9677.12905838471, 9706.90293097074, 5696.914546281809, 5876.941230587725, 5883.676397798119, 5987.042073268256, 5998.460138587505, 3621.728370372233, 3633.8601532567122, 3694.8891533253777, 3700.13046933973, 3714.7585406948733, 1132.0377678972889, 1143.5025763792776, 1146.7419830538145, 1152.6657269391867, 1163.1334655681487, 13517.32413379327, 13530.0, 13562.254076025814, 13612.523561675393, 13647.931760341135, 4835.3714017178245, 4882.487338829443, 4914.5822605078965, 4996.275540046746, 5076.340075790124, 10637.680869573926, 10639.14886809069, 10640.095591874622, 10641.361518308926, 10662.573497060186, 5714.714264286769, 5740.167675687086, 5744.827655170351, 5745.712714364265, 5747.5114615257335, 4731.101090792738, 4822.0, 5344.144936810169, 5353.81261655848, 5381.78472861089, 4156.459660244149, 4182.5207499556755, 4262.062346283875, 4278.807453664593, 4357.7385356878685, 1244.1353259484572, 1264.4562837979572, 1284.563248495516, 1292.0309767674244, 1294.2687381629348, 1014.1886490807325, 1024.2011231239644, 1025.415513157493, 1028.840529717895, 1035.4874337202982, 6078.1186727924205, 6079.483243924316, 6219.657918814418, 6229.657368844757, 6354.3010268870385, 476.80927628948007, 483.10143133771857, 487.98048078077187, 492.7413108118195, 510.19644060604696, 4169.8957526062095, 4304.031592891637, 4312.719673221231, 4327.896386421442, 4368.410264614236, 11789.469473873696, 12138.939305303593, 12218.083643726755, 12235.0, 12241.081918856122, 11229.438528073564, 11715.062794976382, 12139.817902731465, 12155.513779448724, 12166.391680415943, 8866.246369058574, 9065.456072635663, 9093.590788414669, 9106.908930910606, 9113.088691130833, 6071.119687645355, 6074.240719910012, 6174.845628859314, 6268.968655156695, 6291.119243306032, 11255.268407553483, 11286.435678216221, 11299.548018079186, 11399.715007124889, 11442.37066961325, 10314.0, 10602.045815876609, 10960.725981850517, 11509.366984815915, 11669.241499302518, 7853.371730261451, 8094.797630059295, 8443.704470343508, 8588.527630980107, 8600.526971016403, 9635.903640963501, 9640.276979226726, 9653.662121825702, 9693.41839489633, 9703.466309353053, 8055.476394034369, 8159.46963447374, 8176.7955801105445, 8334.749957501284, 8446.83106337882, 7925.326347260548, 8178.427510074475, 8192.631331589973, 8336.666533338841, 8460.450070745628, 7401.48189626721, 7419.591922444318, 7494.6252687366505, 7504.587247701429, 7507.474476786572, 5201.661891977009, 5348.171033489815, 5380.569554435714, 5405.24326594285, 5415.160650099241, 5406.702631355313, 5450.127979523258, 5493.0, 5494.313210848644, 5537.058700021086], "value": 4901.009571186508, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.83593129194935, 360.24169123994, 361.6401680328073, 363.2063940290467, 367.89698884312384, 5422.8069824638615, 5488.862778430536, 5494.148406996922, 5495.6702597844105, 5556.91664625031, 5169.56058735008, 5296.848375216749, 5309.601779866506, 5332.440093790145, 5492.368377636573, 4171.353440216772, 4214.536400995896, 4268.743875367472, 4289.2922667759785, 4293.29160688486, 4269.124829409979, 4279.673301276614, 4308.224519586473, 4312.568743125678, 4322.589354011379, 4047.9392809107885, 4217.240896638604, 4229.154169166164, 4253.085586598881, 4264.658827293809, 4207.747535147901, 4220.87337379876, 4245.8513952011535, 4252.8511502097435, 4261.850835220768, 3778.1688028633657, 3778.678812300958, 3788.1666033472593, 3791.620837916214, 3799.715021373408, 5205.6616319939085, 5211.374635043781, 5239.318888544503, 5300.416954135042, 5341.252224688546, 4842.539958703917, 4856.8300109495995, 4863.440704318978, 4875.731834749087, 4881.780319885614, 5600.27196464457, 5637.661740295595, 5669.26299581056, 5669.489745922887, 5694.5159661428825, 3870.1679138984973, 4004.0790618157694, 4045.8988525287104, 4161.500619925629, 4162.500499939996, 5747.499837570628, 5760.855978600526, 5770.134479828017, 5781.4507621775865, 5832.125181222808, 4223.57144641826, 4225.19721252961, 4258.1696569169335, 4258.6380157686945, 4277.973286411301, 4219.599138081878, 4251.893702657397, 4269.551697071763, 4322.805473753689, 4331.761753103609, 4805.639577031737, 4811.735354555463, 4818.494058123917, 4827.179379505493, 4849.733264670477, 5632.155176723442, 5645.556210711463, 5685.943140568541, 5702.344230413472, 5713.4000929902595, 1408.704172123845, 1447.949321773733, 1450.9274536273144, 1471.2423102102425, 1480.0897448069575, 932.6501819261953, 936.840737074699, 940.304174910575, 954.2938225712923, 964.1708131007329, 4877.0, 4894.632902532324, 4896.2655601660035, 4927.359443272351, 4955.702657840541, 5186.2479940409185, 5191.32512773337, 5196.68819870809, 5199.750156152405, 5206.474859693046, 4934.284528743312, 4949.851504454872, 4953.430355509163, 4959.280904268933, 5123.231515272739, 3047.17726213921, 3082.922926926845, 3090.1656552731088, 3090.922726931845, 3096.8451577421038, 6042.969785151134, 6065.0, 6072.692376896751, 6121.908171377433, 6151.138840562325, 5676.4323567642905, 5915.852103697356, 5936.0, 5959.195508606369, 5963.7912673056235, 6083.543734219951, 6095.8476038098515, 6096.817095487142, 6098.939010609926, 6125.1731016313115, 5620.494155526022, 5807.490052586273, 5861.267341582302, 5894.440028197486, 5911.615744976562, 5729.197912292199, 5739.335592678243, 5765.798197063083, 5773.336066352424, 5784.1034639630925, 6094.268687757498, 6094.8171554853425, 6105.267367915701, 6119.326874043941, 6131.601445906002, 3894.3379625464304, 4045.8988525287104, 4217.894552636209, 4225.704200705921, 4249.3413520904305, 5663.235463212413, 5663.858403539944, 5668.121441176624, 5685.204071430086, 5693.402192769865, 5634.154876768517, 5688.3742788294085, 5699.401562835843, 5701.287339082614, 5706.457886500776, 5445.346558413015, 5666.121751128581, 5666.773329066956, 5675.858103547444, 5691.4877661010705, 5624.465675760796, 5631.915521267102, 5645.181448689835, 5645.403114567345, 5661.575381846295, 5581.441855814386, 5697.800576979786, 5716.456936591184, 5789.697318103393, 5793.710314484343, 5521.5306698931045, 5555.944440555542, 5560.277163968658, 5568.582356323211, 5570.359408667893], "value": 4312.809106787561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.1166833985126, 830.6760363458252, 835.5989125219894, 840.8444437779009, 902.2646543067395, 16152.757708634379, 17487.38794142207, 17508.21213045413, 17529.298828046896, 17635.823641763578, 15573.922130389361, 16056.83943160568, 16219.351225950977, 16270.918645406786, 16297.511074667778, 738.4424759306715, 859.3941271403645, 860.0238729042519, 861.1646702698373, 865.1002956924791, 748.0425055928393, 760.7413479416997, 760.8288135169598, 843.1989609870617, 888.9866652000226, 923.5197697197453, 935.5836652689555, 936.69089200564, 936.9906300937006, 937.1144268666113, 924.3113880159257, 925.3476299209109, 926.1803304075908, 926.666400095965, 927.3693888156051, 549.9175123731472, 642.5726891617069, 681.608075356667, 689.018149137477, 690.6753825701912, 15428.614284642974, 15457.149856757875, 15460.304286307031, 15462.072275663384, 15477.613059673486, 14818.184999825005, 14987.550373488813, 15122.697546049127, 15385.846141538554, 15589.844101558952, 13797.379117939718, 13876.653083672989, 13909.721805563933, 14169.78745318821, 14239.359228834728, 4648.3259927310355, 4752.144613969484, 4845.055214233226, 4860.3195552622665, 4864.708117512961, 89871.55064224637, 89883.0, 90023.0, 90209.0, 90280.0, 11500.424978751029, 11688.532458701557, 12198.878011219951, 12283.201591896499, 12820.102592818414, 21714.13143474275, 21747.0, 21771.12915483363, 21781.673274900888, 21849.235276765237, 8925.241354484942, 8970.237529809909, 9207.217386522087, 9265.490398028038, 9352.859707104399, 4913.091078150554, 5002.57478114357, 5061.544460998528, 5104.668196567285, 5132.79468821243, 3614.2229420674157, 3751.4747935289083, 3753.3619284721663, 3754.0614846288427, 3765.8870233893026, 634.6953462338012, 637.4072112934922, 684.8287928017995, 726.5676922231264, 730.3682314797662, 769.5536588778496, 787.9448438609357, 796.4783067091065, 820.1060843680417, 838.1869586501206, 10225.642102526375, 10346.53440595175, 10639.946800265949, 10830.729231769268, 10951.83572246417, 656.9507286953498, 757.806759276392, 785.1755656560589, 788.0936922539125, 792.4730054513799, 5024.899502009939, 5073.188289873602, 5109.335786347825, 5243.501867322674, 5307.973460132752, 130610.34694826465, 133398.0, 133576.33211834074, 136394.0, 139763.0, 90108.54945725361, 108231.0, 114402.0, 117323.0, 118160.0, 97831.0, 97887.0, 101611.49194253981, 101698.25301234853, 103050.0, 9760.414375137514, 9822.414818591305, 10088.54601542947, 10129.341592796445, 10142.755423660372, 48232.51767482421, 56452.43547564636, 56505.15242271287, 56847.71576142093, 58768.11847822372, 49411.75294123506, 51490.22764658458, 63348.68325658342, 77081.0, 77866.0, 14453.421863125357, 14469.421223150955, 14689.632759181106, 14756.63108422298, 14816.185109818633, 15794.842051579797, 16134.354625814836, 16311.59221019484, 16673.749893751847, 16975.151242438074, 14756.188409637143, 15075.623109422353, 15680.049210987165, 16278.837211628206, 16376.75434868454, 12264.264144151379, 12979.935100324437, 13485.86514134846, 13929.721405571629, 14131.15213087218, 14198.290085495477, 14919.0, 14928.253587320807, 15243.16162611067, 15426.074435533905, 11897.762044758883, 12321.281309924772, 12494.750104997667, 12530.060245481443, 12670.176438531465, 12577.308248046445, 12638.0, 12917.357917010162, 12973.545925892926, 13207.801882971571], "value": 8205.507575965656, "unit": "score"}], "name": "dromaeo_css", "value": 7691.126527729543, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 121.0, 110.0, 111.0, 109.0, 111.0, 112.0, 111.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 149.0, 168.0, 116.0, 145.0, 145.0, 116.0, 174.0, 119.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 176.0, 75.0, 75.0, 74.0, 76.0, 161.0, 76.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 149.0, 83.0, 84.0, 85.0, 137.0, 84.0, 84.0, 137.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 52.0, 50.0, 50.0, 52.0, 50.0, 53.0, 50.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 72.0, 77.0, 72.0, 73.0, 74.0, 72.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 121.0, 119.0, 127.0, 123.0, 115.0, 115.0, 158.0, 118.0], "value": 123.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 154.0, 153.0, 151.0, 152.0, 155.0, 149.0, 150.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 65.0, 60.0, 60.0, 61.0, 62.0, 61.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1482.6000000000001, "alertThreshold": 2.0}]} 04:12:37 INFO - Return code: 0 04:12:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:12:37 INFO - # TBPL SUCCESS # 04:12:37 INFO - Running post-action listener: _resource_record_post_action 04:12:37 INFO - [mozharness: 2016-09-19 11:12:37.549193Z] Finished run-tests step (success) 04:12:37 INFO - Running post-run listener: _resource_record_post_run 04:12:37 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 328691712; Read time: 0; Write time: 317636 04:12:37 INFO - TinderboxPrint: CPU usage
12.9% 04:12:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:12:37 INFO - TinderboxPrint: I/O write bytes / time
328,691,712 / 317,636 04:12:37 INFO - TinderboxPrint: CPU idle
8,276.1 (87.1%) 04:12:37 INFO - TinderboxPrint: CPU user
1,183.7 (12.5%) 04:12:37 INFO - TinderboxPrint: Swap in / out
0 / 0 04:12:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78663680; Read time: 0; Write time: 95836 04:12:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248983552; Read time: 0; Write time: 221748 04:12:38 INFO - Running post-run listener: _upload_blobber_files 04:12:38 INFO - Blob upload gear active. 04:12:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:12:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:12:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:12:38 INFO - (blobuploader) - INFO - Open directory for files ... 04:12:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:12:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:12:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:49 INFO - (blobuploader) - INFO - Done attempting. 04:12:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:12:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:12:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:50 INFO - (blobuploader) - INFO - Done attempting. 04:12:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:12:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:12:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:51 INFO - (blobuploader) - INFO - Done attempting. 04:12:51 INFO - (blobuploader) - INFO - Iteration through files over. 04:12:51 INFO - Return code: 0 04:12:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:12:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:12:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f927cb601559f91917ee3a014fb69e14f9aa9e77cf3538b4504b1cbdd7a2a12104481b51cb31e48112ca0f927fcfd13b102e05f41691c2f8d18508e13237c395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/538e6acf7adc335a1ec694352ce4e274167a658df706ea4efd4b5afec37bd882eb7da968897879b1e5d684a2b5dd82d0a138fda5567a1db6e1167de3f25fa23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7580fa91c5d218271fdbf7a9d29d46c3d4c05fd940f3defd89486c7a92ad649243129e9e25701368030746ca0186fb9008e6ee70f179b1cc9884577c6f149363"} 04:12:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:12:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:12:51 INFO - Contents: 04:12:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f927cb601559f91917ee3a014fb69e14f9aa9e77cf3538b4504b1cbdd7a2a12104481b51cb31e48112ca0f927fcfd13b102e05f41691c2f8d18508e13237c395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/538e6acf7adc335a1ec694352ce4e274167a658df706ea4efd4b5afec37bd882eb7da968897879b1e5d684a2b5dd82d0a138fda5567a1db6e1167de3f25fa23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7580fa91c5d218271fdbf7a9d29d46c3d4c05fd940f3defd89486c7a92ad649243129e9e25701368030746ca0186fb9008e6ee70f179b1cc9884577c6f149363"} 04:12:51 INFO - Running post-run listener: copy_logs_to_upload_dir 04:12:51 INFO - Copying logs to upload dir... 04:12:51 INFO - mkdir: /builds/slave/test/build/upload/logs 04:12:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1685.621799 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-19 04:12:50.496778) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 04:12:50.500110) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 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/f927cb601559f91917ee3a014fb69e14f9aa9e77cf3538b4504b1cbdd7a2a12104481b51cb31e48112ca0f927fcfd13b102e05f41691c2f8d18508e13237c395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/538e6acf7adc335a1ec694352ce4e274167a658df706ea4efd4b5afec37bd882eb7da968897879b1e5d684a2b5dd82d0a138fda5567a1db6e1167de3f25fa23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7580fa91c5d218271fdbf7a9d29d46c3d4c05fd940f3defd89486c7a92ad649243129e9e25701368030746ca0186fb9008e6ee70f179b1cc9884577c6f149363"} build_url:https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011401 build_url: 'https://queue.taskcluster.net/v1/task/A-8g-0PiSiacTuTvBSY2IQ/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/f927cb601559f91917ee3a014fb69e14f9aa9e77cf3538b4504b1cbdd7a2a12104481b51cb31e48112ca0f927fcfd13b102e05f41691c2f8d18508e13237c395", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/538e6acf7adc335a1ec694352ce4e274167a658df706ea4efd4b5afec37bd882eb7da968897879b1e5d684a2b5dd82d0a138fda5567a1db6e1167de3f25fa23a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7580fa91c5d218271fdbf7a9d29d46c3d4c05fd940f3defd89486c7a92ad649243129e9e25701368030746ca0186fb9008e6ee70f179b1cc9884577c6f149363"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 04:12:50.525277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:12:50.525605) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474281883.259843-2087376940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 04:12:50.611488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 04:12:50.611825) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 04:12:50.612112) ========= ========= Total master_lag: 0.16 =========