builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1471427318.89 results: success (0) buildid: 20160817000116 builduid: 1b6e6293fa4245c58022415da2da6c45 revision: fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:38.889958) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:38.890406) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:38.890704) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.066515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.066783) ========= 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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-17 02:48:39-- 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-17 02:48:39 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122811 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.203238) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.203531) ========= 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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020683 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.253668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.253959) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 --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 fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 --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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-17 02:48:39,590 truncating revision to first 12 chars 2016-08-17 02:48:39,591 Setting DEBUG logging. 2016-08-17 02:48:39,591 attempt 1/10 2016-08-17 02:48:39,591 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fdc2f1d67e39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 02:48:39,897 unpacking tar archive at: mozilla-aurora-fdc2f1d67e39/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649562 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.920331) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.920858) ========= script_repo_revision: fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.921738) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.922079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 02:48:39.933282) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-17 02:48:39.933591) ========= /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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:48:40 INFO - MultiFileLogger online at 20160817 02:48:40 in /builds/slave/test 02:48:40 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 02:48:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:48:40 INFO - {'append_to_log': False, 02:48:40 INFO - 'base_work_dir': '/builds/slave/test', 02:48:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:48:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:48:40 INFO - 'branch': 'Mozilla-Aurora', 02:48:40 INFO - 'buildbot_json_path': 'buildprops.json', 02:48:40 INFO - 'config_files': ('talos/linux_config.py',), 02:48:40 INFO - 'default_actions': ('clobber', 02:48:40 INFO - 'read-buildbot-config', 02:48:40 INFO - 'download-and-extract', 02:48:40 INFO - 'populate-webroot', 02:48:40 INFO - 'create-virtualenv', 02:48:40 INFO - 'install', 02:48:40 INFO - 'run-tests'), 02:48:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:48:40 INFO - 'download_minidump_stackwalk': True, 02:48:40 INFO - 'download_symbols': 'ondemand', 02:48:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:48:40 INFO - 'tooltool.py': '/tools/tooltool.py', 02:48:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:48:40 INFO - '/tools/misc-python/virtualenv.py')}, 02:48:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:48:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:48:40 INFO - 'installer_path': 'installer.exe', 02:48:40 INFO - 'log_level': 'info', 02:48:40 INFO - 'log_name': 'talos', 02:48:40 INFO - 'log_to_console': True, 02:48:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:48:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:48:40 INFO - 'opt_config_files': (), 02:48:40 INFO - 'pip_index': False, 02:48:40 INFO - 'sps_profile': False, 02:48:40 INFO - 'sps_profile_interval': 0, 02:48:40 INFO - 'suite': 'dromaeojs', 02:48:40 INFO - 'system_bits': '32', 02:48:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:48:40 INFO - 'title': 'talos-linux64-ix-043', 02:48:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:48:40 INFO - 'use_talos_json': True, 02:48:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:48:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:48:40 INFO - 'work_dir': 'build'} 02:48:40 INFO - [mozharness: 2016-08-17 09:48:40.357791Z] Running clobber step. 02:48:40 INFO - Running pre-action listener: _resource_record_pre_action 02:48:40 INFO - Running main action method: clobber 02:48:40 INFO - rmtree: /builds/slave/test/build 02:48:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:48:40 INFO - Running post-action listener: _resource_record_post_action 02:48:40 INFO - [mozharness: 2016-08-17 09:48:40.811831Z] Finished clobber step (success) 02:48:40 INFO - [mozharness: 2016-08-17 09:48:40.811933Z] Running read-buildbot-config step. 02:48:40 INFO - Running pre-action listener: _resource_record_pre_action 02:48:40 INFO - Running main action method: read_buildbot_config 02:48:40 INFO - Using buildbot properties: 02:48:40 INFO - { 02:48:40 INFO - "project": "", 02:48:40 INFO - "product": "firefox", 02:48:40 INFO - "script_repo_revision": "production", 02:48:40 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 02:48:40 INFO - "repository": "", 02:48:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 02:48:40 INFO - "buildid": "20160817000116", 02:48:40 INFO - "pgo_build": "True", 02:48:40 INFO - "basedir": "/builds/slave/test", 02:48:40 INFO - "buildnumber": 677, 02:48:40 INFO - "slavename": "talos-linux64-ix-043", 02:48:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:48:40 INFO - "platform": "ubuntu64_hw", 02:48:40 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 02:48:40 INFO - "branch": "mozilla-aurora", 02:48:40 INFO - "repo_path": "releases/mozilla-aurora", 02:48:40 INFO - "revision": "fdc2f1d67e3977e4fbc79d0acbb32259eca0f524", 02:48:40 INFO - "stage_platform": "linux64-pgo", 02:48:40 INFO - "builduid": "1b6e6293fa4245c58022415da2da6c45", 02:48:40 INFO - "slavebuilddir": "test" 02:48:40 INFO - } 02:48:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 02:48:40 INFO - Running post-action listener: _resource_record_post_action 02:48:40 INFO - [mozharness: 2016-08-17 09:48:40.814386Z] Finished read-buildbot-config step (success) 02:48:40 INFO - [mozharness: 2016-08-17 09:48:40.814474Z] Running download-and-extract step. 02:48:40 INFO - Running pre-action listener: _resource_record_pre_action 02:48:40 INFO - Running main action method: download_and_extract 02:48:40 INFO - mkdir: /builds/slave/test/build/tests 02:48:40 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')]} 02:48:40 INFO - https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:48:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:48:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:48:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:48:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:48:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:48:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:48:40 INFO - retry: Failed, sleeping 30 seconds before retrying 02:49:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:49:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:49:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:49:10 INFO - retry: Failed, sleeping 60 seconds before retrying 02:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:50:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:50:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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! 02:50:10 INFO - Caught exception: HTTP Error 404: Not Found 02:50:10 INFO - Caught exception: HTTP Error 404: Not Found 02:50:10 INFO - Caught exception: HTTP Error 404: Not Found 02:50:10 INFO - trying https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:50:10 INFO - Downloading https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:11 INFO - Downloaded 1361 bytes. 02:50:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 02:50:11 INFO - Using the following test package requirements: 02:50:11 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 02:50:11 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 02:50:11 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 02:50:11 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'jsshell-linux-x86_64.zip'], 02:50:11 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 02:50:11 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 02:50:11 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 02:50:11 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 02:50:11 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 02:50:11 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 02:50:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 02:50:11 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:11 INFO - https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:50:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:50:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:50:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:50:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:50:11 INFO - retry: Failed, sleeping 30 seconds before retrying 02:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:50:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:50:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:50:41 INFO - retry: Failed, sleeping 60 seconds before retrying 02:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:51:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:51:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:51:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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! 02:51:41 INFO - Caught exception: HTTP Error 404: Not Found 02:51:41 INFO - Caught exception: HTTP Error 404: Not Found 02:51:41 INFO - Caught exception: HTTP Error 404: Not Found 02:51:41 INFO - trying https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:51:41 INFO - Downloading https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:51:43 INFO - Downloaded 21767137 bytes. 02:51:43 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'] 02:51:43 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 02:51:44 INFO - Return code: 0 02:51:44 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 02:51: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')]} 02:51:44 INFO - https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:51:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:51:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:51:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:51:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:51:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:51:44 INFO - retry: Failed, sleeping 30 seconds before retrying 02:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:52:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:52:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:52:14 INFO - retry: Failed, sleeping 60 seconds before retrying 02:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:53:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:53:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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! 02:53:14 INFO - Caught exception: HTTP Error 404: Not Found 02:53:14 INFO - Caught exception: HTTP Error 404: Not Found 02:53:14 INFO - Caught exception: HTTP Error 404: Not Found 02:53:14 INFO - trying https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 02:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:15 INFO - Downloaded 21767137 bytes. 02:53:15 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'] 02:53:15 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 02:53:17 INFO - Return code: 0 02:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:17 INFO - https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:53:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:53:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:53:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:53:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:53:17 INFO - retry: Failed, sleeping 30 seconds before retrying 02:53:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:53:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:53:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:53:47 INFO - retry: Failed, sleeping 60 seconds before retrying 02:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:54:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:54:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:54:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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! 02:54:47 INFO - Caught exception: HTTP Error 404: Not Found 02:54:47 INFO - Caught exception: HTTP Error 404: Not Found 02:54:47 INFO - Caught exception: HTTP Error 404: Not Found 02:54:47 INFO - trying https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 02:54:47 INFO - Downloading https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:54:48 INFO - Downloaded 14413723 bytes. 02:54:48 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'] 02:54:48 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 02:54:48 INFO - Return code: 0 02:54:48 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')]} 02:54:48 INFO - https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:54:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:54:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:54:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:54:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:54:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:54:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:54:48 INFO - retry: Failed, sleeping 30 seconds before retrying 02:55:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:55:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:55:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:55:18 INFO - retry: Failed, sleeping 60 seconds before retrying 02:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:56:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:56:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:56:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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! 02:56:18 INFO - Caught exception: HTTP Error 404: Not Found 02:56:18 INFO - Caught exception: HTTP Error 404: Not Found 02:56:18 INFO - Caught exception: HTTP Error 404: Not Found 02:56:18 INFO - trying https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:56:18 INFO - Downloading https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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 02:56:20 INFO - Downloaded 63782847 bytes. 02:56:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:56:20 INFO - mkdir: /builds/slave/test/properties 02:56:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:56:20 INFO - Writing to file /builds/slave/test/properties/build_url 02:56:20 INFO - Contents: 02:56:20 INFO - build_url:https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 02:56:20 INFO - Running post-action listener: _resource_record_post_action 02:56:20 INFO - Running post-action listener: set_extra_try_arguments 02:56:20 INFO - [mozharness: 2016-08-17 09:56:20.810997Z] Finished download-and-extract step (success) 02:56:20 INFO - [mozharness: 2016-08-17 09:56:20.811230Z] Running populate-webroot step. 02:56:20 INFO - Running pre-action listener: _resource_record_pre_action 02:56:20 INFO - Running main action method: populate_webroot 02:56:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:56:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:56:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:56:20 INFO - '--tppagecycles', 02:56:20 INFO - '7'], 02:56:20 INFO - 'tests': ['tsvgm']}}, 02:56:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:56:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:56:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:56:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:56:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['damp', 'tps']}, 02:56:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'tests': ['damp', 'tps']}, 02:56:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['dromaeo_dom']}, 02:56:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:56:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['basic_compositor_video']}, 02:56:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:56:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'other-e10s': {'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['a11yr', 02:56:20 INFO - 'ts_paint', 02:56:20 INFO - 'tpaint', 02:56:20 INFO - 'sessionrestore', 02:56:20 INFO - 'sessionrestore_no_auto_restore', 02:56:20 INFO - 'tabpaint']}, 02:56:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['tsvgx', 02:56:20 INFO - 'tsvgr_opacity', 02:56:20 INFO - 'tart', 02:56:20 INFO - 'tscrollx', 02:56:20 INFO - 'cart']}, 02:56:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:56:20 INFO - 'tsvgr_opacity', 02:56:20 INFO - 'tart', 02:56:20 INFO - 'tscrollx', 02:56:20 INFO - 'cart']}, 02:56:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'talos_options': ['--disable-e10s'], 02:56:20 INFO - 'tests': ['tp5o']}, 02:56:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'tests': ['tp5o']}, 02:56:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'talos_options': ['--disable-e10s', 02:56:20 INFO - '--xperf_path', 02:56:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:20 INFO - 'tests': ['tp5n']}, 02:56:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:20 INFO - 'talos_options': ['--xperf_path', 02:56:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:20 INFO - 'tests': ['tp5n']}}, 02:56:20 INFO - 'talos.zip': {'path': '', 02:56:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:56:20 INFO - Running post-action listener: _resource_record_post_action 02:56:20 INFO - [mozharness: 2016-08-17 09:56:20.836303Z] Finished populate-webroot step (success) 02:56:20 INFO - [mozharness: 2016-08-17 09:56:20.836393Z] Running create-virtualenv step. 02:56:20 INFO - Running pre-action listener: _resource_record_pre_action 02:56:20 INFO - Running main action method: create_virtualenv 02:56:20 INFO - Creating virtualenv /builds/slave/test/build/venv 02:56:20 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 02:56:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:56:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:56:20 INFO - Using real prefix '/usr' 02:56:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:56:21 INFO - Installing distribute.............................................................................................................................................................................................done. 02:56:23 INFO - Installing pip.................done. 02:56:23 INFO - Return code: 0 02:56:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:56:23 INFO - Using env: {'DISPLAY': ':0', 02:56:23 INFO - 'HOME': '/home/cltbld', 02:56:23 INFO - 'LANG': 'en_US.UTF-8', 02:56:23 INFO - 'LANGUAGE': 'en_US:en', 02:56:23 INFO - 'LOGNAME': 'cltbld', 02:56:23 INFO - 'MAIL': '/var/mail/cltbld', 02:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:23 INFO - 'MOZ_NO_REMOTE': '1', 02:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:23 INFO - 'NO_EM_RESTART': '1', 02:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:23 INFO - 'PWD': '/builds/slave/test', 02:56:23 INFO - 'SHELL': '/bin/bash', 02:56:23 INFO - 'SHLVL': '1', 02:56:23 INFO - 'TERM': 'linux', 02:56:23 INFO - 'TMOUT': '86400', 02:56:23 INFO - 'USER': 'cltbld', 02:56:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:23 INFO - '_': '/tools/buildbot/bin/python'} 02:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:23 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 02:56:23 INFO - Cleaning up... 02:56:23 INFO - Return code: 0 02:56:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:56:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:56:23 INFO - Using env: {'DISPLAY': ':0', 02:56:23 INFO - 'HOME': '/home/cltbld', 02:56:23 INFO - 'LANG': 'en_US.UTF-8', 02:56:23 INFO - 'LANGUAGE': 'en_US:en', 02:56:23 INFO - 'LOGNAME': 'cltbld', 02:56:23 INFO - 'MAIL': '/var/mail/cltbld', 02:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:23 INFO - 'MOZ_NO_REMOTE': '1', 02:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:23 INFO - 'NO_EM_RESTART': '1', 02:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:23 INFO - 'PWD': '/builds/slave/test', 02:56:23 INFO - 'SHELL': '/bin/bash', 02:56:23 INFO - 'SHLVL': '1', 02:56:23 INFO - 'TERM': 'linux', 02:56:23 INFO - 'TMOUT': '86400', 02:56:23 INFO - 'USER': 'cltbld', 02:56:23 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:23 INFO - '_': '/tools/buildbot/bin/python'} 02:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:23 INFO - Downloading/unpacking psutil>=3.1.1 02:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:56:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:25 INFO - Installing collected packages: psutil 02:56:25 INFO - Running setup.py install for psutil 02:56:25 INFO - building 'psutil._psutil_linux' extension 02:56:25 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 02:56:26 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 02:56:26 INFO - building 'psutil._psutil_posix' extension 02:56:26 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 02:56:26 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 02:56:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:26 INFO - Successfully installed psutil 02:56:26 INFO - Cleaning up... 02:56:26 INFO - Return code: 0 02:56:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:56:26 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')]} 02:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:26 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')]} 02:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:26 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:26 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 02:56:26 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 02:56:26 INFO - Using env: {'DISPLAY': ':0', 02:56:26 INFO - 'HOME': '/home/cltbld', 02:56:26 INFO - 'LANG': 'en_US.UTF-8', 02:56:26 INFO - 'LANGUAGE': 'en_US:en', 02:56:26 INFO - 'LOGNAME': 'cltbld', 02:56:26 INFO - 'MAIL': '/var/mail/cltbld', 02:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:26 INFO - 'MOZ_NO_REMOTE': '1', 02:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:26 INFO - 'NO_EM_RESTART': '1', 02:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:26 INFO - 'PWD': '/builds/slave/test', 02:56:26 INFO - 'SHELL': '/bin/bash', 02:56:26 INFO - 'SHLVL': '1', 02:56:26 INFO - 'TERM': 'linux', 02:56:26 INFO - 'TMOUT': '86400', 02:56:26 INFO - 'USER': 'cltbld', 02:56:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:26 INFO - '_': '/tools/buildbot/bin/python'} 02:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:56:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:56:28 INFO - Installing collected packages: mozsystemmonitor 02:56:28 INFO - Running setup.py install for mozsystemmonitor 02:56:28 INFO - Successfully installed mozsystemmonitor 02:56:28 INFO - Cleaning up... 02:56:28 INFO - Return code: 0 02:56:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:28 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:28 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 02:56:28 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 02:56:28 INFO - Using env: {'DISPLAY': ':0', 02:56:28 INFO - 'HOME': '/home/cltbld', 02:56:28 INFO - 'LANG': 'en_US.UTF-8', 02:56:28 INFO - 'LANGUAGE': 'en_US:en', 02:56:28 INFO - 'LOGNAME': 'cltbld', 02:56:28 INFO - 'MAIL': '/var/mail/cltbld', 02:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:28 INFO - 'MOZ_NO_REMOTE': '1', 02:56:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:28 INFO - 'NO_EM_RESTART': '1', 02:56:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:28 INFO - 'PWD': '/builds/slave/test', 02:56:28 INFO - 'SHELL': '/bin/bash', 02:56:28 INFO - 'SHLVL': '1', 02:56:28 INFO - 'TERM': 'linux', 02:56:28 INFO - 'TMOUT': '86400', 02:56:28 INFO - 'USER': 'cltbld', 02:56:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:28 INFO - '_': '/tools/buildbot/bin/python'} 02:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:28 INFO - Downloading/unpacking jsonschema==2.5.1 02:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:56:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:56:31 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 02:56:31 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 02:56:31 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 02:56:31 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 02:56:31 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 02:56:31 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 02:56:31 INFO - Downloading functools32-3.2.3-2.tar.gz 02:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:56:32 INFO - warning: no files found matching '*.txt' 02:56:32 INFO - no previously-included directories found matching 'build' 02:56:32 INFO - no previously-included directories found matching 'dist' 02:56:32 INFO - no previously-included directories found matching '.git*' 02:56:32 INFO - Installing collected packages: jsonschema, functools32 02:56:32 INFO - Running setup.py install for functools32 02:56:32 INFO - warning: no files found matching '*.txt' 02:56:32 INFO - no previously-included directories found matching 'build' 02:56:32 INFO - no previously-included directories found matching 'dist' 02:56:32 INFO - no previously-included directories found matching '.git*' 02:56:32 INFO - Successfully installed jsonschema functools32 02:56:32 INFO - Cleaning up... 02:56:32 INFO - Return code: 0 02:56:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:56:32 INFO - Using env: {'DISPLAY': ':0', 02:56:32 INFO - 'HOME': '/home/cltbld', 02:56:32 INFO - 'LANG': 'en_US.UTF-8', 02:56:32 INFO - 'LANGUAGE': 'en_US:en', 02:56:32 INFO - 'LOGNAME': 'cltbld', 02:56:32 INFO - 'MAIL': '/var/mail/cltbld', 02:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:32 INFO - 'MOZ_NO_REMOTE': '1', 02:56:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:32 INFO - 'NO_EM_RESTART': '1', 02:56:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:32 INFO - 'PWD': '/builds/slave/test', 02:56:32 INFO - 'SHELL': '/bin/bash', 02:56:32 INFO - 'SHLVL': '1', 02:56:32 INFO - 'TERM': 'linux', 02:56:32 INFO - 'TMOUT': '86400', 02:56:32 INFO - 'USER': 'cltbld', 02:56:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:32 INFO - '_': '/tools/buildbot/bin/python'} 02:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:56:32 INFO - Cleaning up... 02:56:32 INFO - Return code: 0 02:56:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:56:32 INFO - Using env: {'DISPLAY': ':0', 02:56:32 INFO - 'HOME': '/home/cltbld', 02:56:32 INFO - 'LANG': 'en_US.UTF-8', 02:56:32 INFO - 'LANGUAGE': 'en_US:en', 02:56:32 INFO - 'LOGNAME': 'cltbld', 02:56:32 INFO - 'MAIL': '/var/mail/cltbld', 02:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:32 INFO - 'MOZ_NO_REMOTE': '1', 02:56:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:32 INFO - 'NO_EM_RESTART': '1', 02:56:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:32 INFO - 'PWD': '/builds/slave/test', 02:56:32 INFO - 'SHELL': '/bin/bash', 02:56:32 INFO - 'SHLVL': '1', 02:56:32 INFO - 'TERM': 'linux', 02:56:32 INFO - 'TMOUT': '86400', 02:56:32 INFO - 'USER': 'cltbld', 02:56:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:32 INFO - '_': '/tools/buildbot/bin/python'} 02:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:32 INFO - Downloading/unpacking blobuploader==1.2.4 02:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:56:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:56:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:35 INFO - Downloading docopt-0.6.1.tar.gz 02:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:56:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:56:35 INFO - Running setup.py install for blobuploader 02:56:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:56:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:56:35 INFO - Running setup.py install for requests 02:56:35 INFO - Running setup.py install for docopt 02:56:35 INFO - Successfully installed blobuploader requests docopt 02:56:35 INFO - Cleaning up... 02:56:35 INFO - Return code: 0 02:56:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:35 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 02:56:35 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 02:56:35 INFO - Using env: {'DISPLAY': ':0', 02:56:35 INFO - 'HOME': '/home/cltbld', 02:56:35 INFO - 'LANG': 'en_US.UTF-8', 02:56:35 INFO - 'LANGUAGE': 'en_US:en', 02:56:35 INFO - 'LOGNAME': 'cltbld', 02:56:35 INFO - 'MAIL': '/var/mail/cltbld', 02:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:35 INFO - 'MOZ_NO_REMOTE': '1', 02:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:35 INFO - 'NO_EM_RESTART': '1', 02:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:35 INFO - 'PWD': '/builds/slave/test', 02:56:35 INFO - 'SHELL': '/bin/bash', 02:56:35 INFO - 'SHLVL': '1', 02:56:35 INFO - 'TERM': 'linux', 02:56:35 INFO - 'TMOUT': '86400', 02:56:35 INFO - 'USER': 'cltbld', 02:56:35 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:35 INFO - '_': '/tools/buildbot/bin/python'} 02:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:36 INFO - Running setup.py (path:/tmp/pip-5DFJeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:36 INFO - Running setup.py (path:/tmp/pip-OZTbC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:36 INFO - Running setup.py (path:/tmp/pip-W0A5qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:36 INFO - Running setup.py (path:/tmp/pip-imfGBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:36 INFO - Running setup.py (path:/tmp/pip-TdBC2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:36 INFO - Running setup.py (path:/tmp/pip-9ie74G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:36 INFO - Running setup.py (path:/tmp/pip-4KrFBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:36 INFO - Running setup.py (path:/tmp/pip-dy4Ghd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:36 INFO - Running setup.py (path:/tmp/pip-5hQOS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:36 INFO - Running setup.py (path:/tmp/pip-M9Bfl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:36 INFO - Running setup.py (path:/tmp/pip-vP6AL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:36 INFO - Running setup.py (path:/tmp/pip-oRyyRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:37 INFO - Running setup.py (path:/tmp/pip-M1UDgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:37 INFO - Running setup.py (path:/tmp/pip-6ytWmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:37 INFO - Running setup.py (path:/tmp/pip-E4RcXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:37 INFO - Running setup.py (path:/tmp/pip-5kq8C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:37 INFO - Running setup.py (path:/tmp/pip-h29QZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:56:37 INFO - Running setup.py install for manifestparser 02:56:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:56:37 INFO - Running setup.py install for mozcrash 02:56:37 INFO - Running setup.py install for mozdebug 02:56:37 INFO - Running setup.py install for mozdevice 02:56:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:56:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:56:37 INFO - Running setup.py install for mozfile 02:56:37 INFO - Running setup.py install for mozhttpd 02:56:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozinfo 02:56:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozInstall 02:56:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozleak 02:56:38 INFO - Running setup.py install for mozlog 02:56:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for moznetwork 02:56:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozprocess 02:56:38 INFO - Running setup.py install for mozprofile 02:56:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozrunner 02:56:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:56:38 INFO - Running setup.py install for mozscreenshot 02:56:38 INFO - Running setup.py install for moztest 02:56:39 INFO - Running setup.py install for mozversion 02:56:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:56:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:56:39 INFO - Cleaning up... 02:56:39 INFO - Return code: 0 02:56:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:39 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:39 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 02:56:39 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 02:56:39 INFO - Using env: {'DISPLAY': ':0', 02:56:39 INFO - 'HOME': '/home/cltbld', 02:56:39 INFO - 'LANG': 'en_US.UTF-8', 02:56:39 INFO - 'LANGUAGE': 'en_US:en', 02:56:39 INFO - 'LOGNAME': 'cltbld', 02:56:39 INFO - 'MAIL': '/var/mail/cltbld', 02:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:39 INFO - 'MOZ_NO_REMOTE': '1', 02:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:39 INFO - 'NO_EM_RESTART': '1', 02:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:39 INFO - 'PWD': '/builds/slave/test', 02:56:39 INFO - 'SHELL': '/bin/bash', 02:56:39 INFO - 'SHLVL': '1', 02:56:39 INFO - 'TERM': 'linux', 02:56:39 INFO - 'TMOUT': '86400', 02:56:39 INFO - 'USER': 'cltbld', 02:56:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:39 INFO - '_': '/tools/buildbot/bin/python'} 02:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:39 INFO - Running setup.py (path:/tmp/pip-Y3seCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:39 INFO - Running setup.py (path:/tmp/pip-b_Z0oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:39 INFO - Running setup.py (path:/tmp/pip-c0C6Et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:39 INFO - Running setup.py (path:/tmp/pip-OzjzcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:39 INFO - Running setup.py (path:/tmp/pip-fzWpN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:39 INFO - Running setup.py (path:/tmp/pip-UXlyYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:39 INFO - Running setup.py (path:/tmp/pip-TBIy5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:39 INFO - Running setup.py (path:/tmp/pip-7n93iZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:39 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)) 02:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:39 INFO - Running setup.py (path:/tmp/pip-p0v5t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:40 INFO - Running setup.py (path:/tmp/pip-7NIQ4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:40 INFO - Running setup.py (path:/tmp/pip-RzhdEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:40 INFO - Running setup.py (path:/tmp/pip-pCOiWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:40 INFO - Running setup.py (path:/tmp/pip-IJme3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:40 INFO - Running setup.py (path:/tmp/pip-EAKecq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:40 INFO - Running setup.py (path:/tmp/pip-qcVFTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:40 INFO - Running setup.py (path:/tmp/pip-dwoTCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:40 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)) 02:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:40 INFO - Running setup.py (path:/tmp/pip-EgdLkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:40 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)) 02:56:40 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)) 02:56:40 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)) 02:56:40 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)) 02:56:40 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)) 02:56:40 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)) 02:56:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:56:40 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 02:56:40 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 02:56:40 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 02:56:40 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 02:56:40 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 02:56:40 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 02:56:42 INFO - Downloading blessings-1.6.tar.gz 02:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:56:42 INFO - Installing collected packages: blessings 02:56:42 INFO - Running setup.py install for blessings 02:56:42 INFO - Successfully installed blessings 02:56:42 INFO - Cleaning up... 02:56:42 INFO - Return code: 0 02:56:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:56:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:42 INFO - Reading from file tmpfile_stdout 02:56:42 INFO - Current package versions: 02:56:42 INFO - argparse == 1.2.1 02:56:42 INFO - blessings == 1.6 02:56:42 INFO - blobuploader == 1.2.4 02:56:42 INFO - docopt == 0.6.1 02:56:42 INFO - functools32 == 3.2.3-2 02:56:42 INFO - jsonschema == 2.5.1 02:56:42 INFO - manifestparser == 1.1 02:56:42 INFO - mozInstall == 1.12 02:56:42 INFO - mozcrash == 0.17 02:56:42 INFO - mozdebug == 0.1 02:56:42 INFO - mozdevice == 0.48 02:56:42 INFO - mozfile == 1.2 02:56:42 INFO - mozhttpd == 0.7 02:56:42 INFO - mozinfo == 0.9 02:56:42 INFO - mozleak == 0.1 02:56:42 INFO - mozlog == 3.3 02:56:42 INFO - moznetwork == 0.27 02:56:42 INFO - mozprocess == 0.23 02:56:42 INFO - mozprofile == 0.28 02:56:42 INFO - mozrunner == 6.12 02:56:42 INFO - mozscreenshot == 0.1 02:56:42 INFO - mozsystemmonitor == 0.3 02:56:42 INFO - moztest == 0.7 02:56:42 INFO - mozversion == 1.4 02:56:42 INFO - psutil == 3.1.1 02:56:42 INFO - requests == 1.2.3 02:56:42 INFO - wsgiref == 0.1.2 02:56:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:42 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:42 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 02:56:42 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 02:56:42 INFO - Using env: {'DISPLAY': ':0', 02:56:42 INFO - 'HOME': '/home/cltbld', 02:56:42 INFO - 'LANG': 'en_US.UTF-8', 02:56:42 INFO - 'LANGUAGE': 'en_US:en', 02:56:42 INFO - 'LOGNAME': 'cltbld', 02:56:42 INFO - 'MAIL': '/var/mail/cltbld', 02:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:42 INFO - 'MOZ_NO_REMOTE': '1', 02:56:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:42 INFO - 'NO_EM_RESTART': '1', 02:56:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:42 INFO - 'PWD': '/builds/slave/test', 02:56:42 INFO - 'SHELL': '/bin/bash', 02:56:42 INFO - 'SHLVL': '1', 02:56:42 INFO - 'TERM': 'linux', 02:56:42 INFO - 'TMOUT': '86400', 02:56:42 INFO - 'USER': 'cltbld', 02:56:42 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:42 INFO - '_': '/tools/buildbot/bin/python'} 02:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 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)) 02:56:43 INFO - Cleaning up... 02:56:43 INFO - Return code: 0 02:56:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:43 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 0x182e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, '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': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', '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 02:56:43 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 02:56:43 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 02:56:43 INFO - Using env: {'DISPLAY': ':0', 02:56:43 INFO - 'HOME': '/home/cltbld', 02:56:43 INFO - 'LANG': 'en_US.UTF-8', 02:56:43 INFO - 'LANGUAGE': 'en_US:en', 02:56:43 INFO - 'LOGNAME': 'cltbld', 02:56:43 INFO - 'MAIL': '/var/mail/cltbld', 02:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:43 INFO - 'MOZ_NO_REMOTE': '1', 02:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:56:43 INFO - 'NO_EM_RESTART': '1', 02:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:43 INFO - 'PWD': '/builds/slave/test', 02:56:43 INFO - 'SHELL': '/bin/bash', 02:56:43 INFO - 'SHLVL': '1', 02:56:43 INFO - 'TERM': 'linux', 02:56:43 INFO - 'TMOUT': '86400', 02:56:43 INFO - 'USER': 'cltbld', 02:56:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:43 INFO - '_': '/tools/buildbot/bin/python'} 02:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:56:43 INFO - Cleaning up... 02:56:43 INFO - Return code: 0 02:56:43 INFO - Running post-action listener: _resource_record_post_action 02:56:43 INFO - Running post-action listener: _start_resource_monitoring 02:56:43 INFO - Starting resource monitoring. 02:56:43 INFO - [mozharness: 2016-08-17 09:56:43.454839Z] Finished create-virtualenv step (success) 02:56:43 INFO - [mozharness: 2016-08-17 09:56:43.455269Z] Running install step. 02:56:43 INFO - Running pre-action listener: _resource_record_pre_action 02:56:43 INFO - Running main action method: install 02:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:43 INFO - Reading from file tmpfile_stdout 02:56:43 INFO - Detecting whether we're running mozinstall >=1.0... 02:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:56:43 INFO - Reading from file tmpfile_stdout 02:56:43 INFO - Output received: 02:56:43 INFO - Usage: mozinstall [options] installer 02:56:43 INFO - Options: 02:56:43 INFO - -h, --help show this help message and exit 02:56:43 INFO - -d DEST, --destination=DEST 02:56:43 INFO - Directory to install application into. [default: 02:56:43 INFO - "/builds/slave/test"] 02:56:43 INFO - --app=APP Application being installed. [default: firefox] 02:56:43 INFO - mkdir: /builds/slave/test/build/application 02:56:43 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'] 02:56:43 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 02:57:00 INFO - Reading from file tmpfile_stdout 02:57:00 INFO - Output received: 02:57:00 INFO - /builds/slave/test/build/application/firefox/firefox 02:57:00 INFO - Running post-action listener: _resource_record_post_action 02:57:00 INFO - [mozharness: 2016-08-17 09:57:00.421984Z] Finished install step (success) 02:57:00 INFO - [mozharness: 2016-08-17 09:57:00.422265Z] Running run-tests step. 02:57:00 INFO - Running pre-action listener: _resource_record_pre_action 02:57:00 INFO - Running main action method: run_tests 02:57:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:57:00 INFO - Python 2.7.3 02:57:00 INFO - Return code: 0 02:57:00 INFO - grabbing minidump binary from tooltool 02:57:00 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')]} 02:57:00 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 0x1aed3b0>, '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 0x1aee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aee740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:57:00 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 02:57:00 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 02:57:00 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 02:57:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:57:00 INFO - Return code: 0 02:57:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:57:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:57:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:57:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:57:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:57:00 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/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 02:57:00 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/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 02:57:00 INFO - Using env: {'DISPLAY': ':0', 02:57:00 INFO - 'HOME': '/home/cltbld', 02:57:00 INFO - 'LANG': 'en_US.UTF-8', 02:57:00 INFO - 'LANGUAGE': 'en_US:en', 02:57:00 INFO - 'LOGNAME': 'cltbld', 02:57:00 INFO - 'MAIL': '/var/mail/cltbld', 02:57:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:57:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:00 INFO - 'MOZ_NO_REMOTE': '1', 02:57:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:00 INFO - 'NO_EM_RESTART': '1', 02:57:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:00 INFO - 'PWD': '/builds/slave/test', 02:57:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:57:00 INFO - 'SHELL': '/bin/bash', 02:57:00 INFO - 'SHLVL': '1', 02:57:00 INFO - 'TERM': 'linux', 02:57:00 INFO - 'TMOUT': '86400', 02:57:00 INFO - 'USER': 'cltbld', 02:57:00 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1471423906.713521-9286304', 02:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:00 INFO - '_': '/tools/buildbot/bin/python'} 02:57:00 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/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 02:57:00 INFO - mozversion application_buildid: 20160817000116 02:57:00 INFO - mozversion application_changeset: fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 02:57:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:57:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:57:00 INFO - mozversion application_name: Firefox 02:57:00 INFO - mozversion application_remotingname: firefox-dev 02:57:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:57:00 INFO - mozversion application_vendor: Mozilla 02:57:00 INFO - mozversion application_version: 50.0a2 02:57:00 INFO - mozversion platform_buildid: 20160817000116 02:57:00 INFO - mozversion platform_changeset: fdc2f1d67e3977e4fbc79d0acbb32259eca0f524 02:57:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:57:00 INFO - mozversion platform_version: 50.0a2 02:57:00 INFO - using testdate: 1471427820 02:57:00 INFO - actual date: 1471427820 02:57:00 INFO - starting webserver on 'localhost:48584' 02:57:00 INFO - SUITE-START | Running 2 tests 02:57:00 INFO - TEST-START | dromaeo_css 02:57:00 INFO - operating with platform_type : linux_ 02:57:00 INFO - Initialising browser for dromaeo_css test... 02:57:00 INFO - TEST-INFO | started process 3754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0dirSa/profile http://localhost:48584/getInfo.html) 02:57:05 INFO - PROCESS | 3754 | __metrics Screen width/height:1600/1200 02:57:05 INFO - PROCESS | 3754 | colorDepth:24 02:57:05 INFO - PROCESS | 3754 | Browser inner width/height: 1024/697 02:57:05 INFO - PROCESS | 3754 | __metrics 02:57:05 INFO - PROCESS | 3754 | 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" 02:57:05 INFO - PROCESS | 3754 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:57:06 INFO - TEST-INFO | 3754: exit 0 02:57:06 INFO - Browser initialized. 02:57:06 INFO - Running cycle 1/1 for dromaeo_css test... 02:57:06 INFO - TEST-INFO | started process 3844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0dirSa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:18 INFO - PROCESS | 3844 | RSS: Main: 145944576 02:57:18 INFO - PROCESS | 3844 | 02:58:09 INFO - PROCESS | 3844 | 1471427889412 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:00:18 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48584/tests/dromaeo/cssquery-ext.html) 03:00:19 INFO - PROCESS | 3844 | RSS: Main: 220512256 03:00:19 INFO - PROCESS | 3844 | 03:03:21 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-ext.html (next: http://localhost:48584/tests/dromaeo/cssquery-jquery.html) 03:03:22 INFO - PROCESS | 3844 | RSS: Main: 230785024 03:03:22 INFO - PROCESS | 3844 | 03:06:24 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48584/tests/dromaeo/cssquery-mootools.html) 03:06:24 INFO - PROCESS | 3844 | RSS: Main: 199278592 03:06:24 INFO - PROCESS | 3844 | 03:09:26 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48584/tests/dromaeo/cssquery-prototype.html) 03:09:27 INFO - PROCESS | 3844 | RSS: Main: 175366144 03:09:27 INFO - PROCESS | 3844 | 03:12:29 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48584/tests/dromaeo/cssquery-yui.html) 03:12:30 INFO - PROCESS | 3844 | RSS: Main: 177336320 03:12:30 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | Cycle 1(1): loaded http://localhost:48584/tests/dromaeo/cssquery-yui.html (next: http://localhost:48584/tests/dromaeo/cssquery-dojo.html) 03:15:33 INFO - PROCESS | 3844 | RSS: Main: 260706304 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | __start_tp_report 03:15:33 INFO - PROCESS | 3844 | _x_x_mozilla_page_load 03:15:33 INFO - PROCESS | 3844 | _x_x_mozilla_page_load_details 03:15:33 INFO - PROCESS | 3844 | |i|pagename|runs| 03:15:33 INFO - PROCESS | 3844 | |0;dojo.html;3230.0309907027895;3241.2707140893317;3247.009662053073;3247.4154652162615;3267.722243609293;5533.059379905416;5577.470140336672;5584.6090773645865;5601.6358936669085;5602.019646561856;5572.693501857407;5644.350899646532;5666.121751128571;5669.262995810539;5713.14302854572;5026.84919452417;5064.79740810369;5087.847364579059;5115.795368185268;5157.149070403379;5065.493450654942;5157.149070403379;5158.74206289684;5169.1987741899875;5176.896462070757;5074.264231686403;5116.386033675964;5153.665011774241;5153.896922061556;5159.277701121827;5171.974140129294;5190.974045129769;5191.662541934762;5193.221016847503;5220.556252718524;4943.480934501861;5010.924836127425;5076.416212135615;5077.619178561623;5088.0587091388215;5518.91721624176;5556.305461817273;5568.52667523257;5626.634268772516;5650.971745141289;6067.817965461043;6074.088886667036;6082.026875699867;6087.4825639820665;6088.238970128734;6007.309159446676;6036.0644100164545;6051.0923361496125;6071.028635418356;6090.42140996609;5607.102863541813;5652.63257888236;5659.3208814941645;5667.3482549507335;5668.518175955008;6001.849953751192;6147.600405973687;6167.229096362955;6176.382361763787;6184.196054512884;5809.7095145242565;5853.151293062568;5870.442307980735;5926.259217597801;5930.258717660293;5975.850603734942;5984.251968503937;6013.188219590299;6013.338532761392;6015.759369625166;5752.079667253303;5913.408659134052;5952.404759524013;5955.166276721263;5969.611975221684;5882;5905.055191169392;5931.347551769302;5963.314218864886;5994.880102397928;4629.560191781776;4630.4443466784205;4630.467496237909;4631.212693842055;4662.067586482649;4015.377616469452;4125.1131006833675;4134.276501612206;4144.088300573869;4153.02403935075;5662;5700.458456446631;5706.058500347396;5786.88426231473;5810.273715785527;5990.520758339322;6021.57848950578;6028.4272994065495;6035.426634469718;6054.697265136726;5743.10981797822;5743.282089738783;5746.712664366765;5754.309482862083;5756.798512052058;5010.64925455222;5092.643514953992;5179.45615710345;5182.689038657692;5256.316678831728;6200.689965501797;6211.378862113843;6213.844653883689;6217.285012223561;6218.440340369298;5999.430054144849;6009.338972713085;6013;6016.187814644967;6016.368281330572;5943;6135.202423684892;6155.230596175478;6193.938060619336;6274.529410294155;5978.790742323998;5980.43185897339;5990.191324171181;6005.549583781147;6026.15633811258;6094.39056094387;6099.420555047263;6105.267367915879;6108.786192483241;6110.388961103854;5633.8309850704545;5852.4147585241135;5853.473187413153;5868.823935281948;5982.0727787193055;5912.379200183918;5918.23063001807;5918.674472904032;5921.407859214044;5923.7926672566255;5826.796062137974;5839.007368747408;5867.823965281048;5886.558508111994;5888.793892213752;5830.4461076197695;5858.179854820243;5937.821865344046;5940.019896716994;6034.547408944434;5849.151872978479;5851.268591426072;5854.677992710272;5856.385079566755;5856.853578660568 03:15:33 INFO - PROCESS | 3844 | |1;ext.html;597.3130899465614;606.6208619612742;606.8361542383557;626.6553395632407;634.6731433311845;36052.09869753254;38427.80786096069;46491.76754116225;47670.284945725754;47809.760951195196;49135.26297105546;49523.25715114276;49543.256851147264;49612.50387496124;49671.75164124183;9378.765530861732;9536.189423898977;9546.904530954705;9547.14075733184;9549.570269337897;8708.21626053655;9375.859362109573;11132.833007504894;11356.829647555292;11369.943150284236;11521.942390288037;11596;11606.187566870323;11614.6515604532;11629.360385178812;11372.203945723802;11411.771764564704;11426.428678566039;11440.885591144066;11476.311421314706;11242.66272011841;11322.490487928064;11322.660320190407;11325.32048077118;11337.716557086056;45324.093518129455;45353.773231133964;45450.77274613639;45582.54417455816;45640.77179614114;3767.6420740029926;3789.488419063419;4437.334399840018;4450.732956022649;4535.31970204472;35151.64848351509;35245.647543524494;35666.465003024976;35975.4603680945;36281.27437451263;3904.3362628352966;3920.137569734654;3948.0919388540797;4005.2790497710403;4012.779297138656;405300;407339;407651.99999999703;408180;408678;38703.61296387056;60871;61321.08018379639;61357.69321153455;61371.38628613657;39114.413283800764;41643.58356416397;41668.79165604213;41824.37263441051;41842;15896.523104306889;16021.359145634044;16022;16027.118508481912;16030;11992.220505667105;13138.4087716053;13186.736265274641;13225.867741322656;13233.139845910178;9148.817023659489;9382.765430864283;9441.811163776685;9469.289803264644;9545.331826772059;3537.1864471171775;3561.4479755637917;3602.765820221677;3605.296967091392;3620.2940426617083;1149.6378640728144;1159.2349049627205;1192.3203773848822;1241.267652085274;1242;35708.28583428317;35956.460653090224;36079.4588081179;36141.27717445689;36189;1168.3515648814819;1184.7275126720901;1229.2562999385423;1241.1746188784539;1263.6145975477568;18385.724214136797;18475.630487390175;18626;18637.347692830685;18779.34272300463;171877.14061429864;173127;173306.13346933437;173717;174321;43880.3417948731;44042.559574403844;44146.337804932955;44210;44272.3359149613;1317.157019507516;1398.189050350794;1443.7545617244884;1453.14264583896;1459.3505889879114;35343.29313413769;37251.81374093221;37333;37336.626633733315;37562.62437375591;54627.72686136703;55242.44757552373;55285;55324.44675553193;55326.17010744922;456680;457022;458526;458808;459109;51471.22793158031;51768.48231517636;52149;52284.21573676474;52798.47201527935;52268.21597676114;52869.7356513215;56391.71804140953;57102.7144864273;57213;45367.31949020701;46593.6021919343;48384.27423588719;48524;49499.752501237264;47480.52519474761;48265.276020860416;48345.51654483551;48897.266541002624;49042.509574903794;47004.29493557531;47115;47764.2835357463;48633.75683121703;48649.75675121602;30435.695643043287;32003.35993280168;32275;32438.189045273113;32546.349073017933;30528;37273.81363093167;38262;38979.61020389873;39780.40329395003 03:15:33 INFO - PROCESS | 3844 | |2;jquery.html;398.90226894410876;403.6407597238458;421.8460262004368;424.54785653279254;431.68918378767296;15279.847201527982;20096.196152153934;20561.177552897883;20630.99999999998;21568.89215553924;20448.795512044875;20453.590928181464;20537.178512859467;20545.58908821823;20669.1732330707;5627.746751396197;5663.122216056518;5778.248827652399;5810.506106980891;5820.068788993762;5674.3190817101795;5771.076627739562;5805.941940580604;5811.389804070575;5814.534837213013;7468.327850493454;7636.694532218705;7695.230476952316;7811.33603643691;7817.530948143101;7414.962925185367;7654.923450765476;7709.074911010686;7756.883646745303;7762.7671169865;7245.275472452765;7460.328570428687;7506.474546781727;7533.4349923755935;7542.773716788505;27788.58317125245;28229.01198458044;28766.712332876614;28949;29501.704982950327;2687.1804099749766;2859.0089956357865;2922.8100173488656;3498.247876706495;3569.678728914384;16239.188040597923;16272.267747951371;17420.477385678325;17574.472765817045;17942.641147177113;2136.658134698441;2240.137547044383;2240.20472732181;2304.781045800646;2305.988470057639;104642.47678761557;105407.47296263547;105772;109044.4547777264;112178.43910780393;18340.908295458437;18878.640169978753;19106.71339929902;19499;19876.70184947227;35127.4730879037;35437.29125417529;35618.465723014175;35861.82069089638;36125;10311.587536498457;10565.841512377321;10585.9470702646;10603.469826508644;10785.514651840529;8840.204381605558;8842.734717958472;8867.955660221658;8920.509371984603;9082.727518174466;9386.95306523463;9570.521473926276;9630.436738406834;9640.807183856283;9693.951530242304;3321.7674762766856;3366.141633883393;3497.9475307870403;3561.661642143992;3694.4088945769086;930.8836395450569;997.0777031246068;1014.6296601740312;1019.6788011776267;1153.135148638521;18883.811161888207;19531.121099550553;20861.89569052145;21140.894295528426;21446.78553214479;1005.4319309590059;1054.4253381906879;1063.5161001744189;1077.28898926708;1103.1340397787699;11090.833637495443;11663;11671.241369310968;11724.413779311;11738.765224695459;179917;183318;207656;591160;592511;59891;76465.61767191239;79544.60227698824;90848;91523.54238228766;1062.7768168684663;1082.880883102858;1087.1194332590626;1095.5782023920847;1113.365381732421;18488;18689.71965420547;18803;18825.90587047111;19193.232270709574;37878.8106059468;41528.58471415247;43232.567674322854;44462.55537444713;47704.04591908212;62472.687636561524;63503.14235859305;63693.04460433005;63829;64665;8834.337424693045;9146.405483643543;9223.538823058954;9297.72106836796;9371.859422108537;10088.568711696218;12851.228926264454;13456.932715336361;13835.308234588028;13908.443662253396;11808.645740627795;12006.159568830099;12163.695907602381;12183;12184;11175.38535380562;11561.42192890369;11694.4737486815;11725.120615953667;11923.10576706733;6438.8390290243115;8299.377546683902;11837.697853235964;12211.938940305241;12219.75560488803;9064.274858011475;9090.090990900857;9106.772330691785;9122.08779122109;9267.814643707223;9012.594433250651;11323.377214252965;11633.825492617449;11927.165098443025;12072.39638018078 03:15:33 INFO - PROCESS | 3844 | |3;mootools.html;1534.9999999999995;1593.816711078226;1802.7476153338528;1862.1434140295464;1903.4860587641347;9387;10105.090541851232;10105.747356316087;10105.848412273803;10110.191184705234;8517;9329.626814927395;9524.047595240472;9544.04559544047;9861.161801246884;2518.282289547479;2550.8979640814437;2553.3616595851036;2556.322574517754;2556.7187609362954;2498.5127900059497;2563.6667233259745;2566.8074894382903;2568.4477837264985;2569.8586577738215;2549.477357141791;2552.3874270175115;2559.296193546771;2562.0392352867675;2566.5765148750434;2478.2193609013193;2490.0164435048146;2507.987460062697;2547.5159719653207;2548.8980440782348;2502.912398066077;2512.567836333291;2513.3465299022205;2517.980218011708;2526.621006848969;9980.650677226335;10069.093781559695;10078.344907580984;10088.344257623232;10093.444860532667;5636.182753500761;5783.2192653992015;5813.418658134195;5854.97072514639;5859.472647461749;3352.3295340931913;3430.9313813723834;3478.269563391691;3484.8431820568135;3492.5598285688575;1023.247912784103;1033.3180101133291;1036.5439206749081;1039.8544203811473;1066.5147357952112;14354.066985646004;14382.856171438256;14429.422823087063;14432.711345773236;14463.855361446356;4850.442199147144;5002.849914502571;5008.949910500921;5107.61692873036;5108.872278193001;11806.645800625995;11848.70378240551;11870.406479675981;11873.584424545099;11877.940610296893;5827.737751801179;5851.356350801409;5859.707014649251;5867.765289388377;5872.706364681749;4683.274092515598;4767.61859051275;5327.840164795062;5328.49379308965;5348.705821179872;4389.012472193731;4390.978045109754;4494.213512635341;4524.977375113103;4526.207913615104;1357.592722183341;1363.4478036395276;1364.1951248763278;1368.2132773655187;1387.8820300274388;1060.1359891688248;1089.3899416326883;1091.1543553745753;1091.514276147107;1097.8078836203633;6554.442872355808;6642.800715978528;6760.323967603299;6774.559653622598;6941.826454338581;484.1503161950754;485.9368282123301;491.87703074231445;499.66023104289434;503.22000898607746;4534;4617.8152873884665;4653.790579423934;4676.532346765296;4727.929081063787;12025.759484810254;12489.313087780078;12502.49990000408;12550.43523041447;12583;12112.333821639719;12362.258264504158;12478.627350991384;12487.562935297221;12510.374481276081;9065.365424420359;9129.954350228207;9168.862467063;9204.263658907272;9224.907750922539;6584.8353791155605;6627.536072474881;6644.800655980328;6672.633005184762;6705.631190284581;11051.17116216271;11161.274517156358;11306.321620702784;11410.315381076829;11441.713957150805;9622.312492817424;9960.651377201762;11514.194006419806;11698.883011169779;11761.000314973324;8509.319254459751;8599.097094804956;8615.91384086151;8633.69782057625;8646.43798153118;9206.861897071416;9353.204977576983;9535.809283814146;9563.521823908915;9855;8390.790230244293;8703.564821759013;8783.824323513621;8788.209061184523;8875.955620221857;8243.958780206061;8572.314214862672;8621.439606425562;8707.3034157266;8801.38390312672;8148.796280093045;8159.714409995621;8243.134470880472;8243.216894395024;8253.174682531699;5892.9116063258225;5900.1149827526215;5926.2295901532525;5982.252218472691;5992.161097446448;5777.508911742549;5879.118132280192;5928.377520360473;5930.733117009831;5989.36106865669 03:15:33 INFO - PROCESS | 3844 | |4;prototype.html;352.9029516882858;358.20299832871865;359.0060576090988;361.5318162978942;367.81425380183003;5535.640183388077;5543.722813859306;5563.329601791138;5565.888682226353;5602.7758889644365;5432.9185062224;5456.945430545703;5508.366537848148;5517.5585953123755;5531.557475401968;4155.542890282074;4327.307630779061;4349.913001739979;4384.912301753963;4388.561143885618;4319.524852266264;4336.457942757155;4338.50107237667;4359.455068116486;4367.475902891641;4309.547497512747;4315.518576818326;4321.178975994567;4332.718373305741;4349.826006959734;4145.129522800215;4261.31818908973;4267.295896177127;4301.956980430187;4331.480222373304;3772.811359432045;3792.5069740933777;3795.2978698940783;3800.315943130263;3810.8094595270127;5344.492273234076;5351.919721204185;5405.648632838853;5417.26866872975;5434.456554344573;4671.27595222741;4830.879228019293;4887.291342755316;4957.801687932479;4964.702117872939;5570.470805273492;5617.691026993513;5631.690257035862;5633.8591535211535;5651.378348381675;4161.417401563784;4173.165366926596;4173.499180098377;4174.227767862955;4186.1418409226035;5842.415758424166;5892.23400957877;5898.675572843492;5970.910436343458;6009.399060094;4232.449781528381;4242.109157076987;4243.363495475642;4244.278472659656;4251.766152861623;4289.549597292302;4297;4299.1831552005015;4311.784410779448;4315.870523884289;4680.648951328664;4844.539768721966;4892.3884514435695;4911.3369695090705;4928.954483889146;5695.686737229409;5697.601167918206;5712.914306285409;5719.942800571941;5726.9713651431475;1477.298283315438;1502.0311898825196;1506.239349128683;1507.8492150785053;1509.0794615284667;918.6876462002817;926.5598840550684;946.2902822882838;970.9611615535441;981.1856159387692;4944.975275123601;4957.20684690455;4986.476419975923;5028.823991160365;5033.546980771748;5201.5838732901275;5211.635185536976;5256.894862102737;5288.867778305573;5289.3652761668845;4980.402351717745;5018.24726291052;5051.696898186047;5281.683099014072;5662.716864156776;3127.6872312768996;3133.0914034929615;3135.3102317490116;3143.827089510053;3151.16494129056;6058.818235452944;6081.5742897996715;6087.93461144298;6123.999240372185;6126.571140020245;5700.543956483471;5751.309842818889;5938.732757025944;5989.221401217901;6009.759609615654;5845.357010728816;6067.908981365283;6082.178905847654;6085.908711369333;6114.021756518936;5897.734601943009;5906.320773111147;5924.763009479572;5931.317898441562;5943.821685349447;5717.799877004285;5808.21589085485;5845.883082338414;5852.7658893643775;5878.823635290948;6056.848578785565;6086.7260973255425;6093.238345206849;6117.204763380732;6127.234095738033;4155.418241446139;4272.679549033897;4277.957220427756;4286.849960251377;4309.224339618773;5883.176355310175;5938.495227905676;5943.851403714942;5958.689088400545;5982.52139828804;5777.94222057774;5820.76716931318;5829.6793676346515;5903.7048147593305;5937.614055086406;5707.571932105192;5723.075396333209;5733.3406658234835;5775.364709881994;5853.239078919713;5713.542916566582;5733.942660573341;5739.655620662774;5740.167675686918;5742.569307301886;5573.108302671553;5806.332271788629;5856.560757943086;5871.647701137946;5883.764649414146;5593.720313984366;5650.208970744017;5677.403872593483;5691.601587888808;5695.206010106803 03:15:33 INFO - PROCESS | 3844 | |5;yui.html;743.5575832379735;781.5193655901622;795.2882170457437;817.3951276055718;832.9125441828608;16873.746893796604;17428.128593570334;17449.214785334658;17777.111144442773;17780.64438711225;16168.919155404235;16187.433439829609;16287.104209268486;16315.347386104571;16747.078910659908;749.1833901047856;877.3288434347729;894.2130924786212;912.6623149434686;917.2020342302206;915.5925613102139;923.866039424283;927.2906226736552;927.7263207353814;928.4846909964966;964.6575465709677;965.1844191658073;965.7006328038295;965.9227261819037;965.932384733069;777.3314949143731;926.3191554207652;959.1894848852709;960.5341409416462;960.6445615122415;692.0207905813319;700;700.6076597105637;700.8142842146835;700.9835738179609;16067.437639682556;16076.392360763946;16129.677406451923;16149.677006459922;16160.27278772458;15575.454859079879;15783.220344059831;15951.282192301374;16022.679546409006;16254.43109491162;13081.757233062845;13370.732585348238;13394.330283485884;13440.93279533596;13499.987500937468;4476.306172543261;4483.125790470859;4515.683902126852;4516.029053753458;4645.303204519315;83553;83697;84961;88990;89840.10159898383;11793.469293881795;11795.705107372385;11849.822252666218;11946.820797688042;11991.160618756603;23592.292231233092;23757.524849503257;23822.404439888796;23891.402714932265;23932.28203153908;8547.188017138362;8766.21104100634;9015.909840901639;9043.050479699668;9047.230985366186;4796.496367881392;4884.462709101996;4886.780094895738;5083.339165908408;5122.590192784643;3368.6799754023327;3470.548828652259;3481.9651803481643;3491.005063556874;3496.6328535503408;644.191539617786;645.0485533837552;651.8989556618732;658.3416583416583;689.7999580121708;744.181400459501;753.8756105242683;761.3604572159413;764.8508540834595;778.9260020298062;10049.949750251202;10374.325668831501;10541.736456588647;10832.079273261703;10882.564697412015;721.0698199322815;728.7667946257253;748.2517482517483;759.5139110968985;771.5679219637021;5128.306865186088;5171.896562068813;5183.429822719498;5261.210818377274;5331.866703332447;117211.41394292974;131773;131938;132487;135576.32211839076;112628.43685781682;115966;117112.41443792896;118721;119362.40318798351;58786.41213587895;60757.69621151954;96007.51996240114;101358;103685.48157259166;9760.463174525185;10034.949825250827;10401.42792146409;11349.375784331942;11784.646460606196;54707;55543;55653.72173139108;56007.43992560022;56476.152857706344;51158;61908.38091619026;76914.23085769295;77265.61367193128;77704.61147694226;13800.964927630266;13828.446862125402;14042.227677477837;14274;14872.553823385315;16426.917865411073;16535.338586456408;16882.155892205094;16940;18139.27442902269;15398.153101579073;15836.129012904401;15926.60183495422;16134.919325403298;16647.66704665924;13786;14504.419823207376;15071.397144114113;15449.304781284643;15812.841871581137;13878.583642490741;14362.497312594009;15092.54722358331;15274.389024438899;15281.235938203268;12985.28580928058;13065.477380905038;13198.670033248862;13659.317034148451;13763.243021633505;11941.46263418131;12668.619941401772;12896.677583060124;12986.805197922227;13002.349882506027 03:15:33 INFO - PROCESS | 3844 | __end_tp_report 03:15:33 INFO - PROCESS | 3844 | __start_cc_report 03:15:33 INFO - PROCESS | 3844 | _x_x_mozilla_cycle_collect,1027 03:15:33 INFO - PROCESS | 3844 | __end_cc_report 03:15:33 INFO - PROCESS | 3844 | __startTimestamp1471428933302__endTimestamp 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | ------- Summary: start ------- 03:15:33 INFO - PROCESS | 3844 | Number of tests: 6 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#0] dojo.html Cycles:165 Average:5592.84 Median:5851.27 stddev:633.22 (10.8%) stddev-sans-first:607.43 03:15:33 INFO - PROCESS | 3844 | Values: 3230.0 3241.3 3247.0 3247.4 3267.7 5533.1 5577.5 5584.6 5601.6 5602.0 5572.7 5644.4 5666.1 5669.3 5713.1 5026.8 5064.8 5087.8 5115.8 5157.1 5065.5 5157.1 5158.7 5169.2 5176.9 5074.3 5116.4 5153.7 5153.9 5159.3 5172.0 5191.0 5191.7 5193.2 5220.6 4943.5 5010.9 5076.4 5077.6 5088.1 5518.9 5556.3 5568.5 5626.6 5651.0 6067.8 6074.1 6082.0 6087.5 6088.2 6007.3 6036.1 6051.1 6071.0 6090.4 5607.1 5652.6 5659.3 5667.3 5668.5 6001.8 6147.6 6167.2 6176.4 6184.2 5809.7 5853.2 5870.4 5926.3 5930.3 5975.9 5984.3 6013.2 6013.3 6015.8 5752.1 5913.4 5952.4 5955.2 5969.6 5882.0 5905.1 5931.3 5963.3 5994.9 4629.6 4630.4 4630.5 4631.2 4662.1 4015.4 4125.1 4134.3 4144.1 4153.0 5662.0 5700.5 5706.1 5786.9 5810.3 5990.5 6021.6 6028.4 6035.4 6054.7 5743.1 5743.3 5746.7 5754.3 5756.8 5010.6 5092.6 5179.5 5182.7 5256.3 6200.7 6211.4 6213.8 6217.3 6218.4 5999.4 6009.3 6013.0 6016.2 6016.4 5943.0 6135.2 6155.2 6193.9 6274.5 5978.8 5980.4 5990.2 6005.5 6026.2 6094.4 6099.4 6105.3 6108.8 6110.4 5633.8 5852.4 5853.5 5868.8 5982.1 5912.4 5918.2 5918.7 5921.4 5923.8 5826.8 5839.0 5867.8 5886.6 5888.8 5830.4 5858.2 5937.8 5940.0 6034.5 5849.2 5851.3 5854.7 5856.4 5856.9 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#1] ext.html Cycles:180 Average:53618.69 Median:21864.25 stddev:97342.65 (445.2%) stddev-sans-first:97534.32 03:15:33 INFO - PROCESS | 3844 | Values: 597.3 606.6 606.8 626.7 634.7 36052.1 38427.8 46491.8 47670.3 47809.8 49135.3 49523.3 49543.3 49612.5 49671.8 9378.8 9536.2 9546.9 9547.1 9549.6 8708.2 9375.9 11132.8 11356.8 11369.9 11521.9 11596.0 11606.2 11614.7 11629.4 11372.2 11411.8 11426.4 11440.9 11476.3 11242.7 11322.5 11322.7 11325.3 11337.7 45324.1 45353.8 45450.8 45582.5 45640.8 3767.6 3789.5 4437.3 4450.7 4535.3 35151.6 35245.6 35666.5 35975.5 36281.3 3904.3 3920.1 3948.1 4005.3 4012.8 405300.0 407339.0 407652.0 408180.0 408678.0 38703.6 60871.0 61321.1 61357.7 61371.4 39114.4 41643.6 41668.8 41824.4 41842.0 15896.5 16021.4 16022.0 16027.1 16030.0 11992.2 13138.4 13186.7 13225.9 13233.1 9148.8 9382.8 9441.8 9469.3 9545.3 3537.2 3561.4 3602.8 3605.3 3620.3 1149.6 1159.2 1192.3 1241.3 1242.0 35708.3 35956.5 36079.5 36141.3 36189.0 1168.4 1184.7 1229.3 1241.2 1263.6 18385.7 18475.6 18626.0 18637.3 18779.3 171877.1 173127.0 173306.1 173717.0 174321.0 43880.3 44042.6 44146.3 44210.0 44272.3 1317.2 1398.2 1443.8 1453.1 1459.4 35343.3 37251.8 37333.0 37336.6 37562.6 54627.7 55242.4 55285.0 55324.4 55326.2 456680.0 457022.0 458526.0 458808.0 459109.0 51471.2 51768.5 52149.0 52284.2 52798.5 52268.2 52869.7 56391.7 57102.7 57213.0 45367.3 46593.6 48384.3 48524.0 49499.8 47480.5 48265.3 48345.5 48897.3 49042.5 47004.3 47115.0 47764.3 48633.8 48649.8 30435.7 32003.4 32275.0 32438.2 32546.3 30528.0 37273.8 38262.0 38979.6 39780.4 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#2] jquery.html Cycles:180 Average:27444.93 Median:15544.01 stddev:67808.83 (436.2%) stddev-sans-first:67968.65 03:15:33 INFO - PROCESS | 3844 | Values: 398.9 403.6 421.8 424.5 431.7 15279.8 20096.2 20561.2 20631.0 21568.9 20448.8 20453.6 20537.2 20545.6 20669.2 5627.7 5663.1 5778.2 5810.5 5820.1 5674.3 5771.1 5805.9 5811.4 5814.5 7468.3 7636.7 7695.2 7811.3 7817.5 7415.0 7654.9 7709.1 7756.9 7762.8 7245.3 7460.3 7506.5 7533.4 7542.8 27788.6 28229.0 28766.7 28949.0 29501.7 2687.2 2859.0 2922.8 3498.2 3569.7 16239.2 16272.3 17420.5 17574.5 17942.6 2136.7 2240.1 2240.2 2304.8 2306.0 104642.5 105407.5 105772.0 109044.5 112178.4 18340.9 18878.6 19106.7 19499.0 19876.7 35127.5 35437.3 35618.5 35861.8 36125.0 10311.6 10565.8 10585.9 10603.5 10785.5 8840.2 8842.7 8868.0 8920.5 9082.7 9387.0 9570.5 9630.4 9640.8 9694.0 3321.8 3366.1 3497.9 3561.7 3694.4 930.9 997.1 1014.6 1019.7 1153.1 18883.8 19531.1 20861.9 21140.9 21446.8 1005.4 1054.4 1063.5 1077.3 1103.1 11090.8 11663.0 11671.2 11724.4 11738.8 179917.0 183318.0 207656.0 591160.0 592511.0 59891.0 76465.6 79544.6 90848.0 91523.5 1062.8 1082.9 1087.1 1095.6 1113.4 18488.0 18689.7 18803.0 18825.9 19193.2 37878.8 41528.6 43232.6 44462.6 47704.0 62472.7 63503.1 63693.0 63829.0 64665.0 8834.3 9146.4 9223.5 9297.7 9371.9 10088.6 12851.2 13456.9 13835.3 13908.4 11808.6 12006.2 12163.7 12183.0 12184.0 11175.4 11561.4 11694.5 11725.1 11923.1 6438.8 8299.4 11837.7 12211.9 12219.8 9064.3 9090.1 9106.8 9122.1 9267.8 9012.6 11323.4 11633.8 11927.2 12072.4 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#3] mootools.html Cycles:180 Average:6482.41 Median:4530.10 stddev:3796.08 (83.8%) stddev-sans-first:3788.53 03:15:33 INFO - PROCESS | 3844 | Values: 1535.0 1593.8 1802.7 1862.1 1903.5 9387.0 10105.1 10105.7 10105.8 10110.2 8517.0 9329.6 9524.0 9544.0 9861.2 2518.3 2550.9 2553.4 2556.3 2556.7 2498.5 2563.7 2566.8 2568.4 2569.9 2549.5 2552.4 2559.3 2562.0 2566.6 2478.2 2490.0 2508.0 2547.5 2548.9 2502.9 2512.6 2513.3 2518.0 2526.6 9980.7 10069.1 10078.3 10088.3 10093.4 5636.2 5783.2 5813.4 5855.0 5859.5 3352.3 3430.9 3478.3 3484.8 3492.6 1023.2 1033.3 1036.5 1039.9 1066.5 14354.1 14382.9 14429.4 14432.7 14463.9 4850.4 5002.8 5008.9 5107.6 5108.9 11806.6 11848.7 11870.4 11873.6 11877.9 5827.7 5851.4 5859.7 5867.8 5872.7 4683.3 4767.6 5327.8 5328.5 5348.7 4389.0 4391.0 4494.2 4525.0 4526.2 1357.6 1363.4 1364.2 1368.2 1387.9 1060.1 1089.4 1091.2 1091.5 1097.8 6554.4 6642.8 6760.3 6774.6 6941.8 484.2 485.9 491.9 499.7 503.2 4534.0 4617.8 4653.8 4676.5 4727.9 12025.8 12489.3 12502.5 12550.4 12583.0 12112.3 12362.3 12478.6 12487.6 12510.4 9065.4 9130.0 9168.9 9204.3 9224.9 6584.8 6627.5 6644.8 6672.6 6705.6 11051.2 11161.3 11306.3 11410.3 11441.7 9622.3 9960.7 11514.2 11698.9 11761.0 8509.3 8599.1 8615.9 8633.7 8646.4 9206.9 9353.2 9535.8 9563.5 9855.0 8390.8 8703.6 8783.8 8788.2 8876.0 8244.0 8572.3 8621.4 8707.3 8801.4 8148.8 8159.7 8243.1 8243.2 8253.2 5892.9 5900.1 5926.2 5982.3 5992.2 5777.5 5879.1 5928.4 5930.7 5989.4 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#4] prototype.html Cycles:180 Average:4817.27 Median:5482.66 stddev:1402.55 (25.6%) stddev-sans-first:1365.87 03:15:33 INFO - PROCESS | 3844 | Values: 352.9 358.2 359.0 361.5 367.8 5535.6 5543.7 5563.3 5565.9 5602.8 5432.9 5456.9 5508.4 5517.6 5531.6 4155.5 4327.3 4349.9 4384.9 4388.6 4319.5 4336.5 4338.5 4359.5 4367.5 4309.5 4315.5 4321.2 4332.7 4349.8 4145.1 4261.3 4267.3 4302.0 4331.5 3772.8 3792.5 3795.3 3800.3 3810.8 5344.5 5351.9 5405.6 5417.3 5434.5 4671.3 4830.9 4887.3 4957.8 4964.7 5570.5 5617.7 5631.7 5633.9 5651.4 4161.4 4173.2 4173.5 4174.2 4186.1 5842.4 5892.2 5898.7 5970.9 6009.4 4232.4 4242.1 4243.4 4244.3 4251.8 4289.5 4297.0 4299.2 4311.8 4315.9 4680.6 4844.5 4892.4 4911.3 4929.0 5695.7 5697.6 5712.9 5719.9 5727.0 1477.3 1502.0 1506.2 1507.8 1509.1 918.7 926.6 946.3 971.0 981.2 4945.0 4957.2 4986.5 5028.8 5033.5 5201.6 5211.6 5256.9 5288.9 5289.4 4980.4 5018.2 5051.7 5281.7 5662.7 3127.7 3133.1 3135.3 3143.8 3151.2 6058.8 6081.6 6087.9 6124.0 6126.6 5700.5 5751.3 5938.7 5989.2 6009.8 5845.4 6067.9 6082.2 6085.9 6114.0 5897.7 5906.3 5924.8 5931.3 5943.8 5717.8 5808.2 5845.9 5852.8 5878.8 6056.8 6086.7 6093.2 6117.2 6127.2 4155.4 4272.7 4278.0 4286.8 4309.2 5883.2 5938.5 5943.9 5958.7 5982.5 5777.9 5820.8 5829.7 5903.7 5937.6 5707.6 5723.1 5733.3 5775.4 5853.2 5713.5 5733.9 5739.7 5740.2 5742.6 5573.1 5806.3 5856.6 5871.6 5883.8 5593.7 5650.2 5677.4 5691.6 5695.2 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - PROCESS | 3844 | [#5] yui.html Cycles:180 Average:22635.34 Median:13650.48 stddev:32949.31 (241.4%) stddev-sans-first:33000.74 03:15:33 INFO - PROCESS | 3844 | Values: 743.6 781.5 795.3 817.4 832.9 16873.7 17428.1 17449.2 17777.1 17780.6 16168.9 16187.4 16287.1 16315.3 16747.1 749.2 877.3 894.2 912.7 917.2 915.6 923.9 927.3 927.7 928.5 964.7 965.2 965.7 965.9 965.9 777.3 926.3 959.2 960.5 960.6 692.0 700.0 700.6 700.8 701.0 16067.4 16076.4 16129.7 16149.7 16160.3 15575.5 15783.2 15951.3 16022.7 16254.4 13081.8 13370.7 13394.3 13440.9 13500.0 4476.3 4483.1 4515.7 4516.0 4645.3 83553.0 83697.0 84961.0 88990.0 89840.1 11793.5 11795.7 11849.8 11946.8 11991.2 23592.3 23757.5 23822.4 23891.4 23932.3 8547.2 8766.2 9015.9 9043.1 9047.2 4796.5 4884.5 4886.8 5083.3 5122.6 3368.7 3470.5 3482.0 3491.0 3496.6 644.2 645.0 651.9 658.3 689.8 744.2 753.9 761.4 764.9 778.9 10049.9 10374.3 10541.7 10832.1 10882.6 721.1 728.8 748.3 759.5 771.6 5128.3 5171.9 5183.4 5261.2 5331.9 117211.4 131773.0 131938.0 132487.0 135576.3 112628.4 115966.0 117112.4 118721.0 119362.4 58786.4 60757.7 96007.5 101358.0 103685.5 9760.5 10034.9 10401.4 11349.4 11784.6 54707.0 55543.0 55653.7 56007.4 56476.2 51158.0 61908.4 76914.2 77265.6 77704.6 13801.0 13828.4 14042.2 14274.0 14872.6 16426.9 16535.3 16882.2 16940.0 18139.3 15398.2 15836.1 15926.6 16134.9 16647.7 13786.0 14504.4 15071.4 15449.3 15812.8 13878.6 14362.5 15092.5 15274.4 15281.2 12985.3 13065.5 13198.7 13659.3 13763.2 11941.5 12668.6 12896.7 12986.8 13002.3 03:15:33 INFO - PROCESS | 3844 | -------- Summary: end -------- 03:15:33 INFO - PROCESS | 3844 | 03:15:33 INFO - TEST-INFO | 3844: exit 0 03:15:33 INFO - TEST-OK | dromaeo_css | took 1113297ms 03:15:33 INFO - TEST-START | kraken 03:15:33 INFO - operating with platform_type : linux_ 03:15:33 INFO - Initialising browser for kraken test... 03:15:33 INFO - TEST-INFO | started process 4044 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMQc9y1/profile http://localhost:48584/getInfo.html) 03:15:38 INFO - PROCESS | 4044 | __metrics Screen width/height:1600/1200 03:15:38 INFO - PROCESS | 4044 | colorDepth:24 03:15:38 INFO - PROCESS | 4044 | Browser inner width/height: 1024/697 03:15:38 INFO - PROCESS | 4044 | __metrics 03:15:39 INFO - PROCESS | 4044 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:15:39 INFO - PROCESS | 4044 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:15:40 INFO - TEST-INFO | 4044: exit 0 03:15:40 INFO - Browser initialized. 03:15:40 INFO - Running cycle 1/1 for kraken test... 03:15:40 INFO - TEST-INFO | started process 4133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMQc9y1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:15:51 INFO - PROCESS | 4133 | RSS: Main: 145342464 03:15:51 INFO - PROCESS | 4133 | 03:15:54 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48584/tests/kraken/driver.html?testName=audio-beat-detection) 03:15:55 INFO - PROCESS | 4133 | RSS: Main: 191086592 03:15:55 INFO - PROCESS | 4133 | 03:15:57 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48584/tests/kraken/driver.html?testName=audio-dft) 03:15:58 INFO - PROCESS | 4133 | RSS: Main: 180793344 03:15:58 INFO - PROCESS | 4133 | 03:16:01 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48584/tests/kraken/driver.html?testName=audio-fft) 03:16:01 INFO - PROCESS | 4133 | RSS: Main: 270393344 03:16:01 INFO - PROCESS | 4133 | 03:16:03 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48584/tests/kraken/driver.html?testName=audio-oscillator) 03:16:04 INFO - PROCESS | 4133 | RSS: Main: 174714880 03:16:04 INFO - PROCESS | 4133 | 03:16:06 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48584/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:16:07 INFO - PROCESS | 4133 | RSS: Main: 177037312 03:16:07 INFO - PROCESS | 4133 | 03:16:10 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48584/tests/kraken/driver.html?testName=imaging-darkroom) 03:16:10 INFO - PROCESS | 4133 | RSS: Main: 213172224 03:16:10 INFO - PROCESS | 4133 | 03:16:14 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48584/tests/kraken/driver.html?testName=imaging-desaturate) 03:16:14 INFO - PROCESS | 4133 | RSS: Main: 211963904 03:16:14 INFO - PROCESS | 4133 | 03:16:17 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48584/tests/kraken/driver.html?testName=json-parse-financial) 03:16:18 INFO - PROCESS | 4133 | RSS: Main: 211726336 03:16:18 INFO - PROCESS | 4133 | 03:16:19 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48584/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:16:20 INFO - PROCESS | 4133 | RSS: Main: 175255552 03:16:20 INFO - PROCESS | 4133 | 03:16:21 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:16:22 INFO - PROCESS | 4133 | RSS: Main: 181374976 03:16:22 INFO - PROCESS | 4133 | 03:16:24 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:16:25 INFO - PROCESS | 4133 | RSS: Main: 185597952 03:16:25 INFO - PROCESS | 4133 | 03:16:27 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:16:28 INFO - PROCESS | 4133 | RSS: Main: 180719616 03:16:28 INFO - PROCESS | 4133 | 03:16:30 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:16:31 INFO - PROCESS | 4133 | RSS: Main: 183885824 03:16:31 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | Cycle 1(1): loaded http://localhost:48584/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48584/tests/kraken/driver.html?testName=ai-astar) 03:16:33 INFO - PROCESS | 4133 | RSS: Main: 235237376 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | __start_tp_report 03:16:33 INFO - PROCESS | 4133 | _x_x_mozilla_page_load 03:16:33 INFO - PROCESS | 4133 | _x_x_mozilla_page_load_details 03:16:33 INFO - PROCESS | 4133 | |i|pagename|runs| 03:16:33 INFO - PROCESS | 4133 | |0;ai-astar;100;109;109;108;110;109;109;108;107;108 03:16:33 INFO - PROCESS | 4133 | |1;audio-beat-detection;142;114;122;177;117;121;151;117;154;117 03:16:33 INFO - PROCESS | 4133 | |2;audio-dft;161;161;162;161;161;163;160;161;160;160 03:16:33 INFO - PROCESS | 4133 | |3;audio-fft;76;75;75;114;76;76;75;75;111;75 03:16:33 INFO - PROCESS | 4133 | |4;audio-oscillator;84;84;129;84;83;84;124;84;83;131 03:16:33 INFO - PROCESS | 4133 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;116;117 03:16:33 INFO - PROCESS | 4133 | |6;imaging-darkroom;146;146;146;148;147;145;146;146;146;146 03:16:33 INFO - PROCESS | 4133 | |7;imaging-desaturate;94;94;95;95;95;96;94;94;94;94 03:16:33 INFO - PROCESS | 4133 | |8;json-parse-financial;50;50;50;50;49;49;54;49;49;50 03:16:33 INFO - PROCESS | 4133 | |9;json-stringify-tinderbox;58;59;58;59;60;58;58;59;59;59 03:16:33 INFO - PROCESS | 4133 | |10;stanford-crypto-aes;73;74;73;72;73;73;79;71;72;72 03:16:33 INFO - PROCESS | 4133 | |11;stanford-crypto-ccm;120;129;123;131;118;125;120;116;157;129 03:16:33 INFO - PROCESS | 4133 | |12;stanford-crypto-pbkdf2;150;155;152;155;155;154;157;152;151;153 03:16:33 INFO - PROCESS | 4133 | |13;stanford-crypto-sha256-iterative;60;62;65;61;62;61;61;61;60;59 03:16:33 INFO - PROCESS | 4133 | __end_tp_report 03:16:33 INFO - PROCESS | 4133 | __start_cc_report 03:16:33 INFO - PROCESS | 4133 | _x_x_mozilla_cycle_collect,1645 03:16:33 INFO - PROCESS | 4133 | __end_cc_report 03:16:33 INFO - PROCESS | 4133 | __startTimestamp1471428993254__endTimestamp 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | ------- Summary: start ------- 03:16:33 INFO - PROCESS | 4133 | Number of tests: 14 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#0] ai-astar Cycles:10 Average:107.70 Median:109.00 stddev:2.83 (2.6%) stddev-sans-first:0.88 03:16:33 INFO - PROCESS | 4133 | Values: 100.0 109.0 109.0 108.0 110.0 109.0 109.0 108.0 107.0 108.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:132.00 stddev:21.54 (16.3%) stddev-sans-first:22.61 03:16:33 INFO - PROCESS | 4133 | Values: 142.0 114.0 122.0 177.0 117.0 121.0 151.0 117.0 154.0 117.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 03:16:33 INFO - PROCESS | 4133 | Values: 161.0 161.0 162.0 161.0 161.0 163.0 160.0 161.0 160.0 160.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:15.68 (20.9%) stddev-sans-first:16.43 03:16:33 INFO - PROCESS | 4133 | Values: 76.0 75.0 75.0 114.0 76.0 76.0 75.0 75.0 111.0 75.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:84.00 stddev:21.46 (25.6%) stddev-sans-first:22.24 03:16:33 INFO - PROCESS | 4133 | Values: 84.0 84.0 129.0 84.0 83.0 84.0 124.0 84.0 83.0 131.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 03:16:33 INFO - PROCESS | 4133 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 03:16:33 INFO - PROCESS | 4133 | Values: 146.0 146.0 146.0 148.0 147.0 145.0 146.0 146.0 146.0 146.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 03:16:33 INFO - PROCESS | 4133 | Values: 94.0 94.0 95.0 95.0 95.0 96.0 94.0 94.0 94.0 94.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.49 (3.0%) stddev-sans-first:1.58 03:16:33 INFO - PROCESS | 4133 | Values: 50.0 50.0 50.0 50.0 49.0 49.0 54.0 49.0 49.0 50.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 03:16:33 INFO - PROCESS | 4133 | Values: 58.0 59.0 58.0 59.0 60.0 58.0 58.0 59.0 59.0 59.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:2.20 (3.0%) stddev-sans-first:2.33 03:16:33 INFO - PROCESS | 4133 | Values: 73.0 74.0 73.0 72.0 73.0 73.0 79.0 71.0 72.0 72.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:127.00 stddev:11.75 (9.3%) stddev-sans-first:12.21 03:16:33 INFO - PROCESS | 4133 | Values: 120.0 129.0 123.0 131.0 118.0 125.0 120.0 116.0 157.0 129.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.50 stddev:2.17 (1.4%) stddev-sans-first:1.92 03:16:33 INFO - PROCESS | 4133 | Values: 150.0 155.0 152.0 155.0 155.0 154.0 157.0 152.0 151.0 153.0 03:16:33 INFO - PROCESS | 4133 | 03:16:33 INFO - PROCESS | 4133 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.62 (2.7%) stddev-sans-first:1.66 03:16:33 INFO - PROCESS | 4133 | Values: 60.0 62.0 65.0 61.0 62.0 61.0 61.0 61.0 60.0 59.0 03:16:33 INFO - PROCESS | 4133 | -------- Summary: end -------- 03:16:33 INFO - PROCESS | 4133 | 03:16:34 INFO - TEST-INFO | 4133: exit 0 03:16:34 INFO - TEST-OK | kraken | took 60321ms 03:16:34 INFO - SUITE-END | took 1173s 03:16:34 INFO - Completed test suite (00:19:33) 03:16:34 INFO - javascript benchmark 03:16:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.0309907027895, 3241.2707140893317, 3247.009662053073, 3247.4154652162615, 3267.722243609293, 5533.059379905416, 5577.470140336672, 5584.6090773645865, 5601.6358936669085, 5602.019646561856, 5572.693501857407, 5644.350899646532, 5666.121751128571, 5669.262995810539, 5713.14302854572, 5026.84919452417, 5064.79740810369, 5087.847364579059, 5115.795368185268, 5157.149070403379, 5065.493450654942, 5157.149070403379, 5158.74206289684, 5169.1987741899875, 5176.896462070757, 5074.264231686403, 5116.386033675964, 5153.665011774241, 5153.896922061556, 5159.277701121827, 5171.974140129294, 5190.974045129769, 5191.662541934762, 5193.221016847503, 5220.556252718524, 4943.480934501861, 5010.924836127425, 5076.416212135615, 5077.619178561623, 5088.0587091388215, 5518.91721624176, 5556.305461817273, 5568.52667523257, 5626.634268772516, 5650.971745141289, 6067.817965461043, 6074.088886667036, 6082.026875699867, 6087.4825639820665, 6088.238970128734, 6007.309159446676, 6036.0644100164545, 6051.0923361496125, 6071.028635418356, 6090.42140996609, 5607.102863541813, 5652.63257888236, 5659.3208814941645, 5667.3482549507335, 5668.518175955008, 6001.849953751192, 6147.600405973687, 6167.229096362955, 6176.382361763787, 6184.196054512884, 5809.7095145242565, 5853.151293062568, 5870.442307980735, 5926.259217597801, 5930.258717660293, 5975.850603734942, 5984.251968503937, 6013.188219590299, 6013.338532761392, 6015.759369625166, 5752.079667253303, 5913.408659134052, 5952.404759524013, 5955.166276721263, 5969.611975221684, 5882.0, 5905.055191169392, 5931.347551769302, 5963.314218864886, 5994.880102397928, 4629.560191781776, 4630.4443466784205, 4630.467496237909, 4631.212693842055, 4662.067586482649, 4015.377616469452, 4125.1131006833675, 4134.276501612206, 4144.088300573869, 4153.02403935075, 5662.0, 5700.458456446631, 5706.058500347396, 5786.88426231473, 5810.273715785527, 5990.520758339322, 6021.57848950578, 6028.4272994065495, 6035.426634469718, 6054.697265136726, 5743.10981797822, 5743.282089738783, 5746.712664366765, 5754.309482862083, 5756.798512052058, 5010.64925455222, 5092.643514953992, 5179.45615710345, 5182.689038657692, 5256.316678831728, 6200.689965501797, 6211.378862113843, 6213.844653883689, 6217.285012223561, 6218.440340369298, 5999.430054144849, 6009.338972713085, 6013.0, 6016.187814644967, 6016.368281330572, 5943.0, 6135.202423684892, 6155.230596175478, 6193.938060619336, 6274.529410294155, 5978.790742323998, 5980.43185897339, 5990.191324171181, 6005.549583781147, 6026.15633811258, 6094.39056094387, 6099.420555047263, 6105.267367915879, 6108.786192483241, 6110.388961103854, 5633.8309850704545, 5852.4147585241135, 5853.473187413153, 5868.823935281948, 5982.0727787193055, 5912.379200183918, 5918.23063001807, 5918.674472904032, 5921.407859214044, 5923.7926672566255, 5826.796062137974, 5839.007368747408, 5867.823965281048, 5886.558508111994, 5888.793892213752, 5830.4461076197695, 5858.179854820243, 5937.821865344046, 5940.019896716994, 6034.547408944434, 5849.151872978479, 5851.268591426072, 5854.677992710272, 5856.385079566755, 5856.853578660568], "value": 5550.004576633173, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.3130899465614, 606.6208619612742, 606.8361542383557, 626.6553395632407, 634.6731433311845, 36052.09869753254, 38427.80786096069, 46491.76754116225, 47670.284945725754, 47809.760951195196, 49135.26297105546, 49523.25715114276, 49543.256851147264, 49612.50387496124, 49671.75164124183, 9378.765530861732, 9536.189423898977, 9546.904530954705, 9547.14075733184, 9549.570269337897, 8708.21626053655, 9375.859362109573, 11132.833007504894, 11356.829647555292, 11369.943150284236, 11521.942390288037, 11596.0, 11606.187566870323, 11614.6515604532, 11629.360385178812, 11372.203945723802, 11411.771764564704, 11426.428678566039, 11440.885591144066, 11476.311421314706, 11242.66272011841, 11322.490487928064, 11322.660320190407, 11325.32048077118, 11337.716557086056, 45324.093518129455, 45353.773231133964, 45450.77274613639, 45582.54417455816, 45640.77179614114, 3767.6420740029926, 3789.488419063419, 4437.334399840018, 4450.732956022649, 4535.31970204472, 35151.64848351509, 35245.647543524494, 35666.465003024976, 35975.4603680945, 36281.27437451263, 3904.3362628352966, 3920.137569734654, 3948.0919388540797, 4005.2790497710403, 4012.779297138656, 405300.0, 407339.0, 407651.99999999703, 408180.0, 408678.0, 38703.61296387056, 60871.0, 61321.08018379639, 61357.69321153455, 61371.38628613657, 39114.413283800764, 41643.58356416397, 41668.79165604213, 41824.37263441051, 41842.0, 15896.523104306889, 16021.359145634044, 16022.0, 16027.118508481912, 16030.0, 11992.220505667105, 13138.4087716053, 13186.736265274641, 13225.867741322656, 13233.139845910178, 9148.817023659489, 9382.765430864283, 9441.811163776685, 9469.289803264644, 9545.331826772059, 3537.1864471171775, 3561.4479755637917, 3602.765820221677, 3605.296967091392, 3620.2940426617083, 1149.6378640728144, 1159.2349049627205, 1192.3203773848822, 1241.267652085274, 1242.0, 35708.28583428317, 35956.460653090224, 36079.4588081179, 36141.27717445689, 36189.0, 1168.3515648814819, 1184.7275126720901, 1229.2562999385423, 1241.1746188784539, 1263.6145975477568, 18385.724214136797, 18475.630487390175, 18626.0, 18637.347692830685, 18779.34272300463, 171877.14061429864, 173127.0, 173306.13346933437, 173717.0, 174321.0, 43880.3417948731, 44042.559574403844, 44146.337804932955, 44210.0, 44272.3359149613, 1317.157019507516, 1398.189050350794, 1443.7545617244884, 1453.14264583896, 1459.3505889879114, 35343.29313413769, 37251.81374093221, 37333.0, 37336.626633733315, 37562.62437375591, 54627.72686136703, 55242.44757552373, 55285.0, 55324.44675553193, 55326.17010744922, 456680.0, 457022.0, 458526.0, 458808.0, 459109.0, 51471.22793158031, 51768.48231517636, 52149.0, 52284.21573676474, 52798.47201527935, 52268.21597676114, 52869.7356513215, 56391.71804140953, 57102.7144864273, 57213.0, 45367.31949020701, 46593.6021919343, 48384.27423588719, 48524.0, 49499.752501237264, 47480.52519474761, 48265.276020860416, 48345.51654483551, 48897.266541002624, 49042.509574903794, 47004.29493557531, 47115.0, 47764.2835357463, 48633.75683121703, 48649.75675121602, 30435.695643043287, 32003.35993280168, 32275.0, 32438.189045273113, 32546.349073017933, 30528.0, 37273.81363093167, 38262.0, 38979.61020389873, 39780.40329395003], "value": 20354.32485169805, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.90226894410876, 403.6407597238458, 421.8460262004368, 424.54785653279254, 431.68918378767296, 15279.847201527982, 20096.196152153934, 20561.177552897883, 20630.99999999998, 21568.89215553924, 20448.795512044875, 20453.590928181464, 20537.178512859467, 20545.58908821823, 20669.1732330707, 5627.746751396197, 5663.122216056518, 5778.248827652399, 5810.506106980891, 5820.068788993762, 5674.3190817101795, 5771.076627739562, 5805.941940580604, 5811.389804070575, 5814.534837213013, 7468.327850493454, 7636.694532218705, 7695.230476952316, 7811.33603643691, 7817.530948143101, 7414.962925185367, 7654.923450765476, 7709.074911010686, 7756.883646745303, 7762.7671169865, 7245.275472452765, 7460.328570428687, 7506.474546781727, 7533.4349923755935, 7542.773716788505, 27788.58317125245, 28229.01198458044, 28766.712332876614, 28949.0, 29501.704982950327, 2687.1804099749766, 2859.0089956357865, 2922.8100173488656, 3498.247876706495, 3569.678728914384, 16239.188040597923, 16272.267747951371, 17420.477385678325, 17574.472765817045, 17942.641147177113, 2136.658134698441, 2240.137547044383, 2240.20472732181, 2304.781045800646, 2305.988470057639, 104642.47678761557, 105407.47296263547, 105772.0, 109044.4547777264, 112178.43910780393, 18340.908295458437, 18878.640169978753, 19106.71339929902, 19499.0, 19876.70184947227, 35127.4730879037, 35437.29125417529, 35618.465723014175, 35861.82069089638, 36125.0, 10311.587536498457, 10565.841512377321, 10585.9470702646, 10603.469826508644, 10785.514651840529, 8840.204381605558, 8842.734717958472, 8867.955660221658, 8920.509371984603, 9082.727518174466, 9386.95306523463, 9570.521473926276, 9630.436738406834, 9640.807183856283, 9693.951530242304, 3321.7674762766856, 3366.141633883393, 3497.9475307870403, 3561.661642143992, 3694.4088945769086, 930.8836395450569, 997.0777031246068, 1014.6296601740312, 1019.6788011776267, 1153.135148638521, 18883.811161888207, 19531.121099550553, 20861.89569052145, 21140.894295528426, 21446.78553214479, 1005.4319309590059, 1054.4253381906879, 1063.5161001744189, 1077.28898926708, 1103.1340397787699, 11090.833637495443, 11663.0, 11671.241369310968, 11724.413779311, 11738.765224695459, 179917.0, 183318.0, 207656.0, 591160.0, 592511.0, 59891.0, 76465.61767191239, 79544.60227698824, 90848.0, 91523.54238228766, 1062.7768168684663, 1082.880883102858, 1087.1194332590626, 1095.5782023920847, 1113.365381732421, 18488.0, 18689.71965420547, 18803.0, 18825.90587047111, 19193.232270709574, 37878.8106059468, 41528.58471415247, 43232.567674322854, 44462.55537444713, 47704.04591908212, 62472.687636561524, 63503.14235859305, 63693.04460433005, 63829.0, 64665.0, 8834.337424693045, 9146.405483643543, 9223.538823058954, 9297.72106836796, 9371.859422108537, 10088.568711696218, 12851.228926264454, 13456.932715336361, 13835.308234588028, 13908.443662253396, 11808.645740627795, 12006.159568830099, 12163.695907602381, 12183.0, 12184.0, 11175.38535380562, 11561.42192890369, 11694.4737486815, 11725.120615953667, 11923.10576706733, 6438.8390290243115, 8299.377546683902, 11837.697853235964, 12211.938940305241, 12219.75560488803, 9064.274858011475, 9090.090990900857, 9106.772330691785, 9122.08779122109, 9267.814643707223, 9012.594433250651, 11323.377214252965, 11633.825492617449, 11927.165098443025, 12072.39638018078], "value": 10745.556324223353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.9999999999995, 1593.816711078226, 1802.7476153338528, 1862.1434140295464, 1903.4860587641347, 9387.0, 10105.090541851232, 10105.747356316087, 10105.848412273803, 10110.191184705234, 8517.0, 9329.626814927395, 9524.047595240472, 9544.04559544047, 9861.161801246884, 2518.282289547479, 2550.8979640814437, 2553.3616595851036, 2556.322574517754, 2556.7187609362954, 2498.5127900059497, 2563.6667233259745, 2566.8074894382903, 2568.4477837264985, 2569.8586577738215, 2549.477357141791, 2552.3874270175115, 2559.296193546771, 2562.0392352867675, 2566.5765148750434, 2478.2193609013193, 2490.0164435048146, 2507.987460062697, 2547.5159719653207, 2548.8980440782348, 2502.912398066077, 2512.567836333291, 2513.3465299022205, 2517.980218011708, 2526.621006848969, 9980.650677226335, 10069.093781559695, 10078.344907580984, 10088.344257623232, 10093.444860532667, 5636.182753500761, 5783.2192653992015, 5813.418658134195, 5854.97072514639, 5859.472647461749, 3352.3295340931913, 3430.9313813723834, 3478.269563391691, 3484.8431820568135, 3492.5598285688575, 1023.247912784103, 1033.3180101133291, 1036.5439206749081, 1039.8544203811473, 1066.5147357952112, 14354.066985646004, 14382.856171438256, 14429.422823087063, 14432.711345773236, 14463.855361446356, 4850.442199147144, 5002.849914502571, 5008.949910500921, 5107.61692873036, 5108.872278193001, 11806.645800625995, 11848.70378240551, 11870.406479675981, 11873.584424545099, 11877.940610296893, 5827.737751801179, 5851.356350801409, 5859.707014649251, 5867.765289388377, 5872.706364681749, 4683.274092515598, 4767.61859051275, 5327.840164795062, 5328.49379308965, 5348.705821179872, 4389.012472193731, 4390.978045109754, 4494.213512635341, 4524.977375113103, 4526.207913615104, 1357.592722183341, 1363.4478036395276, 1364.1951248763278, 1368.2132773655187, 1387.8820300274388, 1060.1359891688248, 1089.3899416326883, 1091.1543553745753, 1091.514276147107, 1097.8078836203633, 6554.442872355808, 6642.800715978528, 6760.323967603299, 6774.559653622598, 6941.826454338581, 484.1503161950754, 485.9368282123301, 491.87703074231445, 499.66023104289434, 503.22000898607746, 4534.0, 4617.8152873884665, 4653.790579423934, 4676.532346765296, 4727.929081063787, 12025.759484810254, 12489.313087780078, 12502.49990000408, 12550.43523041447, 12583.0, 12112.333821639719, 12362.258264504158, 12478.627350991384, 12487.562935297221, 12510.374481276081, 9065.365424420359, 9129.954350228207, 9168.862467063, 9204.263658907272, 9224.907750922539, 6584.8353791155605, 6627.536072474881, 6644.800655980328, 6672.633005184762, 6705.631190284581, 11051.17116216271, 11161.274517156358, 11306.321620702784, 11410.315381076829, 11441.713957150805, 9622.312492817424, 9960.651377201762, 11514.194006419806, 11698.883011169779, 11761.000314973324, 8509.319254459751, 8599.097094804956, 8615.91384086151, 8633.69782057625, 8646.43798153118, 9206.861897071416, 9353.204977576983, 9535.809283814146, 9563.521823908915, 9855.0, 8390.790230244293, 8703.564821759013, 8783.824323513621, 8788.209061184523, 8875.955620221857, 8243.958780206061, 8572.314214862672, 8621.439606425562, 8707.3034157266, 8801.38390312672, 8148.796280093045, 8159.714409995621, 8243.134470880472, 8243.216894395024, 8253.174682531699, 5892.9116063258225, 5900.1149827526215, 5926.2295901532525, 5982.252218472691, 5992.161097446448, 5777.508911742549, 5879.118132280192, 5928.377520360473, 5930.733117009831, 5989.36106865669], "value": 5036.806711562235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.9029516882858, 358.20299832871865, 359.0060576090988, 361.5318162978942, 367.81425380183003, 5535.640183388077, 5543.722813859306, 5563.329601791138, 5565.888682226353, 5602.7758889644365, 5432.9185062224, 5456.945430545703, 5508.366537848148, 5517.5585953123755, 5531.557475401968, 4155.542890282074, 4327.307630779061, 4349.913001739979, 4384.912301753963, 4388.561143885618, 4319.524852266264, 4336.457942757155, 4338.50107237667, 4359.455068116486, 4367.475902891641, 4309.547497512747, 4315.518576818326, 4321.178975994567, 4332.718373305741, 4349.826006959734, 4145.129522800215, 4261.31818908973, 4267.295896177127, 4301.956980430187, 4331.480222373304, 3772.811359432045, 3792.5069740933777, 3795.2978698940783, 3800.315943130263, 3810.8094595270127, 5344.492273234076, 5351.919721204185, 5405.648632838853, 5417.26866872975, 5434.456554344573, 4671.27595222741, 4830.879228019293, 4887.291342755316, 4957.801687932479, 4964.702117872939, 5570.470805273492, 5617.691026993513, 5631.690257035862, 5633.8591535211535, 5651.378348381675, 4161.417401563784, 4173.165366926596, 4173.499180098377, 4174.227767862955, 4186.1418409226035, 5842.415758424166, 5892.23400957877, 5898.675572843492, 5970.910436343458, 6009.399060094, 4232.449781528381, 4242.109157076987, 4243.363495475642, 4244.278472659656, 4251.766152861623, 4289.549597292302, 4297.0, 4299.1831552005015, 4311.784410779448, 4315.870523884289, 4680.648951328664, 4844.539768721966, 4892.3884514435695, 4911.3369695090705, 4928.954483889146, 5695.686737229409, 5697.601167918206, 5712.914306285409, 5719.942800571941, 5726.9713651431475, 1477.298283315438, 1502.0311898825196, 1506.239349128683, 1507.8492150785053, 1509.0794615284667, 918.6876462002817, 926.5598840550684, 946.2902822882838, 970.9611615535441, 981.1856159387692, 4944.975275123601, 4957.20684690455, 4986.476419975923, 5028.823991160365, 5033.546980771748, 5201.5838732901275, 5211.635185536976, 5256.894862102737, 5288.867778305573, 5289.3652761668845, 4980.402351717745, 5018.24726291052, 5051.696898186047, 5281.683099014072, 5662.716864156776, 3127.6872312768996, 3133.0914034929615, 3135.3102317490116, 3143.827089510053, 3151.16494129056, 6058.818235452944, 6081.5742897996715, 6087.93461144298, 6123.999240372185, 6126.571140020245, 5700.543956483471, 5751.309842818889, 5938.732757025944, 5989.221401217901, 6009.759609615654, 5845.357010728816, 6067.908981365283, 6082.178905847654, 6085.908711369333, 6114.021756518936, 5897.734601943009, 5906.320773111147, 5924.763009479572, 5931.317898441562, 5943.821685349447, 5717.799877004285, 5808.21589085485, 5845.883082338414, 5852.7658893643775, 5878.823635290948, 6056.848578785565, 6086.7260973255425, 6093.238345206849, 6117.204763380732, 6127.234095738033, 4155.418241446139, 4272.679549033897, 4277.957220427756, 4286.849960251377, 4309.224339618773, 5883.176355310175, 5938.495227905676, 5943.851403714942, 5958.689088400545, 5982.52139828804, 5777.94222057774, 5820.76716931318, 5829.6793676346515, 5903.7048147593305, 5937.614055086406, 5707.571932105192, 5723.075396333209, 5733.3406658234835, 5775.364709881994, 5853.239078919713, 5713.542916566582, 5733.942660573341, 5739.655620662774, 5740.167675686918, 5742.569307301886, 5573.108302671553, 5806.332271788629, 5856.560757943086, 5871.647701137946, 5883.764649414146, 5593.720313984366, 5650.208970744017, 5677.403872593483, 5691.601587888808, 5695.206010106803], "value": 4374.504869917477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.5575832379735, 781.5193655901622, 795.2882170457437, 817.3951276055718, 832.9125441828608, 16873.746893796604, 17428.128593570334, 17449.214785334658, 17777.111144442773, 17780.64438711225, 16168.919155404235, 16187.433439829609, 16287.104209268486, 16315.347386104571, 16747.078910659908, 749.1833901047856, 877.3288434347729, 894.2130924786212, 912.6623149434686, 917.2020342302206, 915.5925613102139, 923.866039424283, 927.2906226736552, 927.7263207353814, 928.4846909964966, 964.6575465709677, 965.1844191658073, 965.7006328038295, 965.9227261819037, 965.932384733069, 777.3314949143731, 926.3191554207652, 959.1894848852709, 960.5341409416462, 960.6445615122415, 692.0207905813319, 700.0, 700.6076597105637, 700.8142842146835, 700.9835738179609, 16067.437639682556, 16076.392360763946, 16129.677406451923, 16149.677006459922, 16160.27278772458, 15575.454859079879, 15783.220344059831, 15951.282192301374, 16022.679546409006, 16254.43109491162, 13081.757233062845, 13370.732585348238, 13394.330283485884, 13440.93279533596, 13499.987500937468, 4476.306172543261, 4483.125790470859, 4515.683902126852, 4516.029053753458, 4645.303204519315, 83553.0, 83697.0, 84961.0, 88990.0, 89840.10159898383, 11793.469293881795, 11795.705107372385, 11849.822252666218, 11946.820797688042, 11991.160618756603, 23592.292231233092, 23757.524849503257, 23822.404439888796, 23891.402714932265, 23932.28203153908, 8547.188017138362, 8766.21104100634, 9015.909840901639, 9043.050479699668, 9047.230985366186, 4796.496367881392, 4884.462709101996, 4886.780094895738, 5083.339165908408, 5122.590192784643, 3368.6799754023327, 3470.548828652259, 3481.9651803481643, 3491.005063556874, 3496.6328535503408, 644.191539617786, 645.0485533837552, 651.8989556618732, 658.3416583416583, 689.7999580121708, 744.181400459501, 753.8756105242683, 761.3604572159413, 764.8508540834595, 778.9260020298062, 10049.949750251202, 10374.325668831501, 10541.736456588647, 10832.079273261703, 10882.564697412015, 721.0698199322815, 728.7667946257253, 748.2517482517483, 759.5139110968985, 771.5679219637021, 5128.306865186088, 5171.896562068813, 5183.429822719498, 5261.210818377274, 5331.866703332447, 117211.41394292974, 131773.0, 131938.0, 132487.0, 135576.32211839076, 112628.43685781682, 115966.0, 117112.41443792896, 118721.0, 119362.40318798351, 58786.41213587895, 60757.69621151954, 96007.51996240114, 101358.0, 103685.48157259166, 9760.463174525185, 10034.949825250827, 10401.42792146409, 11349.375784331942, 11784.646460606196, 54707.0, 55543.0, 55653.72173139108, 56007.43992560022, 56476.152857706344, 51158.0, 61908.38091619026, 76914.23085769295, 77265.61367193128, 77704.61147694226, 13800.964927630266, 13828.446862125402, 14042.227677477837, 14274.0, 14872.553823385315, 16426.917865411073, 16535.338586456408, 16882.155892205094, 16940.0, 18139.27442902269, 15398.153101579073, 15836.129012904401, 15926.60183495422, 16134.919325403298, 16647.66704665924, 13786.0, 14504.419823207376, 15071.397144114113, 15449.304781284643, 15812.841871581137, 13878.583642490741, 14362.497312594009, 15092.54722358331, 15274.389024438899, 15281.235938203268, 12985.28580928058, 13065.477380905038, 13198.670033248862, 13659.317034148451, 13763.243021633505, 11941.46263418131, 12668.619941401772, 12896.677583060124, 12986.805197922227, 13002.349882506027], "value": 8255.995488321825, "unit": "score"}], "name": "dromaeo_css", "value": 7774.621470672074, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 109.0, 108.0, 110.0, 109.0, 109.0, 108.0, 107.0, 108.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 122.0, 177.0, 117.0, 121.0, 151.0, 117.0, 154.0, 117.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 163.0, 160.0, 161.0, 160.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 114.0, 76.0, 76.0, 75.0, 75.0, 111.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 129.0, 84.0, 83.0, 84.0, 124.0, 84.0, 83.0, 131.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 148.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 49.0, 49.0, 54.0, 49.0, 49.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 60.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 72.0, 73.0, 73.0, 79.0, 71.0, 72.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 129.0, 123.0, 131.0, 118.0, 125.0, 120.0, 116.0, 157.0, 129.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 152.0, 155.0, 155.0, 154.0, 157.0, 152.0, 151.0, 153.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 65.0, 61.0, 62.0, 61.0, 61.0, 61.0, 60.0, 59.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1462.4000000000003, "alertThreshold": 2.0}]} 03:16:34 INFO - Return code: 0 03:16:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:16:34 INFO - # TBPL SUCCESS # 03:16:34 INFO - Running post-action listener: _resource_record_post_action 03:16:34 INFO - [mozharness: 2016-08-17 10:16:34.559849Z] Finished run-tests step (success) 03:16:34 INFO - Running post-run listener: _resource_record_post_run 03:16:34 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327909376; Read time: 16; Write time: 288304 03:16:34 INFO - TinderboxPrint: CPU usage
12.9% 03:16:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 03:16:34 INFO - TinderboxPrint: I/O write bytes / time
327,909,376 / 288,304 03:16:34 INFO - TinderboxPrint: CPU idle
8,276.3 (87.1%) 03:16:34 INFO - TinderboxPrint: CPU user
1,182.6 (12.4%) 03:16:34 INFO - TinderboxPrint: Swap in / out
0 / 0 03:16:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79204352; Read time: 0; Write time: 99444 03:16:34 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248705024; Read time: 16; Write time: 188860 03:16:35 INFO - Running post-run listener: _upload_blobber_files 03:16:35 INFO - Blob upload gear active. 03:16:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:16:35 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 03:16:35 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'] 03:16:35 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 03:16:35 INFO - (blobuploader) - INFO - Open directory for files ... 03:16:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:16:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:16:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:40 INFO - (blobuploader) - INFO - Done attempting. 03:16:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:16:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:16:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:40 INFO - (blobuploader) - INFO - Done attempting. 03:16:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:16:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:16:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:41 INFO - (blobuploader) - INFO - Done attempting. 03:16:41 INFO - (blobuploader) - INFO - Iteration through files over. 03:16:41 INFO - Return code: 0 03:16:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:16:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:16:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffd7915fdb21da9f15ee9a66d34e56a1638d625cb05b34ec1d6fb86d22118243a063cb3460a7c826713f68258a2e30d992d63e9aabcd34494aad673a45668090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/194e225ae7233e333c1f4be4f32b7deae3d22e68dc87317f2971ab79db201ec1cdc67eb81e2304983c71f1877f20b0d32318417b2f5d4afe42614a9c6a27b32b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb2c32ffe9acdbc0ce04486d0d9cb148328dd7368c9b4c48b74e0cd4cae10a010bfab371e8b7db682a52c27d4c3735044d86ea35e2087a9c99cb6109a9265fc"} 03:16:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:16:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:16:41 INFO - Contents: 03:16:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffd7915fdb21da9f15ee9a66d34e56a1638d625cb05b34ec1d6fb86d22118243a063cb3460a7c826713f68258a2e30d992d63e9aabcd34494aad673a45668090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/194e225ae7233e333c1f4be4f32b7deae3d22e68dc87317f2971ab79db201ec1cdc67eb81e2304983c71f1877f20b0d32318417b2f5d4afe42614a9c6a27b32b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb2c32ffe9acdbc0ce04486d0d9cb148328dd7368c9b4c48b74e0cd4cae10a010bfab371e8b7db682a52c27d4c3735044d86ea35e2087a9c99cb6109a9265fc"} 03:16:41 INFO - Running post-run listener: copy_logs_to_upload_dir 03:16:41 INFO - Copying logs to upload dir... 03:16:41 INFO - mkdir: /builds/slave/test/build/upload/logs 03:16:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.332135 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-17 03:16:41.271101) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 03:16:41.274764) ========= 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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 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/ffd7915fdb21da9f15ee9a66d34e56a1638d625cb05b34ec1d6fb86d22118243a063cb3460a7c826713f68258a2e30d992d63e9aabcd34494aad673a45668090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/194e225ae7233e333c1f4be4f32b7deae3d22e68dc87317f2971ab79db201ec1cdc67eb81e2304983c71f1877f20b0d32318417b2f5d4afe42614a9c6a27b32b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb2c32ffe9acdbc0ce04486d0d9cb148328dd7368c9b4c48b74e0cd4cae10a010bfab371e8b7db682a52c27d4c3735044d86ea35e2087a9c99cb6109a9265fc"} build_url:https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/M4ysyOjBS2ay7bRiZoTWbg/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/ffd7915fdb21da9f15ee9a66d34e56a1638d625cb05b34ec1d6fb86d22118243a063cb3460a7c826713f68258a2e30d992d63e9aabcd34494aad673a45668090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/194e225ae7233e333c1f4be4f32b7deae3d22e68dc87317f2971ab79db201ec1cdc67eb81e2304983c71f1877f20b0d32318417b2f5d4afe42614a9c6a27b32b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/beb2c32ffe9acdbc0ce04486d0d9cb148328dd7368c9b4c48b74e0cd4cae10a010bfab371e8b7db682a52c27d4c3735044d86ea35e2087a9c99cb6109a9265fc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 03:16:41.300089) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:16:41.300428) ========= 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=d2a8c569965ab8367867b893000001a1-1471423906.713521-9286304 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:16:41.386969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 03:16:41.387434) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 03:16:41.387778) ========= ========= Total master_lag: 0.16 =========