builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1471652450.95 results: success (0) buildid: 20160819142916 builduid: ede9c5ce0fa0414cb9a31393d28069f0 revision: e60871a5840385e6f0b920da333b18e7004f96f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:50.953706) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:50.954174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:50.954484) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:50.982463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:50.982741) ========= 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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 17:20:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-08-19 17:20:51 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.370991 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:51.368393) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:51.368682) ========= 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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019969 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:51.420788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:51.421115) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e60871a5840385e6f0b920da333b18e7004f96f4 --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 e60871a5840385e6f0b920da333b18e7004f96f4 --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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 17:20:51,556 truncating revision to first 12 chars 2016-08-19 17:20:51,556 Setting DEBUG logging. 2016-08-19 17:20:51,556 attempt 1/10 2016-08-19 17:20:51,556 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e60871a58403?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 17:20:51,899 unpacking tar archive at: mozilla-aurora-e60871a58403/testing/mozharness/ program finished with exit code 0 elapsedTime=0.702481 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:52.140448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:52.140728) ========= script_repo_revision: e60871a5840385e6f0b920da333b18e7004f96f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:52.141120) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:52.141386) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 17:20:52.157474) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-19 17:20:52.157738) ========= /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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:20:52 INFO - MultiFileLogger online at 20160819 17:20:52 in /builds/slave/test 17:20:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:20:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:52 INFO - {'append_to_log': False, 17:20:52 INFO - 'base_work_dir': '/builds/slave/test', 17:20:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:20:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:52 INFO - 'branch': 'Mozilla-Aurora', 17:20:52 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:52 INFO - 'config_files': ('talos/linux_config.py',), 17:20:52 INFO - 'default_actions': ('clobber', 17:20:52 INFO - 'read-buildbot-config', 17:20:52 INFO - 'download-and-extract', 17:20:52 INFO - 'populate-webroot', 17:20:52 INFO - 'create-virtualenv', 17:20:52 INFO - 'install', 17:20:52 INFO - 'run-tests'), 17:20:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:52 INFO - 'download_minidump_stackwalk': True, 17:20:52 INFO - 'download_symbols': 'ondemand', 17:20:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:52 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:52 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:52 INFO - 'installer_path': 'installer.exe', 17:20:52 INFO - 'log_level': 'info', 17:20:52 INFO - 'log_name': 'talos', 17:20:52 INFO - 'log_to_console': True, 17:20:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:20:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:20:52 INFO - 'opt_config_files': (), 17:20:52 INFO - 'pip_index': False, 17:20:52 INFO - 'sps_profile': False, 17:20:52 INFO - 'sps_profile_interval': 0, 17:20:52 INFO - 'suite': 'dromaeojs', 17:20:52 INFO - 'system_bits': '32', 17:20:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:52 INFO - 'title': 'talos-linux64-ix-031', 17:20:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:52 INFO - 'use_talos_json': True, 17:20:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:52 INFO - 'work_dir': 'build'} 17:20:52 INFO - [mozharness: 2016-08-20 00:20:52.386488Z] Running clobber step. 17:20:52 INFO - Running pre-action listener: _resource_record_pre_action 17:20:52 INFO - Running main action method: clobber 17:20:52 INFO - rmtree: /builds/slave/test/build 17:20:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:53 INFO - Running post-action listener: _resource_record_post_action 17:20:53 INFO - [mozharness: 2016-08-20 00:20:53.364229Z] Finished clobber step (success) 17:20:53 INFO - [mozharness: 2016-08-20 00:20:53.364331Z] Running read-buildbot-config step. 17:20:53 INFO - Running pre-action listener: _resource_record_pre_action 17:20:53 INFO - Running main action method: read_buildbot_config 17:20:53 INFO - Using buildbot properties: 17:20:53 INFO - { 17:20:53 INFO - "project": "", 17:20:53 INFO - "product": "firefox", 17:20:53 INFO - "script_repo_revision": "production", 17:20:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:20:53 INFO - "repository": "", 17:20:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:20:53 INFO - "buildid": "20160819142916", 17:20:53 INFO - "pgo_build": "True", 17:20:53 INFO - "basedir": "/builds/slave/test", 17:20:53 INFO - "buildnumber": 475, 17:20:53 INFO - "slavename": "talos-linux64-ix-031", 17:20:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:20:53 INFO - "platform": "ubuntu64_hw", 17:20:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:20:53 INFO - "branch": "mozilla-aurora", 17:20:53 INFO - "repo_path": "releases/mozilla-aurora", 17:20:53 INFO - "revision": "e60871a5840385e6f0b920da333b18e7004f96f4", 17:20:53 INFO - "stage_platform": "linux64-pgo", 17:20:53 INFO - "builduid": "ede9c5ce0fa0414cb9a31393d28069f0", 17:20:53 INFO - "slavebuilddir": "test" 17:20:53 INFO - } 17:20:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 17:20:53 INFO - Running post-action listener: _resource_record_post_action 17:20:53 INFO - [mozharness: 2016-08-20 00:20:53.366782Z] Finished read-buildbot-config step (success) 17:20:53 INFO - [mozharness: 2016-08-20 00:20:53.366870Z] Running download-and-extract step. 17:20:53 INFO - Running pre-action listener: _resource_record_pre_action 17:20:53 INFO - Running main action method: download_and_extract 17:20:53 INFO - mkdir: /builds/slave/test/build/tests 17:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:53 INFO - https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:20:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:20:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:20:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:20:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:20:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:20:53 INFO - retry: Failed, sleeping 30 seconds before retrying 17:21:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:21:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:21:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:21:23 INFO - retry: Failed, sleeping 60 seconds before retrying 17:22:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:22:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:22:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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! 17:22:23 INFO - Caught exception: HTTP Error 404: Not Found 17:22:23 INFO - Caught exception: HTTP Error 404: Not Found 17:22:23 INFO - Caught exception: HTTP Error 404: Not Found 17:22:23 INFO - trying https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:22:23 INFO - Downloading https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:24 INFO - Downloaded 1361 bytes. 17:22:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:22:24 INFO - Using the following test package requirements: 17:22:24 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:22:24 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:22:24 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:22:24 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'jsshell-linux-x86_64.zip'], 17:22:24 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:22:24 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:22:24 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:22:24 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:22:24 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:22:24 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:22:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:24 INFO - https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:22:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:22:24 INFO - retry: Failed, sleeping 30 seconds before retrying 17:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:22:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:22:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:22:54 INFO - retry: Failed, sleeping 60 seconds before retrying 17:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:23:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:23:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:23:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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! 17:23:54 INFO - Caught exception: HTTP Error 404: Not Found 17:23:54 INFO - Caught exception: HTTP Error 404: Not Found 17:23:54 INFO - Caught exception: HTTP Error 404: Not Found 17:23:54 INFO - trying https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:23:54 INFO - Downloading https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:23:54 INFO - Downloaded 21767056 bytes. 17:23:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:23:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:23:56 INFO - Return code: 0 17:23:56 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 17:23:56 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')]} 17:23:56 INFO - https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:23:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:23:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:23:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:23:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:23:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:23:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:23:56 INFO - retry: Failed, sleeping 30 seconds before retrying 17:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:24:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:24:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:24:26 INFO - retry: Failed, sleeping 60 seconds before retrying 17:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:25:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:25:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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! 17:25:26 INFO - Caught exception: HTTP Error 404: Not Found 17:25:26 INFO - Caught exception: HTTP Error 404: Not Found 17:25:26 INFO - Caught exception: HTTP Error 404: Not Found 17:25:26 INFO - trying https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:25:26 INFO - Downloading https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:27 INFO - Downloaded 21767056 bytes. 17:25:27 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'] 17:25:27 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 17:25:28 INFO - Return code: 0 17:25:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:28 INFO - https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:25:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:25:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:25:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:25:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:25:28 INFO - retry: Failed, sleeping 30 seconds before retrying 17:25:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:25:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:25:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:25:58 INFO - retry: Failed, sleeping 60 seconds before retrying 17:26:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:26:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:26:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:26:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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! 17:26:58 INFO - Caught exception: HTTP Error 404: Not Found 17:26:58 INFO - Caught exception: HTTP Error 404: Not Found 17:26:58 INFO - Caught exception: HTTP Error 404: Not Found 17:26:58 INFO - trying https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:26:58 INFO - Downloading https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:26:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:26:59 INFO - Downloaded 14413723 bytes. 17:26:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:26:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:26:59 INFO - Return code: 0 17:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:26:59 INFO - https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:26:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:26:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:26:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:26:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:26:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:26:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:26:59 INFO - retry: Failed, sleeping 30 seconds before retrying 17:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:27:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:27:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:27:29 INFO - retry: Failed, sleeping 60 seconds before retrying 17:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:28:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:28:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:28:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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! 17:28:29 INFO - Caught exception: HTTP Error 404: Not Found 17:28:29 INFO - Caught exception: HTTP Error 404: Not Found 17:28:29 INFO - Caught exception: HTTP Error 404: Not Found 17:28:29 INFO - trying https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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 17:28:33 INFO - Downloaded 63743625 bytes. 17:28:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:28:33 INFO - mkdir: /builds/slave/test/properties 17:28:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:28:33 INFO - Writing to file /builds/slave/test/properties/build_url 17:28:33 INFO - Contents: 17:28:33 INFO - build_url:https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:28:33 INFO - Running post-action listener: _resource_record_post_action 17:28:33 INFO - Running post-action listener: set_extra_try_arguments 17:28:33 INFO - [mozharness: 2016-08-20 00:28:33.821975Z] Finished download-and-extract step (success) 17:28:33 INFO - [mozharness: 2016-08-20 00:28:33.822210Z] Running populate-webroot step. 17:28:33 INFO - Running pre-action listener: _resource_record_pre_action 17:28:33 INFO - Running main action method: populate_webroot 17:28:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:28:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:28:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:28:33 INFO - '--tppagecycles', 17:28:33 INFO - '7'], 17:28:33 INFO - 'tests': ['tsvgm']}}, 17:28:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:28:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:28:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:28:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:28:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:28:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:28:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['damp', 'tps']}, 17:28:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'tests': ['damp', 'tps']}, 17:28:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['dromaeo_dom']}, 17:28:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:28:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['basic_compositor_video']}, 17:28:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:28:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'other-e10s': {'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['a11yr', 17:28:33 INFO - 'ts_paint', 17:28:33 INFO - 'tpaint', 17:28:33 INFO - 'sessionrestore', 17:28:33 INFO - 'sessionrestore_no_auto_restore', 17:28:33 INFO - 'tabpaint']}, 17:28:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['tsvgx', 17:28:33 INFO - 'tsvgr_opacity', 17:28:33 INFO - 'tart', 17:28:33 INFO - 'tscrollx', 17:28:33 INFO - 'cart']}, 17:28:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:28:33 INFO - 'tsvgr_opacity', 17:28:33 INFO - 'tart', 17:28:33 INFO - 'tscrollx', 17:28:33 INFO - 'cart']}, 17:28:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'talos_options': ['--disable-e10s'], 17:28:33 INFO - 'tests': ['tp5o']}, 17:28:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'tests': ['tp5o']}, 17:28:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'talos_options': ['--disable-e10s', 17:28:33 INFO - '--xperf_path', 17:28:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:28:33 INFO - 'tests': ['tp5n']}, 17:28:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:28:33 INFO - 'talos_options': ['--xperf_path', 17:28:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:28:33 INFO - 'tests': ['tp5n']}}, 17:28:33 INFO - 'talos.zip': {'path': '', 17:28:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:28:33 INFO - Running post-action listener: _resource_record_post_action 17:28:33 INFO - [mozharness: 2016-08-20 00:28:33.842429Z] Finished populate-webroot step (success) 17:28:33 INFO - [mozharness: 2016-08-20 00:28:33.842518Z] Running create-virtualenv step. 17:28:33 INFO - Running pre-action listener: _resource_record_pre_action 17:28:33 INFO - Running main action method: create_virtualenv 17:28:33 INFO - Creating virtualenv /builds/slave/test/build/venv 17:28:33 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 17:28:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:28:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:28:33 INFO - Using real prefix '/usr' 17:28:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:28:34 INFO - Installing distribute.............................................................................................................................................................................................done. 17:28:36 INFO - Installing pip.................done. 17:28:36 INFO - Return code: 0 17:28:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:28:36 INFO - Using env: {'DISPLAY': ':0', 17:28:36 INFO - 'HOME': '/home/cltbld', 17:28:36 INFO - 'LANG': 'en_US.UTF-8', 17:28:36 INFO - 'LANGUAGE': 'en_US:en', 17:28:36 INFO - 'LOGNAME': 'cltbld', 17:28:36 INFO - 'MAIL': '/var/mail/cltbld', 17:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:36 INFO - 'MOZ_NO_REMOTE': '1', 17:28:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:36 INFO - 'NO_EM_RESTART': '1', 17:28:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:36 INFO - 'PWD': '/builds/slave/test', 17:28:36 INFO - 'SHELL': '/bin/bash', 17:28:36 INFO - 'SHLVL': '1', 17:28:36 INFO - 'TERM': 'linux', 17:28:36 INFO - 'TMOUT': '86400', 17:28:36 INFO - 'USER': 'cltbld', 17:28:36 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:36 INFO - '_': '/tools/buildbot/bin/python'} 17:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:36 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 17:28:36 INFO - Cleaning up... 17:28:36 INFO - Return code: 0 17:28:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:28:36 INFO - Using env: {'DISPLAY': ':0', 17:28:36 INFO - 'HOME': '/home/cltbld', 17:28:36 INFO - 'LANG': 'en_US.UTF-8', 17:28:36 INFO - 'LANGUAGE': 'en_US:en', 17:28:36 INFO - 'LOGNAME': 'cltbld', 17:28:36 INFO - 'MAIL': '/var/mail/cltbld', 17:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:36 INFO - 'MOZ_NO_REMOTE': '1', 17:28:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:36 INFO - 'NO_EM_RESTART': '1', 17:28:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:36 INFO - 'PWD': '/builds/slave/test', 17:28:36 INFO - 'SHELL': '/bin/bash', 17:28:36 INFO - 'SHLVL': '1', 17:28:36 INFO - 'TERM': 'linux', 17:28:36 INFO - 'TMOUT': '86400', 17:28:36 INFO - 'USER': 'cltbld', 17:28:36 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:36 INFO - '_': '/tools/buildbot/bin/python'} 17:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:36 INFO - Downloading/unpacking psutil>=3.1.1 17:28:36 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 17:28:36 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 17:28:36 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 17:28:36 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 17:28:36 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 17:28:36 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 17:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:28:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:28:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:28:38 INFO - Installing collected packages: psutil 17:28:38 INFO - Running setup.py install for psutil 17:28:38 INFO - building 'psutil._psutil_linux' extension 17:28:38 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 17:28:39 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 17:28:39 INFO - building 'psutil._psutil_posix' extension 17:28:39 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 17:28:39 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 17:28:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:28:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:28:39 INFO - Successfully installed psutil 17:28:39 INFO - Cleaning up... 17:28:39 INFO - Return code: 0 17:28:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:28:39 INFO - Using env: {'DISPLAY': ':0', 17:28:39 INFO - 'HOME': '/home/cltbld', 17:28:39 INFO - 'LANG': 'en_US.UTF-8', 17:28:39 INFO - 'LANGUAGE': 'en_US:en', 17:28:39 INFO - 'LOGNAME': 'cltbld', 17:28:39 INFO - 'MAIL': '/var/mail/cltbld', 17:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:39 INFO - 'MOZ_NO_REMOTE': '1', 17:28:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:39 INFO - 'NO_EM_RESTART': '1', 17:28:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:39 INFO - 'PWD': '/builds/slave/test', 17:28:39 INFO - 'SHELL': '/bin/bash', 17:28:39 INFO - 'SHLVL': '1', 17:28:39 INFO - 'TERM': 'linux', 17:28:39 INFO - 'TMOUT': '86400', 17:28:39 INFO - 'USER': 'cltbld', 17:28:39 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:39 INFO - '_': '/tools/buildbot/bin/python'} 17:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:28:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:28:41 INFO - Installing collected packages: mozsystemmonitor 17:28:41 INFO - Running setup.py install for mozsystemmonitor 17:28:41 INFO - Successfully installed mozsystemmonitor 17:28:41 INFO - Cleaning up... 17:28:41 INFO - Return code: 0 17:28:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:28:41 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')]} 17:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:41 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')]} 17:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:41 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:41 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 17:28:41 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 17:28:41 INFO - Using env: {'DISPLAY': ':0', 17:28:41 INFO - 'HOME': '/home/cltbld', 17:28:41 INFO - 'LANG': 'en_US.UTF-8', 17:28:41 INFO - 'LANGUAGE': 'en_US:en', 17:28:41 INFO - 'LOGNAME': 'cltbld', 17:28:41 INFO - 'MAIL': '/var/mail/cltbld', 17:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:41 INFO - 'MOZ_NO_REMOTE': '1', 17:28:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:41 INFO - 'NO_EM_RESTART': '1', 17:28:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:41 INFO - 'PWD': '/builds/slave/test', 17:28:41 INFO - 'SHELL': '/bin/bash', 17:28:41 INFO - 'SHLVL': '1', 17:28:41 INFO - 'TERM': 'linux', 17:28:41 INFO - 'TMOUT': '86400', 17:28:41 INFO - 'USER': 'cltbld', 17:28:41 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:41 INFO - '_': '/tools/buildbot/bin/python'} 17:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:41 INFO - Downloading/unpacking jsonschema==2.5.1 17:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:28:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28:43 INFO - Downloading functools32-3.2.3-2.tar.gz 17:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:28:44 INFO - warning: no files found matching '*.txt' 17:28:44 INFO - no previously-included directories found matching 'build' 17:28:44 INFO - no previously-included directories found matching 'dist' 17:28:44 INFO - no previously-included directories found matching '.git*' 17:28:44 INFO - Installing collected packages: jsonschema, functools32 17:28:44 INFO - Running setup.py install for functools32 17:28:44 INFO - warning: no files found matching '*.txt' 17:28:44 INFO - no previously-included directories found matching 'build' 17:28:44 INFO - no previously-included directories found matching 'dist' 17:28:44 INFO - no previously-included directories found matching '.git*' 17:28:44 INFO - Successfully installed jsonschema functools32 17:28:44 INFO - Cleaning up... 17:28:44 INFO - Return code: 0 17:28:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:28:44 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')]} 17:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:44 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')]} 17:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:44 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:44 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 17:28:44 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 17:28:44 INFO - Using env: {'DISPLAY': ':0', 17:28:44 INFO - 'HOME': '/home/cltbld', 17:28:44 INFO - 'LANG': 'en_US.UTF-8', 17:28:44 INFO - 'LANGUAGE': 'en_US:en', 17:28:44 INFO - 'LOGNAME': 'cltbld', 17:28:44 INFO - 'MAIL': '/var/mail/cltbld', 17:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:44 INFO - 'MOZ_NO_REMOTE': '1', 17:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:44 INFO - 'NO_EM_RESTART': '1', 17:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:44 INFO - 'PWD': '/builds/slave/test', 17:28:44 INFO - 'SHELL': '/bin/bash', 17:28:44 INFO - 'SHLVL': '1', 17:28:44 INFO - 'TERM': 'linux', 17:28:44 INFO - 'TMOUT': '86400', 17:28:44 INFO - 'USER': 'cltbld', 17:28:44 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:44 INFO - '_': '/tools/buildbot/bin/python'} 17:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:28:44 INFO - Cleaning up... 17:28:44 INFO - Return code: 0 17:28:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:28:44 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')]} 17:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:44 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')]} 17:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:44 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:44 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 17:28:44 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 17:28:44 INFO - Using env: {'DISPLAY': ':0', 17:28:44 INFO - 'HOME': '/home/cltbld', 17:28:44 INFO - 'LANG': 'en_US.UTF-8', 17:28:44 INFO - 'LANGUAGE': 'en_US:en', 17:28:44 INFO - 'LOGNAME': 'cltbld', 17:28:44 INFO - 'MAIL': '/var/mail/cltbld', 17:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:44 INFO - 'MOZ_NO_REMOTE': '1', 17:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:44 INFO - 'NO_EM_RESTART': '1', 17:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:44 INFO - 'PWD': '/builds/slave/test', 17:28:44 INFO - 'SHELL': '/bin/bash', 17:28:44 INFO - 'SHLVL': '1', 17:28:44 INFO - 'TERM': 'linux', 17:28:44 INFO - 'TMOUT': '86400', 17:28:44 INFO - 'USER': 'cltbld', 17:28:44 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:44 INFO - '_': '/tools/buildbot/bin/python'} 17:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:44 INFO - Downloading/unpacking blobuploader==1.2.4 17:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:46 INFO - Downloading blobuploader-1.2.4.tar.gz 17:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:28:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:28:46 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 17:28:46 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 17:28:46 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 17:28:46 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 17:28:46 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 17:28:46 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 17:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:28:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:28:47 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 17:28:47 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 17:28:47 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 17:28:47 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 17:28:47 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 17:28:47 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 17:28:47 INFO - Downloading docopt-0.6.1.tar.gz 17:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:28:47 INFO - Installing collected packages: blobuploader, requests, docopt 17:28:47 INFO - Running setup.py install for blobuploader 17:28:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:28:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:28:47 INFO - Running setup.py install for requests 17:28:47 INFO - Running setup.py install for docopt 17:28:47 INFO - Successfully installed blobuploader requests docopt 17:28:47 INFO - Cleaning up... 17:28:47 INFO - Return code: 0 17:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:47 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')]} 17:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:47 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')]} 17:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:47 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:47 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 17:28:47 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 17:28:47 INFO - Using env: {'DISPLAY': ':0', 17:28:47 INFO - 'HOME': '/home/cltbld', 17:28:47 INFO - 'LANG': 'en_US.UTF-8', 17:28:47 INFO - 'LANGUAGE': 'en_US:en', 17:28:47 INFO - 'LOGNAME': 'cltbld', 17:28:47 INFO - 'MAIL': '/var/mail/cltbld', 17:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:47 INFO - 'MOZ_NO_REMOTE': '1', 17:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:47 INFO - 'NO_EM_RESTART': '1', 17:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:47 INFO - 'PWD': '/builds/slave/test', 17:28:47 INFO - 'SHELL': '/bin/bash', 17:28:47 INFO - 'SHLVL': '1', 17:28:47 INFO - 'TERM': 'linux', 17:28:47 INFO - 'TMOUT': '86400', 17:28:47 INFO - 'USER': 'cltbld', 17:28:47 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:47 INFO - '_': '/tools/buildbot/bin/python'} 17:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:28:48 INFO - Running setup.py (path:/tmp/pip-H_2a1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:28:48 INFO - Running setup.py (path:/tmp/pip-55hQPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:28:48 INFO - Running setup.py (path:/tmp/pip-ord5pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:28:48 INFO - Running setup.py (path:/tmp/pip-zMbBbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:28:48 INFO - Running setup.py (path:/tmp/pip-GxRDOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:28:48 INFO - Running setup.py (path:/tmp/pip-B1xQnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:28:48 INFO - Running setup.py (path:/tmp/pip-IYZoct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:28:48 INFO - Running setup.py (path:/tmp/pip-MbAfNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:28:48 INFO - Running setup.py (path:/tmp/pip-xd30Y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:28:48 INFO - Running setup.py (path:/tmp/pip-UulK8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:28:48 INFO - Running setup.py (path:/tmp/pip-_4x27a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:28:48 INFO - Running setup.py (path:/tmp/pip-zz6uvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:49 INFO - Running setup.py (path:/tmp/pip-MNu_AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:49 INFO - Running setup.py (path:/tmp/pip-_4I8Du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:49 INFO - Running setup.py (path:/tmp/pip-S92Hld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:49 INFO - Running setup.py (path:/tmp/pip-1NSHjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:49 INFO - Running setup.py (path:/tmp/pip-gw4Uha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:28:49 INFO - Running setup.py install for manifestparser 17:28:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:28:49 INFO - Running setup.py install for mozcrash 17:28:49 INFO - Running setup.py install for mozdebug 17:28:49 INFO - Running setup.py install for mozdevice 17:28:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:28:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:28:49 INFO - Running setup.py install for mozfile 17:28:49 INFO - Running setup.py install for mozhttpd 17:28:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozinfo 17:28:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozInstall 17:28:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozleak 17:28:50 INFO - Running setup.py install for mozlog 17:28:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for moznetwork 17:28:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozprocess 17:28:50 INFO - Running setup.py install for mozprofile 17:28:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozrunner 17:28:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:28:50 INFO - Running setup.py install for mozscreenshot 17:28:50 INFO - Running setup.py install for moztest 17:28:51 INFO - Running setup.py install for mozversion 17:28:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:28:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:28:51 INFO - Cleaning up... 17:28:51 INFO - Return code: 0 17:28:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:51 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:51 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 17:28:51 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 17:28:51 INFO - Using env: {'DISPLAY': ':0', 17:28:51 INFO - 'HOME': '/home/cltbld', 17:28:51 INFO - 'LANG': 'en_US.UTF-8', 17:28:51 INFO - 'LANGUAGE': 'en_US:en', 17:28:51 INFO - 'LOGNAME': 'cltbld', 17:28:51 INFO - 'MAIL': '/var/mail/cltbld', 17:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:51 INFO - 'MOZ_NO_REMOTE': '1', 17:28:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:51 INFO - 'NO_EM_RESTART': '1', 17:28:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:51 INFO - 'PWD': '/builds/slave/test', 17:28:51 INFO - 'SHELL': '/bin/bash', 17:28:51 INFO - 'SHLVL': '1', 17:28:51 INFO - 'TERM': 'linux', 17:28:51 INFO - 'TMOUT': '86400', 17:28:51 INFO - 'USER': 'cltbld', 17:28:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:51 INFO - '_': '/tools/buildbot/bin/python'} 17:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:28:51 INFO - Running setup.py (path:/tmp/pip-At99u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:28:51 INFO - Running setup.py (path:/tmp/pip-jjz5HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:28:51 INFO - Running setup.py (path:/tmp/pip-DkaSLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:28:51 INFO - Running setup.py (path:/tmp/pip-6ZOJB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:28:51 INFO - Running setup.py (path:/tmp/pip-1lsNP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:28:51 INFO - Running setup.py (path:/tmp/pip-p6EnxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:28:51 INFO - Running setup.py (path:/tmp/pip-3tjnTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:28:51 INFO - Running setup.py (path:/tmp/pip-L_jeGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:28:51 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)) 17:28:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:28:51 INFO - Running setup.py (path:/tmp/pip-aXSmMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:28:52 INFO - Running setup.py (path:/tmp/pip-igu7Mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:28:52 INFO - Running setup.py (path:/tmp/pip-jtmpdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:28:52 INFO - Running setup.py (path:/tmp/pip-CVkZnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:28:52 INFO - Running setup.py (path:/tmp/pip-7SI9YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:28:52 INFO - Running setup.py (path:/tmp/pip-j6Bjep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:52 INFO - Running setup.py (path:/tmp/pip-pg91fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:28:52 INFO - Running setup.py (path:/tmp/pip-WsOPxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:28:52 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)) 17:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:28:52 INFO - Running setup.py (path:/tmp/pip-8O4tdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:28:52 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)) 17:28:52 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)) 17:28:52 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)) 17:28:52 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)) 17:28:52 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)) 17:28:52 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)) 17:28:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:28:52 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 17:28:52 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 17:28:52 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 17:28:52 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 17:28:52 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 17:28:52 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 17:28:54 INFO - Downloading blessings-1.6.tar.gz 17:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:28:54 INFO - Installing collected packages: blessings 17:28:54 INFO - Running setup.py install for blessings 17:28:54 INFO - Successfully installed blessings 17:28:54 INFO - Cleaning up... 17:28:54 INFO - Return code: 0 17:28:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:28:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:55 INFO - Reading from file tmpfile_stdout 17:28:55 INFO - Current package versions: 17:28:55 INFO - argparse == 1.2.1 17:28:55 INFO - blessings == 1.6 17:28:55 INFO - blobuploader == 1.2.4 17:28:55 INFO - docopt == 0.6.1 17:28:55 INFO - functools32 == 3.2.3-2 17:28:55 INFO - jsonschema == 2.5.1 17:28:55 INFO - manifestparser == 1.1 17:28:55 INFO - mozInstall == 1.12 17:28:55 INFO - mozcrash == 0.17 17:28:55 INFO - mozdebug == 0.1 17:28:55 INFO - mozdevice == 0.48 17:28:55 INFO - mozfile == 1.2 17:28:55 INFO - mozhttpd == 0.7 17:28:55 INFO - mozinfo == 0.9 17:28:55 INFO - mozleak == 0.1 17:28:55 INFO - mozlog == 3.3 17:28:55 INFO - moznetwork == 0.27 17:28:55 INFO - mozprocess == 0.23 17:28:55 INFO - mozprofile == 0.28 17:28:55 INFO - mozrunner == 6.12 17:28:55 INFO - mozscreenshot == 0.1 17:28:55 INFO - mozsystemmonitor == 0.3 17:28:55 INFO - moztest == 0.7 17:28:55 INFO - mozversion == 1.4 17:28:55 INFO - psutil == 3.1.1 17:28:55 INFO - requests == 1.2.3 17:28:55 INFO - wsgiref == 0.1.2 17:28:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:55 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:55 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 17:28:55 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 17:28:55 INFO - Using env: {'DISPLAY': ':0', 17:28:55 INFO - 'HOME': '/home/cltbld', 17:28:55 INFO - 'LANG': 'en_US.UTF-8', 17:28:55 INFO - 'LANGUAGE': 'en_US:en', 17:28:55 INFO - 'LOGNAME': 'cltbld', 17:28:55 INFO - 'MAIL': '/var/mail/cltbld', 17:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:55 INFO - 'MOZ_NO_REMOTE': '1', 17:28:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:55 INFO - 'NO_EM_RESTART': '1', 17:28:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:55 INFO - 'PWD': '/builds/slave/test', 17:28:55 INFO - 'SHELL': '/bin/bash', 17:28:55 INFO - 'SHLVL': '1', 17:28:55 INFO - 'TERM': 'linux', 17:28:55 INFO - 'TMOUT': '86400', 17:28:55 INFO - 'USER': 'cltbld', 17:28:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:55 INFO - '_': '/tools/buildbot/bin/python'} 17:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 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)) 17:28:55 INFO - Cleaning up... 17:28:55 INFO - Return code: 0 17:28:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:55 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 0x29a9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, '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': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', '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 17:28:55 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 17:28:55 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 17:28:55 INFO - Using env: {'DISPLAY': ':0', 17:28:55 INFO - 'HOME': '/home/cltbld', 17:28:55 INFO - 'LANG': 'en_US.UTF-8', 17:28:55 INFO - 'LANGUAGE': 'en_US:en', 17:28:55 INFO - 'LOGNAME': 'cltbld', 17:28:55 INFO - 'MAIL': '/var/mail/cltbld', 17:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:55 INFO - 'MOZ_NO_REMOTE': '1', 17:28:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:55 INFO - 'NO_EM_RESTART': '1', 17:28:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:55 INFO - 'PWD': '/builds/slave/test', 17:28:55 INFO - 'SHELL': '/bin/bash', 17:28:55 INFO - 'SHLVL': '1', 17:28:55 INFO - 'TERM': 'linux', 17:28:55 INFO - 'TMOUT': '86400', 17:28:55 INFO - 'USER': 'cltbld', 17:28:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:55 INFO - '_': '/tools/buildbot/bin/python'} 17:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:28:55 INFO - Cleaning up... 17:28:55 INFO - Return code: 0 17:28:55 INFO - Running post-action listener: _resource_record_post_action 17:28:55 INFO - Running post-action listener: _start_resource_monitoring 17:28:55 INFO - Starting resource monitoring. 17:28:55 INFO - [mozharness: 2016-08-20 00:28:55.475906Z] Finished create-virtualenv step (success) 17:28:55 INFO - [mozharness: 2016-08-20 00:28:55.476336Z] Running install step. 17:28:55 INFO - Running pre-action listener: _resource_record_pre_action 17:28:55 INFO - Running main action method: install 17:28:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:28:55 INFO - Reading from file tmpfile_stdout 17:28:55 INFO - Detecting whether we're running mozinstall >=1.0... 17:28:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:28:55 INFO - Reading from file tmpfile_stdout 17:28:55 INFO - Output received: 17:28:55 INFO - Usage: mozinstall [options] installer 17:28:55 INFO - Options: 17:28:55 INFO - -h, --help show this help message and exit 17:28:55 INFO - -d DEST, --destination=DEST 17:28:55 INFO - Directory to install application into. [default: 17:28:55 INFO - "/builds/slave/test"] 17:28:55 INFO - --app=APP Application being installed. [default: firefox] 17:28:55 INFO - mkdir: /builds/slave/test/build/application 17:28:55 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'] 17:28:55 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 17:29:12 INFO - Reading from file tmpfile_stdout 17:29:12 INFO - Output received: 17:29:12 INFO - /builds/slave/test/build/application/firefox/firefox 17:29:12 INFO - Running post-action listener: _resource_record_post_action 17:29:12 INFO - [mozharness: 2016-08-20 00:29:12.391997Z] Finished install step (success) 17:29:12 INFO - [mozharness: 2016-08-20 00:29:12.392271Z] Running run-tests step. 17:29:12 INFO - Running pre-action listener: _resource_record_pre_action 17:29:12 INFO - Running main action method: run_tests 17:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:29:12 INFO - Python 2.7.3 17:29:12 INFO - Return code: 0 17:29:12 INFO - grabbing minidump binary from tooltool 17:29:12 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')]} 17:29:12 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 0x2c683b0>, '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 0x2c692b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c69740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:29:12 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 17:29:12 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 17:29:12 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 17:29:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:29:12 INFO - Return code: 0 17:29:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:29:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:29:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:29:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:29:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:29:12 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/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 17:29:12 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/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 17:29:12 INFO - Using env: {'DISPLAY': ':0', 17:29:12 INFO - 'HOME': '/home/cltbld', 17:29:12 INFO - 'LANG': 'en_US.UTF-8', 17:29:12 INFO - 'LANGUAGE': 'en_US:en', 17:29:12 INFO - 'LOGNAME': 'cltbld', 17:29:12 INFO - 'MAIL': '/var/mail/cltbld', 17:29:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:29:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:12 INFO - 'MOZ_NO_REMOTE': '1', 17:29:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:12 INFO - 'NO_EM_RESTART': '1', 17:29:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:12 INFO - 'PWD': '/builds/slave/test', 17:29:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:29:12 INFO - 'SHELL': '/bin/bash', 17:29:12 INFO - 'SHLVL': '1', 17:29:12 INFO - 'TERM': 'linux', 17:29:12 INFO - 'TMOUT': '86400', 17:29:12 INFO - 'USER': 'cltbld', 17:29:12 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741', 17:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:12 INFO - '_': '/tools/buildbot/bin/python'} 17:29:12 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/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 17:29:12 INFO - mozversion application_buildid: 20160819142916 17:29:12 INFO - mozversion application_changeset: e60871a5840385e6f0b920da333b18e7004f96f4 17:29:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:29:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:29:12 INFO - mozversion application_name: Firefox 17:29:12 INFO - mozversion application_remotingname: firefox-dev 17:29:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:29:12 INFO - mozversion application_vendor: Mozilla 17:29:12 INFO - mozversion application_version: 50.0a2 17:29:12 INFO - mozversion platform_buildid: 20160819142916 17:29:12 INFO - mozversion platform_changeset: e60871a5840385e6f0b920da333b18e7004f96f4 17:29:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:29:12 INFO - mozversion platform_version: 50.0a2 17:29:12 INFO - using testdate: 1471652952 17:29:12 INFO - actual date: 1471652952 17:29:12 INFO - starting webserver on 'localhost:50912' 17:29:12 INFO - SUITE-START | Running 2 tests 17:29:12 INFO - TEST-START | dromaeo_css 17:29:12 INFO - operating with platform_type : linux_ 17:29:12 INFO - Initialising browser for dromaeo_css test... 17:29:12 INFO - TEST-INFO | started process 12808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjtkbaD/profile http://localhost:50912/getInfo.html) 17:29:16 INFO - PROCESS | 12808 | __metrics Screen width/height:1600/1200 17:29:16 INFO - PROCESS | 12808 | colorDepth:24 17:29:16 INFO - PROCESS | 12808 | Browser inner width/height: 1024/697 17:29:16 INFO - PROCESS | 12808 | __metrics 17:29:17 INFO - PROCESS | 12808 | 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" 17:29:17 INFO - PROCESS | 12808 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:29:18 INFO - TEST-INFO | 12808: exit 0 17:29:18 INFO - Browser initialized. 17:29:18 INFO - Running cycle 1/1 for dromaeo_css test... 17:29:18 INFO - TEST-INFO | started process 12899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjtkbaD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:30 INFO - PROCESS | 12899 | RSS: Main: 145309696 17:29:30 INFO - PROCESS | 12899 | 17:30:21 INFO - PROCESS | 12899 | 1471653021208 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] 17:32:30 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50912/tests/dromaeo/cssquery-ext.html) 17:32:31 INFO - PROCESS | 12899 | RSS: Main: 273719296 17:32:31 INFO - PROCESS | 12899 | 17:35:33 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-ext.html (next: http://localhost:50912/tests/dromaeo/cssquery-jquery.html) 17:35:34 INFO - PROCESS | 12899 | RSS: Main: 234405888 17:35:34 INFO - PROCESS | 12899 | 17:38:36 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50912/tests/dromaeo/cssquery-mootools.html) 17:38:36 INFO - PROCESS | 12899 | RSS: Main: 201666560 17:38:36 INFO - PROCESS | 12899 | 17:41:38 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50912/tests/dromaeo/cssquery-prototype.html) 17:41:39 INFO - PROCESS | 12899 | RSS: Main: 216727552 17:41:39 INFO - PROCESS | 12899 | 17:44:42 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50912/tests/dromaeo/cssquery-yui.html) 17:44:42 INFO - PROCESS | 12899 | RSS: Main: 175685632 17:44:42 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-yui.html (next: http://localhost:50912/tests/dromaeo/cssquery-dojo.html) 17:47:45 INFO - PROCESS | 12899 | RSS: Main: 266002432 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | __start_tp_report 17:47:45 INFO - PROCESS | 12899 | _x_x_mozilla_page_load 17:47:45 INFO - PROCESS | 12899 | _x_x_mozilla_page_load_details 17:47:45 INFO - PROCESS | 12899 | |i|pagename|runs| 17:47:45 INFO - PROCESS | 12899 | |0;dojo.html;3319.236575587615;3346.548215990842;3346.882859099931;3378.2398960233936;3393.236521782599;5633.21135041094;5736.569757268211;5737.454941780535;5769.451902069307;5771.105478650806;5701.6864072476;5740.081586946079;5750.223719797837;5755.4244575542425;5761.827145185651;5017.949820501784;5190.143626301656;5191.714455704935;5242.685438873661;5273.789048438058;5065.240213967916;5156.0203561323415;5208.192730126836;5232.058229518685;5232.502912223333;5082.288479612857;5132.024915266087;5135.178371460567;5148.227765835118;5211.7133557654315;5200.505951934579;5254.579633629301;5270.578353731711;5273.578113750891;5296.629235953485;4969;4997.400311962551;5026.246063090529;5151.819686310999;5160.200168973777;5553.111502159635;5583.413741557119;5634.126710359901;5637.8590535236535;5684.687342196177;6114.144019837227;6115.7859474918605;6122.755089796403;6126.877462450726;6136.864680034208;5934.406559344075;5987.880242395171;6073.787417440369;6161.260648722137;6181.876362472725;5753.568482363839;5771.480566748971;5772.942270577324;5775.104858746901;5783.450572195635;6271.843203919939;6283.371662833772;6288.496920246461;6290.905636415458;6295.874082518324;5863.589548731633;5870.559708021916;5923.49650279731;5926.762929482772;6059.21230240066;6067.605605635619;6074.2586593570895;6091.939080609226;6104.4200800923845;6105.145279660851;6037;6044.637321760709;6046;6047.9395206047375;6050.969745151246;5924.7333869975955;5943.673097979653;5949.077892926603;5987.850303742354;5988.760449582055;4587.174308624413;4614.676972611952;4619.330197121466;4628.143793398165;4631.096936097429;4128.814203360856;4136.896577585584;4137.213929353413;4143.854965076208;4157.334826427757;5622.746976385991;5716.456936591017;5729.627574207663;5732.942670573324;5734.627249228787;6112.663803490851;6158.568900177034;6177.413145751147;6179.84550386244;6185.938140618537;5830.8542286442325;5839.065749480148;5841.32824725162;5844.912326315109;5865.824025279248;5055.6713813602;5191.844244672666;5310.734463276821;5321.893562128813;5325;6234.781782637586;6400.167978162809;6403.3276505966205;6403.839904002344;6406.967965160145;6112.663803490851;6139.3553676863285;6160.383961603804;6165.537584681257;6168;6124.472479235402;6251.468625166912;6375.458086062737;6377.77677781294;6384.553081284265;5991.910121348271;6053.848653783691;6069.939300606938;6100.816975490742;6109.908351374822;6156.168917196122;6158.876822463615;6165.106059621419;6173.197484327009;6175.104609831639;5779.2775903012125;5888.998870191992;5947.581147553328;6027.909581356371;6032.517398608188;5914.053751399755;6011.128386384037;6015.097735339559;6038.788642397495;6047.334793172836;5911.379305172895;5953.702314884152;5968.4031596841705;5974.342822289632;5978.103284507359;5909;5951.7619295227705;6031.095335699505;6064.696765161636;6072.453479186895;5921;5926.111083337534;5930.940690593039;5934.376890426615;5958.40415958418 17:47:45 INFO - PROCESS | 12899 | |1;ext.html;597.1758972617787;614.1095411653101;627.5356236385074;632.4971647540206;648.649729146261;34350.828245858764;45230.7738461308;46209.30686039704;46306.305405418905;49875.75062124693;46565.30152047722;47050.764746176224;47076;47123.29315060268;47149.764251178785;8451.619677114544;9250.953745231265;9383;9405.482698451582;11019.173561982843;10805.675829725122;11200.943995280013;11213.831792523119;11218.326900385962;11233.887661123366;11279.718007049807;11316.434178291094;11319.830202546967;11328.490217940212;11331.830022549668;11194.328340299608;11259.493322800494;11263.549458021711;11265.211435199539;11274.830877536844;11160.16298777595;11221;11244.606438774603;11246.55013799447;11247.775044499145;46310.53689463096;46493.30260046102;46522;46876.765616172044;46972.5302746975;3759.680427163667;4153.7300075494995;4469.150861336336;4542.227821270359;4573.062522182944;35745.82127089348;35770.8211458941;35894.282114357564;36304.63695363039;37569;4677.719336839767;4711.24620060792;4715.1512727709;4735.928961065587;4791.401074865642;369449;369977;370119;371249;371800.140999296;65704.67147664231;66309.33690663031;66327;66424.66787666127;66454;38421.231575368336;39308;39580;41996.37005444921;67178;18438.170282337327;18499.260029598667;18515.907420462812;18518.81481185171;18520.907395462935;12598.874011259953;14384.35270412834;14568.708625827425;14762.70474590502;14806.481773137888;21241.468963276042;21278.893605531874;21340.573188536142;21414;21572.244971425924;7577.810554736066;7642.04474440695;7693.538387696756;7791.117286603992;7871.921280787234;1047.7433028907967;1114.5820317380983;1123.955041798319;1132.0603898764007;1153.5155234801402;34996.65003349985;38434.80782596125;38881.41677874834;39273.41089883654;39749.80125099414;1174.301290732013;1178.3652535863896;1179.0155220390898;1199.1845545029464;1227.1164761371797;17872.28510859577;18192.818071819376;18267;18302.633947321247;18304.725429118575;627326;629136;629343;629690;636139;64251.35748642547;64662;64721.352786471536;64737.35262647313;64742;1368.0218643669823;1383.6195046362332;1443.4082026369138;1463.2025546077414;1477.2909003678294;30921.226969325948;35549.51153419339;35760.26603967763;36032.80062249311;36098.453051924946;54961;55881.161782574105;56246.71876640591;56284.43715562792;56333.15500267581;501314;502158;504545;505111;505229;29792.404151917275;30362.696373035986;30484.866059375632;30722.846385768822;32278.515822263154;52895.47104529059;52948.47051529436;52967;53069.20396194138;53100.73449632727;43539.346909797016;44141.11717765562;44297.55702443063;45712.31431528596;45750.77124614356;44591.33113003372;45579.31631025471;45973.77013114913;46328;47733.52266477291;44749.53838823055;45287.32069019033;45496.54503455055;45699.31451028304;46176.07647847092;37786.43320350142;39754.60245397509;39902.60097399105;40190.799046004584;40418.79790601028;29855.402891942475;30031.54952675668;30141.698583013887;30320.39359212759;30542.84728576343 17:47:45 INFO - PROCESS | 12899 | |2;jquery.html;386.39336242099915;391.23123063180844;406.5893447617905;408.1530823541152;419.08430080274604;16505.58736031598;19846.30537931173;19853.106610202558;20111.798882011175;20334.288299909505;11700.824487632692;12211.389430528483;12291.201071930318;12351.752964940695;12384.380780960939;5369.838904832852;5577.637453565505;5582.218489411465;5587.804426845061;5594.188842617818;5423.078076726966;5487.039768040597;5843;6095.969520152393;6114.663693496856;6185.8144255672405;6196.783112591038;6202.7518899244205;6204.224471941007;6206.7827626033095;5999;6108.175396321484;6110.236220472441;6150.2927163376335;6156.107364432132;5774.307083150007;5934.6142500737315;5971.313298970587;5991.281046274475;6002.219711437485;14755.409783608642;14897.329620167118;14963;14967.476138335105;23302.184423545263;2409.542186984485;2454.0919859651863;2479.4049428137296;2600.4149066459895;2898.5507246376847;10235.590576376935;10914.563417463292;10924.508397122148;10926.890731092666;11304.208705390625;1970.1134489479791;2043.8569300148995;2091.874487530753;2110.778368271325;2114.0592436365823;68587.31412685859;72672;73582;81089.59455202686;91237;10803.13574914005;10949.890501094887;11452.541898323974;11515.481803318871;11758.294502329716;16293.918530407273;16330.101844398434;16336.591585210228;16344.447277508618;16348.836511634969;8522.062573116953;8631.180037896389;8638.308935285162;8761.518116503654;8892.15524525169;8394.45436046655;8472.279856212168;8520.74437766868;8684.8697269541;8697.869531957025;4636.211843986531;4659.091477161921;4787.16224660683;4803.447603525569;4898.069366820293;1907.7233801098762;2032.8475364347732;2036.6741321388506;2060.8557400982086;2074.4399012266604;948.938319009276;1006.5722068121165;1012.291396022787;1024.6004058417075;1032.318669678009;20880.895595521924;21041.263555775473;21096.05067771954;21320.893395533232;21587.933330363037;1040.7294103532984;1053.752368193474;1074.3392813419846;1074.5594306334365;1102.9669109926715;11208.607698730668;11795.264015256926;11807.763844723057;11847.229930054518;11914.404279785977;556323;561072;563072;582126;586252;87346.56326718452;96219.51890240645;97426;99573.50213249032;99595;1041.4688508860536;1065.4406436620682;1107.9058280046124;1123.7303047268597;1126.1722633864267;18522.444326670222;18556.16497257654;19024;19091.140898659873;19245.843763400782;42901.570984289756;43777;43937.78031109824;44784.10431791411;45729.54270457253;59116;59269.11096333645;60147.39852601593;62527;62627.37372626215;5918.704064796657;6689.966550167413;8657.8701319479;8841.292696584129;9264.258859291376;10036.849447258443;13227.47090116423;13820.723585528434;14032.929835351166;14223.431062757374;11685.941570292094;11908.523659053888;12096.75806483883;12222;12391.070669699628;11635.825462617899;11884.227525210834;11938.58214962472;12191;12340.444679989243;11828;12050.578229761917;12141.332226727614;12493.125481216228;12671.366431678563;8983.910160898307;9140.63437462494;9192.862107068533;9324.11420915012;9409.623615055321;12087;12191;12268.141230113806;12299.262044277371;12380.195287306296 17:47:45 INFO - PROCESS | 12899 | |3;mootools.html;1369.698666293415;1509.320805637463;1549.8295187529372;1716.8626509879211;1878.1454438230605;8220.177982201776;9019.323550733689;9473.289503287264;9480.383775054624;9762.365446245989;9487.241020718344;9542.904570954288;9560.904390956088;9561.569729362178;9567.138957493824;2427.1990243219693;2447.3147518694796;2449.363165576954;2453.889574969122;2459.9016039358403;2426.611742121261;2438;2460.3234110619633;2473.4805690804865;2473.789727873133;2427.368884090132;2429.842060266077;2437;2466.1615050882697;2468.358226861011;2366.325597204788;2382.7378988311357;2384.4992551564105;2388.4028992751814;2409.5783237933465;2384.070212617081;2395.532871090138;2401.267613377919;2402.2192787344006;2403.0147639467737;9128.45229286245;9374.343795934288;9465;9474.289428292906;9488.573014214377;5335.599830012765;5569.916451253234;5573.024720673866;5578.330600327987;5578.44215578443;3457.7233821294235;3563.0379797454543;3568.359414033622;3603.6756691897854;3604.2070744436182;1193.814958681397;1197.114135539703;1202.158126976087;1229.993850030753;1267.6387229639602;13476.797848032287;13554.254516001616;13655.453781848713;13658.04393692442;13693.931530342385;4591.724496530219;4660.883477913079;4810.903781924342;4837.588804951548;4856.80572777092;10606.15150787935;10606.57573697059;10614.787704245871;10621.309614875008;10622.150227981743;5587.413321601254;5588.552915766729;5588.552915766729;5589.161625756169;5598.552115830724;4655.790489427984;4707.105649926503;5123.8462846113935;5178.896422071537;5186.844394668166;4095.9385609215888;4126.587341265849;4164.5210800758305;4171.0615111599645;4236.322188449833;1218.5795900414344;1220.975580488403;1224.536678669;1242.5278394210143;1245.3399698159808;912.3841407050214;931.2084727981297;990.5096976996289;992.9205663546899;1000.7147962830704;5916.402571305809;6099.878002439927;6124.448799607968;6161.784337548254;6294.181756371734;455.9749213793273;457.69105853548723;458.95869371756703;469.5163981099473;473.6045402089294;4090.427340172378;4246.8725938221905;4261.531231564525;4276.508201556799;4329.415528903621;11749.823752643546;12277.324747138815;12279.815802762965;12317.260964342167;12317.75364492723;11226.550937962553;11715.297082174926;11739.23694959842;12136.696582585506;12199.939000304941;8802.86795698065;8967.551622418852;9004.8649270261;9019.954900225588;9035.141661542142;6158.599691020069;6194.287656919512;6295.087212354275;6432.871342573028;6480.351964803671;10977.615783447542;11298.548058077913;11329;11473.196876218919;11473.885261147281;10243.282970192015;10692.786144276915;11547.538098476303;11603.767924641628;11657.358845263592;7991.800204994689;8008.679652813822;8444.155584441507;8512.404131710968;8520.70177543783;9119;9323.627054917728;9330;9357.204637605608;9364.157225849705;7914.287714105758;7940.6426710796995;8085.797855053435;8149.511029338259;8242.711505097293;7656.540607563564;7883.802904927423;7924.603769811371;7946.721864734706;8156.102828688958;7464.440166987606;7496.887546686695;7523.532911082287;7529.096508419025;7532.246775322424;5090.389153301628;5121.9743901281745;5168.53483186515;5193.221016847503;5434.64674796137;5202.271681964604;5221.530062294411;5261.289725886956;5274.525292723673;5435.184722291688 17:47:45 INFO - PROCESS | 12899 | |4;prototype.html;334.91153750311486;340.80403767833496;347.56380742168574;349.87754285999887;350.6160753974397;5411.3506379234495;5431.864203394917;5439.156930675742;5442.877277861632;5467.972660136704;5036.773345199465;5210.55710264627;5211.42674305826;5211.791528338862;5374.602603323135;4179.33130699089;4256.723312984646;4275.508316543606;4279.593438623326;4283.400323954649;4256.553061928499;4257;4265.744055356673;4266.402703621496;4269.380939763748;3995.98002009991;4112.691548133887;4232.15356928614;4236.915261694764;4245.23585754564;4074.8777536673947;4116.5471798102035;4172.979135104335;4180.163967206571;4183.518895327046;3706.962930370689;3707.2029513654425;3710.499082623838;3712.814359282025;3716.4982727331735;5205;5223.2948551945765;5224.63427560071;5228.947710522884;5237.5548078413;4617.145828021827;4746.311784791221;4783.593394561466;4787.856364309041;4797.928031079502;5571.554275657938;5574.581906356999;5582.358028826697;5584.86037849053;5587.312373833251;4068.206699693561;4077.97961010196;4079.8776036718946;4080.3882466425894;4086.1827634473225;5583.162525621149;5727.312722473288;5772.971135144339;5796.391378905197;5801;4135.58644135584;4147.129102888427;4148.875533733993;4154.169166166779;4161.91676166481;4190.329547272422;4191.778357470545;4201.571465701615;4202.621764041251;4203.789810509462;4584.968382113998;4723.7638118093955;4748.406449193851;4770.642201834877;4784.952150478521;5584.776608935678;5607.158926161027;5612.5229355504425;5617.494425501724;5629.437056294338;1446.8770154536983;1459.0807791091545;1462.7659574468034;1463.3195564062762;1471.0952764050032;940.5250348573926;958.5782255807422;967.1778987860403;990.4898977026837;1011.4083261292079;5446.564274858002;5451.754671039813;5467.753951072212;5467.867531662655;5471.945280547144;5702.716888700009;5708.315002199763;5729.742161602738;5749.13762935563;5764.913526297109;5253.697642520788;5405;5422.559848068385;5448.937457195888;5453.318335208099;3034.573750337338;3054.282243672735;3061.464243757378;3064.218624250802;3066.4173806976605;5776.364599894008;5934.020886553671;5959.731812068468;5973.641581505123;5981.252343457068;5794.82615521535;5912.142739302777;5917.289925208916;5918.201042859159;5954.672493012926;6051.788187413419;6063;6078.513718902565;6101.786437474667;6102.237220347457;5579.74891129892;5752.539796816161;5821.563382746226;5832.445917637987;5834.504067154339;5760.539156867524;5782.85542861415;5799.449052340021;5801.3908539602735;5807.419258074159;5859.267591551056;6011.879762404815;6080.939190608215;6094.939050609437;6105.8168254952425;4110.486189226347;4151.875443736692;4156.293430116948;4165.729227600196;4169.957510622345;5806.82579522615;5842.824715258549;5847.532197424281;5848.795292164754;5852.571182959025;5482.616216864782;5645.943540564542;5667.801626943037;5673.716314184192;5693.658380497183;5656.321241451053;5663.150527420919;5664.207011018543;5667.518260947865;5678.403767604342;5400.513953744182;5654;5656.886862262814;5669.829905102853;5670.971645141748;5377.8924421510565;5708.34354049273;5722.656640601577;5724.427557244242;5728.050054221996;5520.1443776214755;5569.944300556943;5581.469760372758;5583.832485025456;5590.664560126405 17:47:45 INFO - PROCESS | 12899 | |5;yui.html;781.6365390093605;805.5287656720818;812.9674813007476;841.1756478650922;852.3820230333009;15223.162726050063;16417.589560261;16812.075335856538;17662.293508259685;17891.463256102306;14898.851011489882;14962.925185374084;14969.92515037426;14974.25128743564;14992.925035374836;750.650947309501;858.6651206029638;874.3267683883406;874.4010352908247;895.230102112183;898.0660113482004;907.2877790934118;907.3059109781022;908.3959167153868;908.7546362482126;945.2957546627772;951.8239125761756;953.1278879824987;954.4464210757778;954.6229239450448;754.5359603843624;892.6607889002138;925.3892430995578;952.4095061062151;952.8618350339196;686.7767975408012;686.9828254293633;687.0552989639247;687.1685260834396;687.3058211206717;16105.516834494983;16117.194140292939;16140.435084772094;16155.19224038805;16156.192190390551;15164.090154590649;15587.688246235011;15845.841541584552;15863.920680396639;15983.200839958072;14144.646383840383;14206.92896535521;14221.644458888508;14314;14355.210463424508;4645.953540464619;4683.8360657376825;4686.484486706459;4748.71507709535;4852.660313778002;88100;88255;88324;88495;88923.55538222332;12088.41803014919;12224.388780561114;12233.143679942319;12241.387930603434;12500.312482813533;24380;24396.756032439447;24400.14599489045;24586.877065614557;24686.259412217663;8837.823243535222;8962.596683149273;9026.232770214605;9146.085391460801;9177.587008584629;4948.950510494921;5130.538251557377;5160.045391602565;5252.501012403815;5281.445448227958;3648.4892115103908;3792.033031576985;3794.5636251831393;3796.6013568574904;3802.125511132455;660.5376236634376;661.5931202310641;662.7382184037278;667.2493444874516;676.4047638078558;752.8155601877465;757.3978686943893;772.8106613879523;775.6542398937838;777.1179711028024;10891.618793342353;10940.343579385104;11061.170412219115;11069;11362.318260904372;666.2171947961126;740.5593671765291;755.320211809365;800.9599520023976;815.9388045896462;5169.974150129226;5204.8959020819375;5374.731263436813;5382.085045542267;5461.644993075437;76117;77891;80776;81253;81852.18147818565;76768.61615691886;77498;84280.57859710662;121334.39332803279;124651;64329;102246.48876755568;105232;105262.4736876326;108124.97187682775;10404.271700981162;10535.947320263656;10585.788284234426;10722.195835312226;10740.570377185139;55985.44014559965;56020.43979560315;56923.14615280691;57589.136162956755;57616.423835762784;78909;83478.16521834947;84150;84940;86692;13389.531366402129;13479.19962588183;13560.972217392784;13658;13872.237026963614;15204.853569021312;15256.771148433005;15308.464203752816;15809.13049782273;15899.602509937344;9878.452439090646;11135.220534562826;13778.586642400744;14664;14911.403543858125;14548.709025819637;14951.626209344853;14968.40126394932;15021.474248401253;15150.621234469227;14266.429342826586;14462.20457874785;14502.564923052325;14627.195504246944;15758.921205394357;12246.326452045223;12905.935470322589;13092.607221783363;13153.736925261632;13261.336933153496;13103.14829536077;13145.408456619283;13343.733125337632;13379.264140472367;13663.590092297247 17:47:45 INFO - PROCESS | 12899 | __end_tp_report 17:47:45 INFO - PROCESS | 12899 | __start_cc_report 17:47:45 INFO - PROCESS | 12899 | _x_x_mozilla_cycle_collect,1079 17:47:45 INFO - PROCESS | 12899 | __end_cc_report 17:47:45 INFO - PROCESS | 12899 | __startTimestamp1471654065510__endTimestamp 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | ------- Summary: start ------- 17:47:45 INFO - PROCESS | 12899 | Number of tests: 6 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#0] dojo.html Cycles:165 Average:5671.03 Median:5921.00 stddev:649.71 (11.0%) stddev-sans-first:624.96 17:47:45 INFO - PROCESS | 12899 | Values: 3319.2 3346.5 3346.9 3378.2 3393.2 5633.2 5736.6 5737.5 5769.5 5771.1 5701.7 5740.1 5750.2 5755.4 5761.8 5017.9 5190.1 5191.7 5242.7 5273.8 5065.2 5156.0 5208.2 5232.1 5232.5 5082.3 5132.0 5135.2 5148.2 5211.7 5200.5 5254.6 5270.6 5273.6 5296.6 4969.0 4997.4 5026.2 5151.8 5160.2 5553.1 5583.4 5634.1 5637.9 5684.7 6114.1 6115.8 6122.8 6126.9 6136.9 5934.4 5987.9 6073.8 6161.3 6181.9 5753.6 5771.5 5772.9 5775.1 5783.5 6271.8 6283.4 6288.5 6290.9 6295.9 5863.6 5870.6 5923.5 5926.8 6059.2 6067.6 6074.3 6091.9 6104.4 6105.1 6037.0 6044.6 6046.0 6047.9 6051.0 5924.7 5943.7 5949.1 5987.9 5988.8 4587.2 4614.7 4619.3 4628.1 4631.1 4128.8 4136.9 4137.2 4143.9 4157.3 5622.7 5716.5 5729.6 5732.9 5734.6 6112.7 6158.6 6177.4 6179.8 6185.9 5830.9 5839.1 5841.3 5844.9 5865.8 5055.7 5191.8 5310.7 5321.9 5325.0 6234.8 6400.2 6403.3 6403.8 6407.0 6112.7 6139.4 6160.4 6165.5 6168.0 6124.5 6251.5 6375.5 6377.8 6384.6 5991.9 6053.8 6069.9 6100.8 6109.9 6156.2 6158.9 6165.1 6173.2 6175.1 5779.3 5889.0 5947.6 6027.9 6032.5 5914.1 6011.1 6015.1 6038.8 6047.3 5911.4 5953.7 5968.4 5974.3 5978.1 5909.0 5951.8 6031.1 6064.7 6072.5 5921.0 5926.1 5930.9 5934.4 5958.4 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#1] ext.html Cycles:180 Average:67265.87 Median:39664.90 stddev:136200.55 (343.4%) stddev-sans-first:136490.65 17:47:45 INFO - PROCESS | 12899 | Values: 597.2 614.1 627.5 632.5 648.6 34350.8 45230.8 46209.3 46306.3 49875.8 46565.3 47050.8 47076.0 47123.3 47149.8 8451.6 9251.0 9383.0 9405.5 11019.2 10805.7 11200.9 11213.8 11218.3 11233.9 11279.7 11316.4 11319.8 11328.5 11331.8 11194.3 11259.5 11263.5 11265.2 11274.8 11160.2 11221.0 11244.6 11246.6 11247.8 46310.5 46493.3 46522.0 46876.8 46972.5 3759.7 4153.7 4469.2 4542.2 4573.1 35745.8 35770.8 35894.3 36304.6 37569.0 4677.7 4711.2 4715.2 4735.9 4791.4 369449.0 369977.0 370119.0 371249.0 371800.1 65704.7 66309.3 66327.0 66424.7 66454.0 38421.2 39308.0 39580.0 41996.4 67178.0 18438.2 18499.3 18515.9 18518.8 18520.9 12598.9 14384.4 14568.7 14762.7 14806.5 21241.5 21278.9 21340.6 21414.0 21572.2 7577.8 7642.0 7693.5 7791.1 7871.9 1047.7 1114.6 1124.0 1132.1 1153.5 34996.7 38434.8 38881.4 39273.4 39749.8 1174.3 1178.4 1179.0 1199.2 1227.1 17872.3 18192.8 18267.0 18302.6 18304.7 627326.0 629136.0 629343.0 629690.0 636139.0 64251.4 64662.0 64721.4 64737.4 64742.0 1368.0 1383.6 1443.4 1463.2 1477.3 30921.2 35549.5 35760.3 36032.8 36098.5 54961.0 55881.2 56246.7 56284.4 56333.2 501314.0 502158.0 504545.0 505111.0 505229.0 29792.4 30362.7 30484.9 30722.8 32278.5 52895.5 52948.5 52967.0 53069.2 53100.7 43539.3 44141.1 44297.6 45712.3 45750.8 44591.3 45579.3 45973.8 46328.0 47733.5 44749.5 45287.3 45496.5 45699.3 46176.1 37786.4 39754.6 39902.6 40190.8 40418.8 29855.4 30031.5 30141.7 30320.4 30542.8 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#2] jquery.html Cycles:180 Average:31307.31 Median:11717.48 stddev:93651.79 (799.2%) stddev-sans-first:93885.73 17:47:45 INFO - PROCESS | 12899 | Values: 386.4 391.2 406.6 408.2 419.1 16505.6 19846.3 19853.1 20111.8 20334.3 11700.8 12211.4 12291.2 12351.8 12384.4 5369.8 5577.6 5582.2 5587.8 5594.2 5423.1 5487.0 5843.0 6096.0 6114.7 6185.8 6196.8 6202.8 6204.2 6206.8 5999.0 6108.2 6110.2 6150.3 6156.1 5774.3 5934.6 5971.3 5991.3 6002.2 14755.4 14897.3 14963.0 14967.5 23302.2 2409.5 2454.1 2479.4 2600.4 2898.6 10235.6 10914.6 10924.5 10926.9 11304.2 1970.1 2043.9 2091.9 2110.8 2114.1 68587.3 72672.0 73582.0 81089.6 91237.0 10803.1 10949.9 11452.5 11515.5 11758.3 16293.9 16330.1 16336.6 16344.4 16348.8 8522.1 8631.2 8638.3 8761.5 8892.2 8394.5 8472.3 8520.7 8684.9 8697.9 4636.2 4659.1 4787.2 4803.4 4898.1 1907.7 2032.8 2036.7 2060.9 2074.4 948.9 1006.6 1012.3 1024.6 1032.3 20880.9 21041.3 21096.1 21320.9 21587.9 1040.7 1053.8 1074.3 1074.6 1103.0 11208.6 11795.3 11807.8 11847.2 11914.4 556323.0 561072.0 563072.0 582126.0 586252.0 87346.6 96219.5 97426.0 99573.5 99595.0 1041.5 1065.4 1107.9 1123.7 1126.2 18522.4 18556.2 19024.0 19091.1 19245.8 42901.6 43777.0 43937.8 44784.1 45729.5 59116.0 59269.1 60147.4 62527.0 62627.4 5918.7 6690.0 8657.9 8841.3 9264.3 10036.8 13227.5 13820.7 14032.9 14223.4 11685.9 11908.5 12096.8 12222.0 12391.1 11635.8 11884.2 11938.6 12191.0 12340.4 11828.0 12050.6 12141.3 12493.1 12671.4 8983.9 9140.6 9192.9 9324.1 9409.6 12087.0 12191.0 12268.1 12299.3 12380.2 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#3] mootools.html Cycles:180 Average:6171.35 Median:4658.34 stddev:3649.56 (78.3%) stddev-sans-first:3641.96 17:47:45 INFO - PROCESS | 12899 | Values: 1369.7 1509.3 1549.8 1716.9 1878.1 8220.2 9019.3 9473.3 9480.4 9762.4 9487.2 9542.9 9560.9 9561.6 9567.1 2427.2 2447.3 2449.4 2453.9 2459.9 2426.6 2438.0 2460.3 2473.5 2473.8 2427.4 2429.8 2437.0 2466.2 2468.4 2366.3 2382.7 2384.5 2388.4 2409.6 2384.1 2395.5 2401.3 2402.2 2403.0 9128.5 9374.3 9465.0 9474.3 9488.6 5335.6 5569.9 5573.0 5578.3 5578.4 3457.7 3563.0 3568.4 3603.7 3604.2 1193.8 1197.1 1202.2 1230.0 1267.6 13476.8 13554.3 13655.5 13658.0 13693.9 4591.7 4660.9 4810.9 4837.6 4856.8 10606.2 10606.6 10614.8 10621.3 10622.2 5587.4 5588.6 5588.6 5589.2 5598.6 4655.8 4707.1 5123.8 5178.9 5186.8 4095.9 4126.6 4164.5 4171.1 4236.3 1218.6 1221.0 1224.5 1242.5 1245.3 912.4 931.2 990.5 992.9 1000.7 5916.4 6099.9 6124.4 6161.8 6294.2 456.0 457.7 459.0 469.5 473.6 4090.4 4246.9 4261.5 4276.5 4329.4 11749.8 12277.3 12279.8 12317.3 12317.8 11226.6 11715.3 11739.2 12136.7 12199.9 8802.9 8967.6 9004.9 9020.0 9035.1 6158.6 6194.3 6295.1 6432.9 6480.4 10977.6 11298.5 11329.0 11473.2 11473.9 10243.3 10692.8 11547.5 11603.8 11657.4 7991.8 8008.7 8444.2 8512.4 8520.7 9119.0 9323.6 9330.0 9357.2 9364.2 7914.3 7940.6 8085.8 8149.5 8242.7 7656.5 7883.8 7924.6 7946.7 8156.1 7464.4 7496.9 7523.5 7529.1 7532.2 5090.4 5122.0 5168.5 5193.2 5434.6 5202.3 5221.5 5261.3 5274.5 5435.2 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#4] prototype.html Cycles:180 Average:4757.40 Median:5447.75 stddev:1394.94 (25.6%) stddev-sans-first:1358.78 17:47:45 INFO - PROCESS | 12899 | Values: 334.9 340.8 347.6 349.9 350.6 5411.4 5431.9 5439.2 5442.9 5468.0 5036.8 5210.6 5211.4 5211.8 5374.6 4179.3 4256.7 4275.5 4279.6 4283.4 4256.6 4257.0 4265.7 4266.4 4269.4 3996.0 4112.7 4232.2 4236.9 4245.2 4074.9 4116.5 4173.0 4180.2 4183.5 3707.0 3707.2 3710.5 3712.8 3716.5 5205.0 5223.3 5224.6 5228.9 5237.6 4617.1 4746.3 4783.6 4787.9 4797.9 5571.6 5574.6 5582.4 5584.9 5587.3 4068.2 4078.0 4079.9 4080.4 4086.2 5583.2 5727.3 5773.0 5796.4 5801.0 4135.6 4147.1 4148.9 4154.2 4161.9 4190.3 4191.8 4201.6 4202.6 4203.8 4585.0 4723.8 4748.4 4770.6 4785.0 5584.8 5607.2 5612.5 5617.5 5629.4 1446.9 1459.1 1462.8 1463.3 1471.1 940.5 958.6 967.2 990.5 1011.4 5446.6 5451.8 5467.8 5467.9 5471.9 5702.7 5708.3 5729.7 5749.1 5764.9 5253.7 5405.0 5422.6 5448.9 5453.3 3034.6 3054.3 3061.5 3064.2 3066.4 5776.4 5934.0 5959.7 5973.6 5981.3 5794.8 5912.1 5917.3 5918.2 5954.7 6051.8 6063.0 6078.5 6101.8 6102.2 5579.7 5752.5 5821.6 5832.4 5834.5 5760.5 5782.9 5799.4 5801.4 5807.4 5859.3 6011.9 6080.9 6094.9 6105.8 4110.5 4151.9 4156.3 4165.7 4170.0 5806.8 5842.8 5847.5 5848.8 5852.6 5482.6 5645.9 5667.8 5673.7 5693.7 5656.3 5663.2 5664.2 5667.5 5678.4 5400.5 5654.0 5656.9 5669.8 5671.0 5377.9 5708.3 5722.7 5724.4 5728.1 5520.1 5569.9 5581.5 5583.8 5590.7 17:47:45 INFO - PROCESS | 12899 | 17:47:45 INFO - PROCESS | 12899 | [#5] yui.html Cycles:180 Average:21439.21 Median:4664.89 stddev:29492.82 (632.2%) stddev-sans-first:29534.77 17:47:45 INFO - PROCESS | 12899 | Values: 781.6 805.5 813.0 841.2 852.4 15223.2 16417.6 16812.1 17662.3 17891.5 14898.9 14962.9 14969.9 14974.3 14992.9 750.7 858.7 874.3 874.4 895.2 898.1 907.3 907.3 908.4 908.8 945.3 951.8 953.1 954.4 954.6 754.5 892.7 925.4 952.4 952.9 686.8 687.0 687.1 687.2 687.3 16105.5 16117.2 16140.4 16155.2 16156.2 15164.1 15587.7 15845.8 15863.9 15983.2 14144.6 14206.9 14221.6 14314.0 14355.2 4646.0 4683.8 4686.5 4748.7 4852.7 88100.0 88255.0 88324.0 88495.0 88923.6 12088.4 12224.4 12233.1 12241.4 12500.3 24380.0 24396.8 24400.1 24586.9 24686.3 8837.8 8962.6 9026.2 9146.1 9177.6 4949.0 5130.5 5160.0 5252.5 5281.4 3648.5 3792.0 3794.6 3796.6 3802.1 660.5 661.6 662.7 667.2 676.4 752.8 757.4 772.8 775.7 777.1 10891.6 10940.3 11061.2 11069.0 11362.3 666.2 740.6 755.3 801.0 815.9 5170.0 5204.9 5374.7 5382.1 5461.6 76117.0 77891.0 80776.0 81253.0 81852.2 76768.6 77498.0 84280.6 121334.4 124651.0 64329.0 102246.5 105232.0 105262.5 108125.0 10404.3 10535.9 10585.8 10722.2 10740.6 55985.4 56020.4 56923.1 57589.1 57616.4 78909.0 83478.2 84150.0 84940.0 86692.0 13389.5 13479.2 13561.0 13658.0 13872.2 15204.9 15256.8 15308.5 15809.1 15899.6 9878.5 11135.2 13778.6 14664.0 14911.4 14548.7 14951.6 14968.4 15021.5 15150.6 14266.4 14462.2 14502.6 14627.2 15758.9 12246.3 12905.9 13092.6 13153.7 13261.3 13103.1 13145.4 13343.7 13379.3 13663.6 17:47:45 INFO - PROCESS | 12899 | -------- Summary: end -------- 17:47:45 INFO - PROCESS | 12899 | 17:47:46 INFO - TEST-INFO | 12899: exit 0 17:47:46 INFO - TEST-OK | dromaeo_css | took 1113632ms 17:47:46 INFO - TEST-START | kraken 17:47:46 INFO - operating with platform_type : linux_ 17:47:46 INFO - Initialising browser for kraken test... 17:47:46 INFO - TEST-INFO | started process 13106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2wMpCO/profile http://localhost:50912/getInfo.html) 17:47:50 INFO - PROCESS | 13106 | __metrics Screen width/height:1600/1200 17:47:50 INFO - PROCESS | 13106 | colorDepth:24 17:47:50 INFO - PROCESS | 13106 | Browser inner width/height: 1024/697 17:47:50 INFO - PROCESS | 13106 | __metrics 17:47:51 INFO - PROCESS | 13106 | 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" 17:47:51 INFO - PROCESS | 13106 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:47:52 INFO - TEST-INFO | 13106: exit 0 17:47:52 INFO - Browser initialized. 17:47:52 INFO - Running cycle 1/1 for kraken test... 17:47:52 INFO - TEST-INFO | started process 13193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2wMpCO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:48:03 INFO - PROCESS | 13193 | RSS: Main: 145276928 17:48:03 INFO - PROCESS | 13193 | 17:48:06 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection) 17:48:07 INFO - PROCESS | 13193 | RSS: Main: 191692800 17:48:07 INFO - PROCESS | 13193 | 17:48:09 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-dft) 17:48:10 INFO - PROCESS | 13193 | RSS: Main: 180441088 17:48:10 INFO - PROCESS | 13193 | 17:48:13 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-fft) 17:48:13 INFO - PROCESS | 13193 | RSS: Main: 269762560 17:48:13 INFO - PROCESS | 13193 | 17:48:15 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator) 17:48:16 INFO - PROCESS | 13193 | RSS: Main: 175562752 17:48:16 INFO - PROCESS | 13193 | 17:48:18 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:48:19 INFO - PROCESS | 13193 | RSS: Main: 177065984 17:48:19 INFO - PROCESS | 13193 | 17:48:22 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom) 17:48:22 INFO - PROCESS | 13193 | RSS: Main: 213127168 17:48:22 INFO - PROCESS | 13193 | 17:48:26 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate) 17:48:26 INFO - PROCESS | 13193 | RSS: Main: 211791872 17:48:26 INFO - PROCESS | 13193 | 17:48:29 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial) 17:48:30 INFO - PROCESS | 13193 | RSS: Main: 211718144 17:48:30 INFO - PROCESS | 13193 | 17:48:31 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:48:32 INFO - PROCESS | 13193 | RSS: Main: 175669248 17:48:32 INFO - PROCESS | 13193 | 17:48:33 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:48:34 INFO - PROCESS | 13193 | RSS: Main: 182194176 17:48:34 INFO - PROCESS | 13193 | 17:48:36 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:48:37 INFO - PROCESS | 13193 | RSS: Main: 186601472 17:48:37 INFO - PROCESS | 13193 | 17:48:39 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:48:40 INFO - PROCESS | 13193 | RSS: Main: 181411840 17:48:40 INFO - PROCESS | 13193 | 17:48:42 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:48:43 INFO - PROCESS | 13193 | RSS: Main: 184647680 17:48:43 INFO - PROCESS | 13193 | 17:48:44 INFO - PROCESS | 13193 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50912/tests/kraken/driver.html?testName=ai-astar) 17:48:45 INFO - PROCESS | 13193 | RSS: Main: 235622400 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | __start_tp_report 17:48:45 INFO - PROCESS | 13193 | _x_x_mozilla_page_load 17:48:45 INFO - PROCESS | 13193 | _x_x_mozilla_page_load_details 17:48:45 INFO - PROCESS | 13193 | |i|pagename|runs| 17:48:45 INFO - PROCESS | 13193 | |0;ai-astar;101;109;109;111;111;109;110;109;110;110 17:48:45 INFO - PROCESS | 13193 | |1;audio-beat-detection;139;116;118;160;121;122;146;114;155;115 17:48:45 INFO - PROCESS | 13193 | |2;audio-dft;160;160;159;160;161;160;163;160;162;161 17:48:45 INFO - PROCESS | 13193 | |3;audio-fft;75;74;74;106;76;75;75;75;111;74 17:48:45 INFO - PROCESS | 13193 | |4;audio-oscillator;82;83;131;84;84;83;125;83;83;130 17:48:45 INFO - PROCESS | 13193 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;118;117 17:48:45 INFO - PROCESS | 13193 | |6;imaging-darkroom;145;146;146;146;146;146;145;146;146;146 17:48:45 INFO - PROCESS | 13193 | |7;imaging-desaturate;95;94;102;94;94;94;95;95;95;95 17:48:45 INFO - PROCESS | 13193 | |8;json-parse-financial;51;54;50;49;49;50;52;49;50;51 17:48:45 INFO - PROCESS | 13193 | |9;json-stringify-tinderbox;58;61;59;59;60;60;59;59;60;59 17:48:45 INFO - PROCESS | 13193 | |10;stanford-crypto-aes;74;82;73;73;72;77;73;73;73;73 17:48:45 INFO - PROCESS | 13193 | |11;stanford-crypto-ccm;119;116;115;118;116;120;123;114;152;125 17:48:45 INFO - PROCESS | 13193 | |12;stanford-crypto-pbkdf2;151;150;154;153;151;151;152;149;156;149 17:48:45 INFO - PROCESS | 13193 | |13;stanford-crypto-sha256-iterative;63;62;65;63;61;64;62;61;62;60 17:48:45 INFO - PROCESS | 13193 | __end_tp_report 17:48:45 INFO - PROCESS | 13193 | __start_cc_report 17:48:45 INFO - PROCESS | 13193 | _x_x_mozilla_cycle_collect,1634 17:48:45 INFO - PROCESS | 13193 | __end_cc_report 17:48:45 INFO - PROCESS | 13193 | __startTimestamp1471654125202__endTimestamp 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | ------- Summary: start ------- 17:48:45 INFO - PROCESS | 13193 | Number of tests: 14 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.88 (2.6%) stddev-sans-first:0.83 17:48:45 INFO - PROCESS | 13193 | Values: 101.0 109.0 109.0 111.0 111.0 109.0 110.0 109.0 110.0 110.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:130.50 stddev:17.72 (13.6%) stddev-sans-first:18.53 17:48:45 INFO - PROCESS | 13193 | Values: 139.0 116.0 118.0 160.0 121.0 122.0 146.0 114.0 155.0 115.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 17:48:45 INFO - PROCESS | 13193 | Values: 160.0 160.0 159.0 160.0 161.0 160.0 163.0 160.0 162.0 161.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:14.29 (19.1%) stddev-sans-first:14.96 17:48:45 INFO - PROCESS | 13193 | Values: 75.0 74.0 74.0 106.0 76.0 75.0 75.0 75.0 111.0 74.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:83.00 stddev:22.05 (26.6%) stddev-sans-first:22.73 17:48:45 INFO - PROCESS | 13193 | Values: 82.0 83.0 131.0 84.0 84.0 83.0 125.0 83.0 83.0 130.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:48:45 INFO - PROCESS | 13193 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 17:48:45 INFO - PROCESS | 13193 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:2.41 (2.5%) stddev-sans-first:2.55 17:48:45 INFO - PROCESS | 13193 | Values: 95.0 94.0 102.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:1.58 (3.1%) stddev-sans-first:1.67 17:48:45 INFO - PROCESS | 13193 | Values: 51.0 54.0 50.0 49.0 49.0 50.0 52.0 49.0 50.0 51.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.73 17:48:45 INFO - PROCESS | 13193 | Values: 58.0 61.0 59.0 59.0 60.0 60.0 59.0 59.0 60.0 59.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.00 stddev:3.02 (4.1%) stddev-sans-first:3.20 17:48:45 INFO - PROCESS | 13193 | Values: 74.0 82.0 73.0 73.0 72.0 77.0 73.0 73.0 73.0 73.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:119.50 stddev:11.17 (9.4%) stddev-sans-first:11.81 17:48:45 INFO - PROCESS | 13193 | Values: 119.0 116.0 115.0 118.0 116.0 120.0 123.0 114.0 152.0 125.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.60 Median:151.50 stddev:2.22 (1.5%) stddev-sans-first:2.35 17:48:45 INFO - PROCESS | 13193 | Values: 151.0 150.0 154.0 153.0 151.0 151.0 152.0 149.0 156.0 149.0 17:48:45 INFO - PROCESS | 13193 | 17:48:45 INFO - PROCESS | 13193 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.49 (2.4%) stddev-sans-first:1.56 17:48:45 INFO - PROCESS | 13193 | Values: 63.0 62.0 65.0 63.0 61.0 64.0 62.0 61.0 62.0 60.0 17:48:45 INFO - PROCESS | 13193 | -------- Summary: end -------- 17:48:45 INFO - PROCESS | 13193 | 17:48:46 INFO - TEST-INFO | 13193: exit 0 17:48:46 INFO - TEST-OK | kraken | took 59996ms 17:48:46 INFO - SUITE-END | took 1173s 17:48:46 INFO - Completed test suite (00:19:33) 17:48:46 INFO - javascript benchmark 17:48:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.236575587615, 3346.548215990842, 3346.882859099931, 3378.2398960233936, 3393.236521782599, 5633.21135041094, 5736.569757268211, 5737.454941780535, 5769.451902069307, 5771.105478650806, 5701.6864072476, 5740.081586946079, 5750.223719797837, 5755.4244575542425, 5761.827145185651, 5017.949820501784, 5190.143626301656, 5191.714455704935, 5242.685438873661, 5273.789048438058, 5065.240213967916, 5156.0203561323415, 5208.192730126836, 5232.058229518685, 5232.502912223333, 5082.288479612857, 5132.024915266087, 5135.178371460567, 5148.227765835118, 5211.7133557654315, 5200.505951934579, 5254.579633629301, 5270.578353731711, 5273.578113750891, 5296.629235953485, 4969.0, 4997.400311962551, 5026.246063090529, 5151.819686310999, 5160.200168973777, 5553.111502159635, 5583.413741557119, 5634.126710359901, 5637.8590535236535, 5684.687342196177, 6114.144019837227, 6115.7859474918605, 6122.755089796403, 6126.877462450726, 6136.864680034208, 5934.406559344075, 5987.880242395171, 6073.787417440369, 6161.260648722137, 6181.876362472725, 5753.568482363839, 5771.480566748971, 5772.942270577324, 5775.104858746901, 5783.450572195635, 6271.843203919939, 6283.371662833772, 6288.496920246461, 6290.905636415458, 6295.874082518324, 5863.589548731633, 5870.559708021916, 5923.49650279731, 5926.762929482772, 6059.21230240066, 6067.605605635619, 6074.2586593570895, 6091.939080609226, 6104.4200800923845, 6105.145279660851, 6037.0, 6044.637321760709, 6046.0, 6047.9395206047375, 6050.969745151246, 5924.7333869975955, 5943.673097979653, 5949.077892926603, 5987.850303742354, 5988.760449582055, 4587.174308624413, 4614.676972611952, 4619.330197121466, 4628.143793398165, 4631.096936097429, 4128.814203360856, 4136.896577585584, 4137.213929353413, 4143.854965076208, 4157.334826427757, 5622.746976385991, 5716.456936591017, 5729.627574207663, 5732.942670573324, 5734.627249228787, 6112.663803490851, 6158.568900177034, 6177.413145751147, 6179.84550386244, 6185.938140618537, 5830.8542286442325, 5839.065749480148, 5841.32824725162, 5844.912326315109, 5865.824025279248, 5055.6713813602, 5191.844244672666, 5310.734463276821, 5321.893562128813, 5325.0, 6234.781782637586, 6400.167978162809, 6403.3276505966205, 6403.839904002344, 6406.967965160145, 6112.663803490851, 6139.3553676863285, 6160.383961603804, 6165.537584681257, 6168.0, 6124.472479235402, 6251.468625166912, 6375.458086062737, 6377.77677781294, 6384.553081284265, 5991.910121348271, 6053.848653783691, 6069.939300606938, 6100.816975490742, 6109.908351374822, 6156.168917196122, 6158.876822463615, 6165.106059621419, 6173.197484327009, 6175.104609831639, 5779.2775903012125, 5888.998870191992, 5947.581147553328, 6027.909581356371, 6032.517398608188, 5914.053751399755, 6011.128386384037, 6015.097735339559, 6038.788642397495, 6047.334793172836, 5911.379305172895, 5953.702314884152, 5968.4031596841705, 5974.342822289632, 5978.103284507359, 5909.0, 5951.7619295227705, 6031.095335699505, 6064.696765161636, 6072.453479186895, 5921.0, 5926.111083337534, 5930.940690593039, 5934.376890426615, 5958.40415958418], "value": 5627.1732173333885, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.1758972617787, 614.1095411653101, 627.5356236385074, 632.4971647540206, 648.649729146261, 34350.828245858764, 45230.7738461308, 46209.30686039704, 46306.305405418905, 49875.75062124693, 46565.30152047722, 47050.764746176224, 47076.0, 47123.29315060268, 47149.764251178785, 8451.619677114544, 9250.953745231265, 9383.0, 9405.482698451582, 11019.173561982843, 10805.675829725122, 11200.943995280013, 11213.831792523119, 11218.326900385962, 11233.887661123366, 11279.718007049807, 11316.434178291094, 11319.830202546967, 11328.490217940212, 11331.830022549668, 11194.328340299608, 11259.493322800494, 11263.549458021711, 11265.211435199539, 11274.830877536844, 11160.16298777595, 11221.0, 11244.606438774603, 11246.55013799447, 11247.775044499145, 46310.53689463096, 46493.30260046102, 46522.0, 46876.765616172044, 46972.5302746975, 3759.680427163667, 4153.7300075494995, 4469.150861336336, 4542.227821270359, 4573.062522182944, 35745.82127089348, 35770.8211458941, 35894.282114357564, 36304.63695363039, 37569.0, 4677.719336839767, 4711.24620060792, 4715.1512727709, 4735.928961065587, 4791.401074865642, 369449.0, 369977.0, 370119.0, 371249.0, 371800.140999296, 65704.67147664231, 66309.33690663031, 66327.0, 66424.66787666127, 66454.0, 38421.231575368336, 39308.0, 39580.0, 41996.37005444921, 67178.0, 18438.170282337327, 18499.260029598667, 18515.907420462812, 18518.81481185171, 18520.907395462935, 12598.874011259953, 14384.35270412834, 14568.708625827425, 14762.70474590502, 14806.481773137888, 21241.468963276042, 21278.893605531874, 21340.573188536142, 21414.0, 21572.244971425924, 7577.810554736066, 7642.04474440695, 7693.538387696756, 7791.117286603992, 7871.921280787234, 1047.7433028907967, 1114.5820317380983, 1123.955041798319, 1132.0603898764007, 1153.5155234801402, 34996.65003349985, 38434.80782596125, 38881.41677874834, 39273.41089883654, 39749.80125099414, 1174.301290732013, 1178.3652535863896, 1179.0155220390898, 1199.1845545029464, 1227.1164761371797, 17872.28510859577, 18192.818071819376, 18267.0, 18302.633947321247, 18304.725429118575, 627326.0, 629136.0, 629343.0, 629690.0, 636139.0, 64251.35748642547, 64662.0, 64721.352786471536, 64737.35262647313, 64742.0, 1368.0218643669823, 1383.6195046362332, 1443.4082026369138, 1463.2025546077414, 1477.2909003678294, 30921.226969325948, 35549.51153419339, 35760.26603967763, 36032.80062249311, 36098.453051924946, 54961.0, 55881.161782574105, 56246.71876640591, 56284.43715562792, 56333.15500267581, 501314.0, 502158.0, 504545.0, 505111.0, 505229.0, 29792.404151917275, 30362.696373035986, 30484.866059375632, 30722.846385768822, 32278.515822263154, 52895.47104529059, 52948.47051529436, 52967.0, 53069.20396194138, 53100.73449632727, 43539.346909797016, 44141.11717765562, 44297.55702443063, 45712.31431528596, 45750.77124614356, 44591.33113003372, 45579.31631025471, 45973.77013114913, 46328.0, 47733.52266477291, 44749.53838823055, 45287.32069019033, 45496.54503455055, 45699.31451028304, 46176.07647847092, 37786.43320350142, 39754.60245397509, 39902.60097399105, 40190.799046004584, 40418.79790601028, 29855.402891942475, 30031.54952675668, 30141.698583013887, 30320.39359212759, 30542.84728576343], "value": 22241.94392354084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.39336242099915, 391.23123063180844, 406.5893447617905, 408.1530823541152, 419.08430080274604, 16505.58736031598, 19846.30537931173, 19853.106610202558, 20111.798882011175, 20334.288299909505, 11700.824487632692, 12211.389430528483, 12291.201071930318, 12351.752964940695, 12384.380780960939, 5369.838904832852, 5577.637453565505, 5582.218489411465, 5587.804426845061, 5594.188842617818, 5423.078076726966, 5487.039768040597, 5843.0, 6095.969520152393, 6114.663693496856, 6185.8144255672405, 6196.783112591038, 6202.7518899244205, 6204.224471941007, 6206.7827626033095, 5999.0, 6108.175396321484, 6110.236220472441, 6150.2927163376335, 6156.107364432132, 5774.307083150007, 5934.6142500737315, 5971.313298970587, 5991.281046274475, 6002.219711437485, 14755.409783608642, 14897.329620167118, 14963.0, 14967.476138335105, 23302.184423545263, 2409.542186984485, 2454.0919859651863, 2479.4049428137296, 2600.4149066459895, 2898.5507246376847, 10235.590576376935, 10914.563417463292, 10924.508397122148, 10926.890731092666, 11304.208705390625, 1970.1134489479791, 2043.8569300148995, 2091.874487530753, 2110.778368271325, 2114.0592436365823, 68587.31412685859, 72672.0, 73582.0, 81089.59455202686, 91237.0, 10803.13574914005, 10949.890501094887, 11452.541898323974, 11515.481803318871, 11758.294502329716, 16293.918530407273, 16330.101844398434, 16336.591585210228, 16344.447277508618, 16348.836511634969, 8522.062573116953, 8631.180037896389, 8638.308935285162, 8761.518116503654, 8892.15524525169, 8394.45436046655, 8472.279856212168, 8520.74437766868, 8684.8697269541, 8697.869531957025, 4636.211843986531, 4659.091477161921, 4787.16224660683, 4803.447603525569, 4898.069366820293, 1907.7233801098762, 2032.8475364347732, 2036.6741321388506, 2060.8557400982086, 2074.4399012266604, 948.938319009276, 1006.5722068121165, 1012.291396022787, 1024.6004058417075, 1032.318669678009, 20880.895595521924, 21041.263555775473, 21096.05067771954, 21320.893395533232, 21587.933330363037, 1040.7294103532984, 1053.752368193474, 1074.3392813419846, 1074.5594306334365, 1102.9669109926715, 11208.607698730668, 11795.264015256926, 11807.763844723057, 11847.229930054518, 11914.404279785977, 556323.0, 561072.0, 563072.0, 582126.0, 586252.0, 87346.56326718452, 96219.51890240645, 97426.0, 99573.50213249032, 99595.0, 1041.4688508860536, 1065.4406436620682, 1107.9058280046124, 1123.7303047268597, 1126.1722633864267, 18522.444326670222, 18556.16497257654, 19024.0, 19091.140898659873, 19245.843763400782, 42901.570984289756, 43777.0, 43937.78031109824, 44784.10431791411, 45729.54270457253, 59116.0, 59269.11096333645, 60147.39852601593, 62527.0, 62627.37372626215, 5918.704064796657, 6689.966550167413, 8657.8701319479, 8841.292696584129, 9264.258859291376, 10036.849447258443, 13227.47090116423, 13820.723585528434, 14032.929835351166, 14223.431062757374, 11685.941570292094, 11908.523659053888, 12096.75806483883, 12222.0, 12391.070669699628, 11635.825462617899, 11884.227525210834, 11938.58214962472, 12191.0, 12340.444679989243, 11828.0, 12050.578229761917, 12141.332226727614, 12493.125481216228, 12671.366431678563, 8983.910160898307, 9140.63437462494, 9192.862107068533, 9324.11420915012, 9409.623615055321, 12087.0, 12191.0, 12268.141230113806, 12299.262044277371, 12380.195287306296], "value": 9408.865699630041, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.698666293415, 1509.320805637463, 1549.8295187529372, 1716.8626509879211, 1878.1454438230605, 8220.177982201776, 9019.323550733689, 9473.289503287264, 9480.383775054624, 9762.365446245989, 9487.241020718344, 9542.904570954288, 9560.904390956088, 9561.569729362178, 9567.138957493824, 2427.1990243219693, 2447.3147518694796, 2449.363165576954, 2453.889574969122, 2459.9016039358403, 2426.611742121261, 2438.0, 2460.3234110619633, 2473.4805690804865, 2473.789727873133, 2427.368884090132, 2429.842060266077, 2437.0, 2466.1615050882697, 2468.358226861011, 2366.325597204788, 2382.7378988311357, 2384.4992551564105, 2388.4028992751814, 2409.5783237933465, 2384.070212617081, 2395.532871090138, 2401.267613377919, 2402.2192787344006, 2403.0147639467737, 9128.45229286245, 9374.343795934288, 9465.0, 9474.289428292906, 9488.573014214377, 5335.599830012765, 5569.916451253234, 5573.024720673866, 5578.330600327987, 5578.44215578443, 3457.7233821294235, 3563.0379797454543, 3568.359414033622, 3603.6756691897854, 3604.2070744436182, 1193.814958681397, 1197.114135539703, 1202.158126976087, 1229.993850030753, 1267.6387229639602, 13476.797848032287, 13554.254516001616, 13655.453781848713, 13658.04393692442, 13693.931530342385, 4591.724496530219, 4660.883477913079, 4810.903781924342, 4837.588804951548, 4856.80572777092, 10606.15150787935, 10606.57573697059, 10614.787704245871, 10621.309614875008, 10622.150227981743, 5587.413321601254, 5588.552915766729, 5588.552915766729, 5589.161625756169, 5598.552115830724, 4655.790489427984, 4707.105649926503, 5123.8462846113935, 5178.896422071537, 5186.844394668166, 4095.9385609215888, 4126.587341265849, 4164.5210800758305, 4171.0615111599645, 4236.322188449833, 1218.5795900414344, 1220.975580488403, 1224.536678669, 1242.5278394210143, 1245.3399698159808, 912.3841407050214, 931.2084727981297, 990.5096976996289, 992.9205663546899, 1000.7147962830704, 5916.402571305809, 6099.878002439927, 6124.448799607968, 6161.784337548254, 6294.181756371734, 455.9749213793273, 457.69105853548723, 458.95869371756703, 469.5163981099473, 473.6045402089294, 4090.427340172378, 4246.8725938221905, 4261.531231564525, 4276.508201556799, 4329.415528903621, 11749.823752643546, 12277.324747138815, 12279.815802762965, 12317.260964342167, 12317.75364492723, 11226.550937962553, 11715.297082174926, 11739.23694959842, 12136.696582585506, 12199.939000304941, 8802.86795698065, 8967.551622418852, 9004.8649270261, 9019.954900225588, 9035.141661542142, 6158.599691020069, 6194.287656919512, 6295.087212354275, 6432.871342573028, 6480.351964803671, 10977.615783447542, 11298.548058077913, 11329.0, 11473.196876218919, 11473.885261147281, 10243.282970192015, 10692.786144276915, 11547.538098476303, 11603.767924641628, 11657.358845263592, 7991.800204994689, 8008.679652813822, 8444.155584441507, 8512.404131710968, 8520.70177543783, 9119.0, 9323.627054917728, 9330.0, 9357.204637605608, 9364.157225849705, 7914.287714105758, 7940.6426710796995, 8085.797855053435, 8149.511029338259, 8242.711505097293, 7656.540607563564, 7883.802904927423, 7924.603769811371, 7946.721864734706, 8156.102828688958, 7464.440166987606, 7496.887546686695, 7523.532911082287, 7529.096508419025, 7532.246775322424, 5090.389153301628, 5121.9743901281745, 5168.53483186515, 5193.221016847503, 5434.64674796137, 5202.271681964604, 5221.530062294411, 5261.289725886956, 5274.525292723673, 5435.184722291688], "value": 4792.0787095271635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.91153750311486, 340.80403767833496, 347.56380742168574, 349.87754285999887, 350.6160753974397, 5411.3506379234495, 5431.864203394917, 5439.156930675742, 5442.877277861632, 5467.972660136704, 5036.773345199465, 5210.55710264627, 5211.42674305826, 5211.791528338862, 5374.602603323135, 4179.33130699089, 4256.723312984646, 4275.508316543606, 4279.593438623326, 4283.400323954649, 4256.553061928499, 4257.0, 4265.744055356673, 4266.402703621496, 4269.380939763748, 3995.98002009991, 4112.691548133887, 4232.15356928614, 4236.915261694764, 4245.23585754564, 4074.8777536673947, 4116.5471798102035, 4172.979135104335, 4180.163967206571, 4183.518895327046, 3706.962930370689, 3707.2029513654425, 3710.499082623838, 3712.814359282025, 3716.4982727331735, 5205.0, 5223.2948551945765, 5224.63427560071, 5228.947710522884, 5237.5548078413, 4617.145828021827, 4746.311784791221, 4783.593394561466, 4787.856364309041, 4797.928031079502, 5571.554275657938, 5574.581906356999, 5582.358028826697, 5584.86037849053, 5587.312373833251, 4068.206699693561, 4077.97961010196, 4079.8776036718946, 4080.3882466425894, 4086.1827634473225, 5583.162525621149, 5727.312722473288, 5772.971135144339, 5796.391378905197, 5801.0, 4135.58644135584, 4147.129102888427, 4148.875533733993, 4154.169166166779, 4161.91676166481, 4190.329547272422, 4191.778357470545, 4201.571465701615, 4202.621764041251, 4203.789810509462, 4584.968382113998, 4723.7638118093955, 4748.406449193851, 4770.642201834877, 4784.952150478521, 5584.776608935678, 5607.158926161027, 5612.5229355504425, 5617.494425501724, 5629.437056294338, 1446.8770154536983, 1459.0807791091545, 1462.7659574468034, 1463.3195564062762, 1471.0952764050032, 940.5250348573926, 958.5782255807422, 967.1778987860403, 990.4898977026837, 1011.4083261292079, 5446.564274858002, 5451.754671039813, 5467.753951072212, 5467.867531662655, 5471.945280547144, 5702.716888700009, 5708.315002199763, 5729.742161602738, 5749.13762935563, 5764.913526297109, 5253.697642520788, 5405.0, 5422.559848068385, 5448.937457195888, 5453.318335208099, 3034.573750337338, 3054.282243672735, 3061.464243757378, 3064.218624250802, 3066.4173806976605, 5776.364599894008, 5934.020886553671, 5959.731812068468, 5973.641581505123, 5981.252343457068, 5794.82615521535, 5912.142739302777, 5917.289925208916, 5918.201042859159, 5954.672493012926, 6051.788187413419, 6063.0, 6078.513718902565, 6101.786437474667, 6102.237220347457, 5579.74891129892, 5752.539796816161, 5821.563382746226, 5832.445917637987, 5834.504067154339, 5760.539156867524, 5782.85542861415, 5799.449052340021, 5801.3908539602735, 5807.419258074159, 5859.267591551056, 6011.879762404815, 6080.939190608215, 6094.939050609437, 6105.8168254952425, 4110.486189226347, 4151.875443736692, 4156.293430116948, 4165.729227600196, 4169.957510622345, 5806.82579522615, 5842.824715258549, 5847.532197424281, 5848.795292164754, 5852.571182959025, 5482.616216864782, 5645.943540564542, 5667.801626943037, 5673.716314184192, 5693.658380497183, 5656.321241451053, 5663.150527420919, 5664.207011018543, 5667.518260947865, 5678.403767604342, 5400.513953744182, 5654.0, 5656.886862262814, 5669.829905102853, 5670.971645141748, 5377.8924421510565, 5708.34354049273, 5722.656640601577, 5724.427557244242, 5728.050054221996, 5520.1443776214755, 5569.944300556943, 5581.469760372758, 5583.832485025456, 5590.664560126405], "value": 4316.036674873302, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.6365390093605, 805.5287656720818, 812.9674813007476, 841.1756478650922, 852.3820230333009, 15223.162726050063, 16417.589560261, 16812.075335856538, 17662.293508259685, 17891.463256102306, 14898.851011489882, 14962.925185374084, 14969.92515037426, 14974.25128743564, 14992.925035374836, 750.650947309501, 858.6651206029638, 874.3267683883406, 874.4010352908247, 895.230102112183, 898.0660113482004, 907.2877790934118, 907.3059109781022, 908.3959167153868, 908.7546362482126, 945.2957546627772, 951.8239125761756, 953.1278879824987, 954.4464210757778, 954.6229239450448, 754.5359603843624, 892.6607889002138, 925.3892430995578, 952.4095061062151, 952.8618350339196, 686.7767975408012, 686.9828254293633, 687.0552989639247, 687.1685260834396, 687.3058211206717, 16105.516834494983, 16117.194140292939, 16140.435084772094, 16155.19224038805, 16156.192190390551, 15164.090154590649, 15587.688246235011, 15845.841541584552, 15863.920680396639, 15983.200839958072, 14144.646383840383, 14206.92896535521, 14221.644458888508, 14314.0, 14355.210463424508, 4645.953540464619, 4683.8360657376825, 4686.484486706459, 4748.71507709535, 4852.660313778002, 88100.0, 88255.0, 88324.0, 88495.0, 88923.55538222332, 12088.41803014919, 12224.388780561114, 12233.143679942319, 12241.387930603434, 12500.312482813533, 24380.0, 24396.756032439447, 24400.14599489045, 24586.877065614557, 24686.259412217663, 8837.823243535222, 8962.596683149273, 9026.232770214605, 9146.085391460801, 9177.587008584629, 4948.950510494921, 5130.538251557377, 5160.045391602565, 5252.501012403815, 5281.445448227958, 3648.4892115103908, 3792.033031576985, 3794.5636251831393, 3796.6013568574904, 3802.125511132455, 660.5376236634376, 661.5931202310641, 662.7382184037278, 667.2493444874516, 676.4047638078558, 752.8155601877465, 757.3978686943893, 772.8106613879523, 775.6542398937838, 777.1179711028024, 10891.618793342353, 10940.343579385104, 11061.170412219115, 11069.0, 11362.318260904372, 666.2171947961126, 740.5593671765291, 755.320211809365, 800.9599520023976, 815.9388045896462, 5169.974150129226, 5204.8959020819375, 5374.731263436813, 5382.085045542267, 5461.644993075437, 76117.0, 77891.0, 80776.0, 81253.0, 81852.18147818565, 76768.61615691886, 77498.0, 84280.57859710662, 121334.39332803279, 124651.0, 64329.0, 102246.48876755568, 105232.0, 105262.4736876326, 108124.97187682775, 10404.271700981162, 10535.947320263656, 10585.788284234426, 10722.195835312226, 10740.570377185139, 55985.44014559965, 56020.43979560315, 56923.14615280691, 57589.136162956755, 57616.423835762784, 78909.0, 83478.16521834947, 84150.0, 84940.0, 86692.0, 13389.531366402129, 13479.19962588183, 13560.972217392784, 13658.0, 13872.237026963614, 15204.853569021312, 15256.771148433005, 15308.464203752816, 15809.13049782273, 15899.602509937344, 9878.452439090646, 11135.220534562826, 13778.586642400744, 14664.0, 14911.403543858125, 14548.709025819637, 14951.626209344853, 14968.40126394932, 15021.474248401253, 15150.621234469227, 14266.429342826586, 14462.20457874785, 14502.564923052325, 14627.195504246944, 15758.921205394357, 12246.326452045223, 12905.935470322589, 13092.607221783363, 13153.736925261632, 13261.336933153496, 13103.14829536077, 13145.408456619283, 13343.733125337632, 13379.264140472367, 13663.590092297247], "value": 8157.068801823607, "unit": "score"}], "name": "dromaeo_css", "value": 7638.913972172852, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 109.0, 111.0, 111.0, 109.0, 110.0, 109.0, 110.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 118.0, 160.0, 121.0, 122.0, 146.0, 114.0, 155.0, 115.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 161.0, 160.0, 163.0, 160.0, 162.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 106.0, 76.0, 75.0, 75.0, 75.0, 111.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 131.0, 84.0, 84.0, 83.0, 125.0, 83.0, 83.0, 130.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 102.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 54.0, 50.0, 49.0, 49.0, 50.0, 52.0, 49.0, 50.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 73.0, 73.0, 72.0, 77.0, 73.0, 73.0, 73.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 115.0, 118.0, 116.0, 120.0, 123.0, 114.0, 152.0, 125.0], "value": 121.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 154.0, 153.0, 151.0, 151.0, 152.0, 149.0, 156.0, 149.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 65.0, 63.0, 61.0, 64.0, 62.0, 61.0, 62.0, 60.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1456.6, "alertThreshold": 2.0}]} 17:48:46 INFO - Return code: 0 17:48:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:48:46 INFO - # TBPL SUCCESS # 17:48:46 INFO - Running post-action listener: _resource_record_post_action 17:48:46 INFO - [mozharness: 2016-08-20 00:48:46.518557Z] Finished run-tests step (success) 17:48:46 INFO - Running post-run listener: _resource_record_post_run 17:48:46 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 270336; Write bytes: 328441856; Read time: 236; Write time: 247564 17:48:46 INFO - TinderboxPrint: CPU usage
12.9% 17:48:46 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 236 17:48:46 INFO - TinderboxPrint: I/O write bytes / time
328,441,856 / 247,564 17:48:46 INFO - TinderboxPrint: CPU idle
8,272.1 (87.1%) 17:48:46 INFO - TinderboxPrint: CPU user
1,180.0 (12.4%) 17:48:46 INFO - TinderboxPrint: Swap in / out
0 / 0 17:48:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79151104; Read time: 0; Write time: 45380 17:48:46 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 249290752; Read time: 236; Write time: 202184 17:48:47 INFO - Running post-run listener: _upload_blobber_files 17:48:47 INFO - Blob upload gear active. 17:48:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:48:47 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 17:48:47 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'] 17:48:47 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 17:48:47 INFO - (blobuploader) - INFO - Open directory for files ... 17:48:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:48:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:48:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:51 INFO - (blobuploader) - INFO - Done attempting. 17:48:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:48:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:48:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:52 INFO - (blobuploader) - INFO - Done attempting. 17:48:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:48:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:48:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:48:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:48:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:48:54 INFO - (blobuploader) - INFO - Done attempting. 17:48:54 INFO - (blobuploader) - INFO - Iteration through files over. 17:48:54 INFO - Return code: 0 17:48:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:48:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:48:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4c46593d841584c01133ae92a60e27becbb7aea8f684a6a0fd8c0618db60bfe98f9e0555ab57c9504007034b7032e4167c81ceab37ba9e9b6b40a6f3b9539c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/658121c14add0552796196ab171526847363c8ccf22b8932bcd34188989e7caea1a7ceb0fa97eafe6d3b270759ea247c03f6433991788d7d0c27a76cd5af2bd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d9efc7646f2b9964662f698d2262d1d2fd2f8c60052fc1eb57b6897e7cf767227b5ce82d8ce49c446ae4445effe30eb9df5adb879f9f27e202dd4f371d8356"} 17:48:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:48:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:48:54 INFO - Contents: 17:48:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4c46593d841584c01133ae92a60e27becbb7aea8f684a6a0fd8c0618db60bfe98f9e0555ab57c9504007034b7032e4167c81ceab37ba9e9b6b40a6f3b9539c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/658121c14add0552796196ab171526847363c8ccf22b8932bcd34188989e7caea1a7ceb0fa97eafe6d3b270759ea247c03f6433991788d7d0c27a76cd5af2bd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d9efc7646f2b9964662f698d2262d1d2fd2f8c60052fc1eb57b6897e7cf767227b5ce82d8ce49c446ae4445effe30eb9df5adb879f9f27e202dd4f371d8356"} 17:48:54 INFO - Running post-run listener: copy_logs_to_upload_dir 17:48:54 INFO - Copying logs to upload dir... 17:48:54 INFO - mkdir: /builds/slave/test/build/upload/logs 17:48:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.813664 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-19 17:48:54.007230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 17:48:54.010481) ========= 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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 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/d4c46593d841584c01133ae92a60e27becbb7aea8f684a6a0fd8c0618db60bfe98f9e0555ab57c9504007034b7032e4167c81ceab37ba9e9b6b40a6f3b9539c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/658121c14add0552796196ab171526847363c8ccf22b8932bcd34188989e7caea1a7ceb0fa97eafe6d3b270759ea247c03f6433991788d7d0c27a76cd5af2bd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d9efc7646f2b9964662f698d2262d1d2fd2f8c60052fc1eb57b6897e7cf767227b5ce82d8ce49c446ae4445effe30eb9df5adb879f9f27e202dd4f371d8356"} build_url:https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/c_JWRLUUTRqbNqW9FEaXkA/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/d4c46593d841584c01133ae92a60e27becbb7aea8f684a6a0fd8c0618db60bfe98f9e0555ab57c9504007034b7032e4167c81ceab37ba9e9b6b40a6f3b9539c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/658121c14add0552796196ab171526847363c8ccf22b8932bcd34188989e7caea1a7ceb0fa97eafe6d3b270759ea247c03f6433991788d7d0c27a76cd5af2bd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64d9efc7646f2b9964662f698d2262d1d2fd2f8c60052fc1eb57b6897e7cf767227b5ce82d8ce49c446ae4445effe30eb9df5adb879f9f27e202dd4f371d8356"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 17:48:54.038380) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:48:54.038751) ========= 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=db0483c52d987ecb441a263b000001ad-1471650199.182579-1207179741 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 17:48:54.118811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 17:48:54.122228) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 17:48:54.122592) ========= ========= Total master_lag: 0.19 =========