builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1470286856.92 results: success (0) buildid: 20160803193007 builduid: aa74b6375a464e46b1eb446848455e3f revision: 1576e7bc1bec7232e9e4ba78cce62526b1a6380b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:56.922719) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:56.923472) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:56.923761) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:56.984510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:56.984944) ========= 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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-03 22:00:56-- 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% 228M=0s 2016-08-03 22:00:57 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129981 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.147456) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.147779) ========= 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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.134109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.301234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.301611) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1576e7bc1bec7232e9e4ba78cce62526b1a6380b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1576e7bc1bec7232e9e4ba78cce62526b1a6380b --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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-03 22:00:57,323 truncating revision to first 12 chars 2016-08-03 22:00:57,323 Setting DEBUG logging. 2016-08-03 22:00:57,323 attempt 1/10 2016-08-03 22:00:57,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1576e7bc1bec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 22:00:57,628 unpacking tar archive at: mozilla-central-1576e7bc1bec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650888 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.967101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.967387) ========= script_repo_revision: 1576e7bc1bec7232e9e4ba78cce62526b1a6380b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.967745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.968019) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 22:00:57.977366) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-03 22:00:57.977639) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:58 INFO - MultiFileLogger online at 20160803 22:00:58 in /builds/slave/test 22:00:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:00:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:58 INFO - {'append_to_log': False, 22:00:58 INFO - 'base_work_dir': '/builds/slave/test', 22:00:58 INFO - 'blob_upload_branch': 'Firefox', 22:00:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:58 INFO - 'branch': 'Firefox', 22:00:58 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:58 INFO - 'config_files': ('talos/linux_config.py',), 22:00:58 INFO - 'default_actions': ('clobber', 22:00:58 INFO - 'read-buildbot-config', 22:00:58 INFO - 'download-and-extract', 22:00:58 INFO - 'populate-webroot', 22:00:58 INFO - 'create-virtualenv', 22:00:58 INFO - 'install', 22:00:58 INFO - 'run-tests'), 22:00:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:58 INFO - 'download_minidump_stackwalk': True, 22:00:58 INFO - 'download_symbols': 'ondemand', 22:00:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:58 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:58 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:58 INFO - 'installer_path': 'installer.exe', 22:00:58 INFO - 'log_level': 'info', 22:00:58 INFO - 'log_name': 'talos', 22:00:58 INFO - 'log_to_console': True, 22:00:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:58 INFO - 'opt_config_files': (), 22:00:58 INFO - 'pip_index': False, 22:00:58 INFO - 'sps_profile': False, 22:00:58 INFO - 'sps_profile_interval': 0, 22:00:58 INFO - 'suite': 'dromaeojs', 22:00:58 INFO - 'system_bits': '32', 22:00:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:58 INFO - 'title': 'talos-linux64-ix-025', 22:00:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:58 INFO - 'use_talos_json': True, 22:00:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:58 INFO - 'work_dir': 'build'} 22:00:58 INFO - [mozharness: 2016-08-04 05:00:58.198502Z] Running clobber step. 22:00:58 INFO - Running pre-action listener: _resource_record_pre_action 22:00:58 INFO - Running main action method: clobber 22:00:58 INFO - rmtree: /builds/slave/test/build 22:00:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:00 INFO - Running post-action listener: _resource_record_post_action 22:01:00 INFO - [mozharness: 2016-08-04 05:01:00.196819Z] Finished clobber step (success) 22:01:00 INFO - [mozharness: 2016-08-04 05:01:00.196975Z] Running read-buildbot-config step. 22:01:00 INFO - Running pre-action listener: _resource_record_pre_action 22:01:00 INFO - Running main action method: read_buildbot_config 22:01:00 INFO - Using buildbot properties: 22:01:00 INFO - { 22:01:00 INFO - "project": "", 22:01:00 INFO - "product": "firefox", 22:01:00 INFO - "script_repo_revision": "production", 22:01:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:01:00 INFO - "repository": "", 22:01:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:01:00 INFO - "buildid": "20160803193007", 22:01:00 INFO - "pgo_build": "True", 22:01:00 INFO - "basedir": "/builds/slave/test", 22:01:00 INFO - "buildnumber": 822, 22:01:00 INFO - "slavename": "talos-linux64-ix-025", 22:01:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:01:00 INFO - "platform": "ubuntu64_hw", 22:01:00 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:01:00 INFO - "branch": "mozilla-central", 22:01:00 INFO - "repo_path": "mozilla-central", 22:01:00 INFO - "revision": "1576e7bc1bec7232e9e4ba78cce62526b1a6380b", 22:01:00 INFO - "stage_platform": "linux64-pgo", 22:01:00 INFO - "builduid": "aa74b6375a464e46b1eb446848455e3f", 22:01:00 INFO - "slavebuilddir": "test" 22:01:00 INFO - } 22:01:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:01:00 INFO - Running post-action listener: _resource_record_post_action 22:01:00 INFO - [mozharness: 2016-08-04 05:01:00.200865Z] Finished read-buildbot-config step (success) 22:01:00 INFO - [mozharness: 2016-08-04 05:01:00.201007Z] Running download-and-extract step. 22:01:00 INFO - Running pre-action listener: _resource_record_pre_action 22:01:00 INFO - Running main action method: download_and_extract 22:01:00 INFO - mkdir: /builds/slave/test/build/tests 22:01: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')]} 22:01:00 INFO - https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:01:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:01:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:01:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:01:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:01:05 INFO - Downloaded 1361 bytes. 22:01:05 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:01:05 INFO - Using the following test package requirements: 22:01:05 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:01:05 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:01:05 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:01:05 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'jsshell-linux-x86_64.zip'], 22:01:05 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:01:05 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:01:05 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:01:05 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:01:05 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:01:05 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:01:05 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:01:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:05 INFO - https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:01:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:06 INFO - Downloaded 21812712 bytes. 22:01:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:01:08 INFO - Return code: 0 22:01:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:08 INFO - https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:01:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:01:08 INFO - Downloaded 21812712 bytes. 22:01:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:01:10 INFO - Return code: 0 22:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:10 INFO - https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:01:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:01:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:01:11 INFO - Downloaded 14413723 bytes. 22:01:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:01:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:01:12 INFO - Return code: 0 22:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:12 INFO - https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:01:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:01:13 INFO - Downloaded 59326234 bytes. 22:01:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:13 INFO - mkdir: /builds/slave/test/properties 22:01:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:13 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:13 INFO - Contents: 22:01:13 INFO - build_url:https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:01:13 INFO - Running post-action listener: _resource_record_post_action 22:01:13 INFO - Running post-action listener: set_extra_try_arguments 22:01:13 INFO - [mozharness: 2016-08-04 05:01:13.956634Z] Finished download-and-extract step (success) 22:01:13 INFO - [mozharness: 2016-08-04 05:01:13.956866Z] Running populate-webroot step. 22:01:13 INFO - Running pre-action listener: _resource_record_pre_action 22:01:13 INFO - Running main action method: populate_webroot 22:01:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:13 INFO - '--tppagecycles', 22:01:13 INFO - '7'], 22:01:13 INFO - 'tests': ['tsvgm']}}, 22:01:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['damp', 'tps']}, 22:01:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'tests': ['damp', 'tps']}, 22:01:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['dromaeo_dom']}, 22:01:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['basic_compositor_video']}, 22:01:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:01:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['a11yr', 22:01:13 INFO - 'ts_paint', 22:01:13 INFO - 'tpaint', 22:01:13 INFO - 'sessionrestore', 22:01:13 INFO - 'sessionrestore_no_auto_restore', 22:01:13 INFO - 'tabpaint']}, 22:01:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['tsvgx', 22:01:13 INFO - 'tsvgr_opacity', 22:01:13 INFO - 'tart', 22:01:13 INFO - 'tscrollx', 22:01:13 INFO - 'cart']}, 22:01:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:13 INFO - 'tsvgr_opacity', 22:01:13 INFO - 'tart', 22:01:13 INFO - 'tscrollx', 22:01:13 INFO - 'cart']}, 22:01:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'talos_options': ['--disable-e10s'], 22:01:13 INFO - 'tests': ['tp5o']}, 22:01:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'tests': ['tp5o']}, 22:01:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'talos_options': ['--disable-e10s', 22:01:13 INFO - '--xperf_path', 22:01:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:13 INFO - 'tests': ['tp5n']}, 22:01:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:01:13 INFO - 'talos_options': ['--xperf_path', 22:01:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:13 INFO - 'tests': ['tp5n']}}, 22:01:13 INFO - 'talos.zip': {'path': '', 22:01:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:13 INFO - Running post-action listener: _resource_record_post_action 22:01:13 INFO - [mozharness: 2016-08-04 05:01:13.977974Z] Finished populate-webroot step (success) 22:01:13 INFO - [mozharness: 2016-08-04 05:01:13.978063Z] Running create-virtualenv step. 22:01:13 INFO - Running pre-action listener: _resource_record_pre_action 22:01:13 INFO - Running main action method: create_virtualenv 22:01:13 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:13 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 22:01:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:14 INFO - Using real prefix '/usr' 22:01:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:14 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:17 INFO - Installing pip.................done. 22:01:17 INFO - Return code: 0 22:01:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:17 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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:17 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 22:01:17 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 22:01:17 INFO - Using env: {'DISPLAY': ':0', 22:01:17 INFO - 'HOME': '/home/cltbld', 22:01:17 INFO - 'LANG': 'en_US.UTF-8', 22:01:17 INFO - 'LANGUAGE': 'en_US:en', 22:01:17 INFO - 'LOGNAME': 'cltbld', 22:01:17 INFO - 'MAIL': '/var/mail/cltbld', 22:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:17 INFO - 'MOZ_NO_REMOTE': '1', 22:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:17 INFO - 'NO_EM_RESTART': '1', 22:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:17 INFO - 'PWD': '/builds/slave/test', 22:01:17 INFO - 'SHELL': '/bin/bash', 22:01:17 INFO - 'SHLVL': '1', 22:01:17 INFO - 'TERM': 'linux', 22:01:17 INFO - 'TMOUT': '86400', 22:01:17 INFO - 'USER': 'cltbld', 22:01:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:17 INFO - '_': '/tools/buildbot/bin/python'} 22:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:17 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 22:01:17 INFO - Cleaning up... 22:01:17 INFO - Return code: 0 22:01:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:17 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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:17 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 22:01:17 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 22:01:17 INFO - Using env: {'DISPLAY': ':0', 22:01:17 INFO - 'HOME': '/home/cltbld', 22:01:17 INFO - 'LANG': 'en_US.UTF-8', 22:01:17 INFO - 'LANGUAGE': 'en_US:en', 22:01:17 INFO - 'LOGNAME': 'cltbld', 22:01:17 INFO - 'MAIL': '/var/mail/cltbld', 22:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:17 INFO - 'MOZ_NO_REMOTE': '1', 22:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:17 INFO - 'NO_EM_RESTART': '1', 22:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:17 INFO - 'PWD': '/builds/slave/test', 22:01:17 INFO - 'SHELL': '/bin/bash', 22:01:17 INFO - 'SHLVL': '1', 22:01:17 INFO - 'TERM': 'linux', 22:01:17 INFO - 'TMOUT': '86400', 22:01:17 INFO - 'USER': 'cltbld', 22:01:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:17 INFO - '_': '/tools/buildbot/bin/python'} 22:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:17 INFO - Downloading/unpacking psutil>=3.1.1 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:17 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 22:01:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:19 INFO - Installing collected packages: psutil 22:01:19 INFO - Running setup.py install for psutil 22:01:20 INFO - building 'psutil._psutil_linux' extension 22:01:20 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 22:01:21 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 22:01:21 INFO - building 'psutil._psutil_posix' extension 22:01:21 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 22:01:21 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 22:01:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:21 INFO - Successfully installed psutil 22:01:21 INFO - Cleaning up... 22:01:21 INFO - Return code: 0 22:01:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:01:21 INFO - Using env: {'DISPLAY': ':0', 22:01:21 INFO - 'HOME': '/home/cltbld', 22:01:21 INFO - 'LANG': 'en_US.UTF-8', 22:01:21 INFO - 'LANGUAGE': 'en_US:en', 22:01:21 INFO - 'LOGNAME': 'cltbld', 22:01:21 INFO - 'MAIL': '/var/mail/cltbld', 22:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:21 INFO - 'MOZ_NO_REMOTE': '1', 22:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:21 INFO - 'NO_EM_RESTART': '1', 22:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:21 INFO - 'PWD': '/builds/slave/test', 22:01:21 INFO - 'SHELL': '/bin/bash', 22:01:21 INFO - 'SHLVL': '1', 22:01:21 INFO - 'TERM': 'linux', 22:01:21 INFO - 'TMOUT': '86400', 22:01:21 INFO - 'USER': 'cltbld', 22:01:21 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:21 INFO - '_': '/tools/buildbot/bin/python'} 22:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:23 INFO - Installing collected packages: mozsystemmonitor 22:01:23 INFO - Running setup.py install for mozsystemmonitor 22:01:23 INFO - Successfully installed mozsystemmonitor 22:01:23 INFO - Cleaning up... 22:01:23 INFO - Return code: 0 22:01:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:01: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 jsonschema==2.5.1 22:01:23 INFO - Using env: {'DISPLAY': ':0', 22:01:23 INFO - 'HOME': '/home/cltbld', 22:01:23 INFO - 'LANG': 'en_US.UTF-8', 22:01:23 INFO - 'LANGUAGE': 'en_US:en', 22:01:23 INFO - 'LOGNAME': 'cltbld', 22:01:23 INFO - 'MAIL': '/var/mail/cltbld', 22:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:23 INFO - 'MOZ_NO_REMOTE': '1', 22:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:23 INFO - 'NO_EM_RESTART': '1', 22:01:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:23 INFO - 'PWD': '/builds/slave/test', 22:01:23 INFO - 'SHELL': '/bin/bash', 22:01:23 INFO - 'SHLVL': '1', 22:01:23 INFO - 'TERM': 'linux', 22:01:23 INFO - 'TMOUT': '86400', 22:01:23 INFO - 'USER': 'cltbld', 22:01:23 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:23 INFO - '_': '/tools/buildbot/bin/python'} 22:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:24 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:24 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 22:01:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:25 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 22:01:25 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 22:01:25 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 22:01:25 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 22:01:25 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 22:01:25 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 22:01:26 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:26 INFO - warning: no files found matching '*.txt' 22:01:26 INFO - no previously-included directories found matching 'build' 22:01:26 INFO - no previously-included directories found matching 'dist' 22:01:26 INFO - no previously-included directories found matching '.git*' 22:01:26 INFO - Installing collected packages: jsonschema, functools32 22:01:26 INFO - Running setup.py install for functools32 22:01:26 INFO - warning: no files found matching '*.txt' 22:01:26 INFO - no previously-included directories found matching 'build' 22:01:26 INFO - no previously-included directories found matching 'dist' 22:01:26 INFO - no previously-included directories found matching '.git*' 22:01:26 INFO - Successfully installed jsonschema functools32 22:01:26 INFO - Cleaning up... 22:01:26 INFO - Return code: 0 22:01:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:01: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 functools32==3.2.3-2 22:01:26 INFO - Using env: {'DISPLAY': ':0', 22:01:26 INFO - 'HOME': '/home/cltbld', 22:01:26 INFO - 'LANG': 'en_US.UTF-8', 22:01:26 INFO - 'LANGUAGE': 'en_US:en', 22:01:26 INFO - 'LOGNAME': 'cltbld', 22:01:26 INFO - 'MAIL': '/var/mail/cltbld', 22:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:26 INFO - 'MOZ_NO_REMOTE': '1', 22:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:26 INFO - 'NO_EM_RESTART': '1', 22:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:26 INFO - 'PWD': '/builds/slave/test', 22:01:26 INFO - 'SHELL': '/bin/bash', 22:01:26 INFO - 'SHLVL': '1', 22:01:26 INFO - 'TERM': 'linux', 22:01:26 INFO - 'TMOUT': '86400', 22:01:26 INFO - 'USER': 'cltbld', 22:01:26 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:26 INFO - '_': '/tools/buildbot/bin/python'} 22:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:01:26 INFO - Cleaning up... 22:01:26 INFO - Return code: 0 22:01:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:01: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 blobuploader==1.2.4 22:01:26 INFO - Using env: {'DISPLAY': ':0', 22:01:26 INFO - 'HOME': '/home/cltbld', 22:01:26 INFO - 'LANG': 'en_US.UTF-8', 22:01:26 INFO - 'LANGUAGE': 'en_US:en', 22:01:26 INFO - 'LOGNAME': 'cltbld', 22:01:26 INFO - 'MAIL': '/var/mail/cltbld', 22:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:26 INFO - 'MOZ_NO_REMOTE': '1', 22:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:26 INFO - 'NO_EM_RESTART': '1', 22:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:26 INFO - 'PWD': '/builds/slave/test', 22:01:26 INFO - 'SHELL': '/bin/bash', 22:01:26 INFO - 'SHLVL': '1', 22:01:26 INFO - 'TERM': 'linux', 22:01:26 INFO - 'TMOUT': '86400', 22:01:26 INFO - 'USER': 'cltbld', 22:01:26 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:26 INFO - '_': '/tools/buildbot/bin/python'} 22:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:26 INFO - Downloading/unpacking blobuploader==1.2.4 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:28 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:29 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 22:01:29 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 22:01:29 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 22:01:29 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 22:01:29 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 22:01:29 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 22:01:29 INFO - Downloading docopt-0.6.1.tar.gz 22:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:29 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:29 INFO - Running setup.py install for blobuploader 22:01:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:29 INFO - Running setup.py install for requests 22:01:30 INFO - Running setup.py install for docopt 22:01:30 INFO - Successfully installed blobuploader requests docopt 22:01:30 INFO - Cleaning up... 22:01:30 INFO - Return code: 0 22:01:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:30 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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:30 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 22:01:30 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 22:01:30 INFO - Using env: {'DISPLAY': ':0', 22:01:30 INFO - 'HOME': '/home/cltbld', 22:01:30 INFO - 'LANG': 'en_US.UTF-8', 22:01:30 INFO - 'LANGUAGE': 'en_US:en', 22:01:30 INFO - 'LOGNAME': 'cltbld', 22:01:30 INFO - 'MAIL': '/var/mail/cltbld', 22:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:30 INFO - 'MOZ_NO_REMOTE': '1', 22:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:30 INFO - 'NO_EM_RESTART': '1', 22:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:30 INFO - 'PWD': '/builds/slave/test', 22:01:30 INFO - 'SHELL': '/bin/bash', 22:01:30 INFO - 'SHLVL': '1', 22:01:30 INFO - 'TERM': 'linux', 22:01:30 INFO - 'TMOUT': '86400', 22:01:30 INFO - 'USER': 'cltbld', 22:01:30 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:30 INFO - '_': '/tools/buildbot/bin/python'} 22:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:30 INFO - Running setup.py (path:/tmp/pip-1yYTzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:30 INFO - Running setup.py (path:/tmp/pip-Vr_qw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:30 INFO - Running setup.py (path:/tmp/pip-LPl9Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:30 INFO - Running setup.py (path:/tmp/pip-SZbIZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:30 INFO - Running setup.py (path:/tmp/pip-91cpHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:30 INFO - Running setup.py (path:/tmp/pip-lRWiyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:30 INFO - Running setup.py (path:/tmp/pip-T_ITLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:30 INFO - Running setup.py (path:/tmp/pip-Ni4pJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:30 INFO - Running setup.py (path:/tmp/pip-IdOC_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:31 INFO - Running setup.py (path:/tmp/pip-7rJRhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:31 INFO - Running setup.py (path:/tmp/pip-ZioLAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:31 INFO - Running setup.py (path:/tmp/pip-rYFPpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:31 INFO - Running setup.py (path:/tmp/pip-xm_Elb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:31 INFO - Running setup.py (path:/tmp/pip-9gJesV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:31 INFO - Running setup.py (path:/tmp/pip-Dt3ggJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:31 INFO - Running setup.py (path:/tmp/pip-xQumZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:31 INFO - Running setup.py (path:/tmp/pip-pJziXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:31 INFO - Running setup.py install for manifestparser 22:01:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:31 INFO - Running setup.py install for mozcrash 22:01:31 INFO - Running setup.py install for mozdebug 22:01:31 INFO - Running setup.py install for mozdevice 22:01:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozfile 22:01:32 INFO - Running setup.py install for mozhttpd 22:01:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozinfo 22:01:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozInstall 22:01:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozleak 22:01:32 INFO - Running setup.py install for mozlog 22:01:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for moznetwork 22:01:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozprocess 22:01:32 INFO - Running setup.py install for mozprofile 22:01:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:32 INFO - Running setup.py install for mozrunner 22:01:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:33 INFO - Running setup.py install for mozscreenshot 22:01:33 INFO - Running setup.py install for moztest 22:01:33 INFO - Running setup.py install for mozversion 22:01:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:33 INFO - Cleaning up... 22:01:33 INFO - Return code: 0 22:01:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:33 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')]} 22:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:33 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')]} 22:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:33 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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:33 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 22:01:33 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 22:01:33 INFO - Using env: {'DISPLAY': ':0', 22:01:33 INFO - 'HOME': '/home/cltbld', 22:01:33 INFO - 'LANG': 'en_US.UTF-8', 22:01:33 INFO - 'LANGUAGE': 'en_US:en', 22:01:33 INFO - 'LOGNAME': 'cltbld', 22:01:33 INFO - 'MAIL': '/var/mail/cltbld', 22:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:33 INFO - 'MOZ_NO_REMOTE': '1', 22:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:33 INFO - 'NO_EM_RESTART': '1', 22:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:33 INFO - 'PWD': '/builds/slave/test', 22:01:33 INFO - 'SHELL': '/bin/bash', 22:01:33 INFO - 'SHLVL': '1', 22:01:33 INFO - 'TERM': 'linux', 22:01:33 INFO - 'TMOUT': '86400', 22:01:33 INFO - 'USER': 'cltbld', 22:01:33 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:33 INFO - '_': '/tools/buildbot/bin/python'} 22:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:33 INFO - Running setup.py (path:/tmp/pip-13dK1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:33 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)) 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:33 INFO - Running setup.py (path:/tmp/pip-iEXGzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:33 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)) 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:33 INFO - Running setup.py (path:/tmp/pip-ySUYA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:33 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)) 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:33 INFO - Running setup.py (path:/tmp/pip-f4dgXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:33 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)) 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:33 INFO - Running setup.py (path:/tmp/pip-McLAsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:33 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)) 22:01:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:33 INFO - Running setup.py (path:/tmp/pip-vayivR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:34 INFO - Running setup.py (path:/tmp/pip-TSkXuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:34 INFO - Running setup.py (path:/tmp/pip-ZF1sul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:34 INFO - Running setup.py (path:/tmp/pip-S1_Gxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:34 INFO - Running setup.py (path:/tmp/pip-cMnuqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:34 INFO - Running setup.py (path:/tmp/pip-lQMzPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:34 INFO - Running setup.py (path:/tmp/pip-rhg_ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:34 INFO - Running setup.py (path:/tmp/pip-GWTNSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:34 INFO - Running setup.py (path:/tmp/pip-R0bFRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:34 INFO - Running setup.py (path:/tmp/pip-1NHYtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:34 INFO - Running setup.py (path:/tmp/pip-fqBo5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:34 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)) 22:01:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:34 INFO - Running setup.py (path:/tmp/pip-Yf3PQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:34 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)) 22:01:34 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)) 22:01:34 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)) 22:01:34 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)) 22:01:34 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)) 22:01:34 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)) 22:01:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:36 INFO - Downloading blessings-1.6.tar.gz 22:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:36 INFO - Installing collected packages: blessings 22:01:36 INFO - Running setup.py install for blessings 22:01:36 INFO - Successfully installed blessings 22:01:36 INFO - Cleaning up... 22:01:37 INFO - Return code: 0 22:01:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:37 INFO - Reading from file tmpfile_stdout 22:01:37 INFO - Current package versions: 22:01:37 INFO - argparse == 1.2.1 22:01:37 INFO - blessings == 1.6 22:01:37 INFO - blobuploader == 1.2.4 22:01:37 INFO - docopt == 0.6.1 22:01:37 INFO - functools32 == 3.2.3-2 22:01:37 INFO - jsonschema == 2.5.1 22:01:37 INFO - manifestparser == 1.1 22:01:37 INFO - mozInstall == 1.12 22:01:37 INFO - mozcrash == 0.17 22:01:37 INFO - mozdebug == 0.1 22:01:37 INFO - mozdevice == 0.48 22:01:37 INFO - mozfile == 1.2 22:01:37 INFO - mozhttpd == 0.7 22:01:37 INFO - mozinfo == 0.9 22:01:37 INFO - mozleak == 0.1 22:01:37 INFO - mozlog == 3.3 22:01:37 INFO - moznetwork == 0.27 22:01:37 INFO - mozprocess == 0.23 22:01:37 INFO - mozprofile == 0.28 22:01:37 INFO - mozrunner == 6.12 22:01:37 INFO - mozscreenshot == 0.1 22:01:37 INFO - mozsystemmonitor == 0.3 22:01:37 INFO - moztest == 0.7 22:01:37 INFO - mozversion == 1.4 22:01:37 INFO - psutil == 3.1.1 22:01:37 INFO - requests == 1.2.3 22:01:37 INFO - wsgiref == 0.1.2 22:01:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:37 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 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:37 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 22:01:37 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 22:01:37 INFO - Using env: {'DISPLAY': ':0', 22:01:37 INFO - 'HOME': '/home/cltbld', 22:01:37 INFO - 'LANG': 'en_US.UTF-8', 22:01:37 INFO - 'LANGUAGE': 'en_US:en', 22:01:37 INFO - 'LOGNAME': 'cltbld', 22:01:37 INFO - 'MAIL': '/var/mail/cltbld', 22:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:37 INFO - 'MOZ_NO_REMOTE': '1', 22:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:37 INFO - 'NO_EM_RESTART': '1', 22:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:37 INFO - 'PWD': '/builds/slave/test', 22:01:37 INFO - 'SHELL': '/bin/bash', 22:01:37 INFO - 'SHLVL': '1', 22:01:37 INFO - 'TERM': 'linux', 22:01:37 INFO - 'TMOUT': '86400', 22:01:37 INFO - 'USER': 'cltbld', 22:01:37 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:37 INFO - '_': '/tools/buildbot/bin/python'} 22:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 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)) 22:01:37 INFO - Cleaning up... 22:01:37 INFO - Return code: 0 22:01:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a18030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b7b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, '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': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', '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 22:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:01:37 INFO - Using env: {'DISPLAY': ':0', 22:01:37 INFO - 'HOME': '/home/cltbld', 22:01:37 INFO - 'LANG': 'en_US.UTF-8', 22:01:37 INFO - 'LANGUAGE': 'en_US:en', 22:01:37 INFO - 'LOGNAME': 'cltbld', 22:01:37 INFO - 'MAIL': '/var/mail/cltbld', 22:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:37 INFO - 'MOZ_NO_REMOTE': '1', 22:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:37 INFO - 'NO_EM_RESTART': '1', 22:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:37 INFO - 'PWD': '/builds/slave/test', 22:01:37 INFO - 'SHELL': '/bin/bash', 22:01:37 INFO - 'SHLVL': '1', 22:01:37 INFO - 'TERM': 'linux', 22:01:37 INFO - 'TMOUT': '86400', 22:01:37 INFO - 'USER': 'cltbld', 22:01:37 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:37 INFO - '_': '/tools/buildbot/bin/python'} 22:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:37 INFO - Cleaning up... 22:01:37 INFO - Return code: 0 22:01:37 INFO - Running post-action listener: _resource_record_post_action 22:01:37 INFO - Running post-action listener: _start_resource_monitoring 22:01:37 INFO - Starting resource monitoring. 22:01:37 INFO - [mozharness: 2016-08-04 05:01:37.687551Z] Finished create-virtualenv step (success) 22:01:37 INFO - [mozharness: 2016-08-04 05:01:37.688518Z] Running install step. 22:01:37 INFO - Running pre-action listener: _resource_record_pre_action 22:01:37 INFO - Running main action method: install 22:01:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:37 INFO - Reading from file tmpfile_stdout 22:01:37 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:37 INFO - Reading from file tmpfile_stdout 22:01:37 INFO - Output received: 22:01:37 INFO - Usage: mozinstall [options] installer 22:01:37 INFO - Options: 22:01:37 INFO - -h, --help show this help message and exit 22:01:37 INFO - -d DEST, --destination=DEST 22:01:37 INFO - Directory to install application into. [default: 22:01:37 INFO - "/builds/slave/test"] 22:01:37 INFO - --app=APP Application being installed. [default: firefox] 22:01:37 INFO - mkdir: /builds/slave/test/build/application 22:01:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:01:54 INFO - Reading from file tmpfile_stdout 22:01:54 INFO - Output received: 22:01:54 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:54 INFO - Running post-action listener: _resource_record_post_action 22:01:54 INFO - [mozharness: 2016-08-04 05:01:54.822229Z] Finished install step (success) 22:01:54 INFO - [mozharness: 2016-08-04 05:01:54.822503Z] Running run-tests step. 22:01:54 INFO - Running pre-action listener: _resource_record_pre_action 22:01:54 INFO - Running main action method: run_tests 22:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:54 INFO - Python 2.7.3 22:01:54 INFO - Return code: 0 22:01:54 INFO - grabbing minidump binary from tooltool 22:01:54 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')]} 22:01:54 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 0x2cd73b0>, '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 0x2cd82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:54 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 22:01:54 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 22:01:54 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 22:01:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:54 INFO - Return code: 0 22:01:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 22:01:54 INFO - Using env: {'DISPLAY': ':0', 22:01:54 INFO - 'HOME': '/home/cltbld', 22:01:54 INFO - 'LANG': 'en_US.UTF-8', 22:01:54 INFO - 'LANGUAGE': 'en_US:en', 22:01:54 INFO - 'LOGNAME': 'cltbld', 22:01:54 INFO - 'MAIL': '/var/mail/cltbld', 22:01:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:54 INFO - 'MOZ_NO_REMOTE': '1', 22:01:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:54 INFO - 'NO_EM_RESTART': '1', 22:01:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:54 INFO - 'PWD': '/builds/slave/test', 22:01:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:54 INFO - 'SHELL': '/bin/bash', 22:01:54 INFO - 'SHLVL': '1', 22:01:54 INFO - 'TERM': 'linux', 22:01:54 INFO - 'TMOUT': '86400', 22:01:54 INFO - 'USER': 'cltbld', 22:01:54 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1470285781.498938-634745871', 22:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:54 INFO - '_': '/tools/buildbot/bin/python'} 22:01:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 22:01:55 INFO - mozversion application_buildid: 20160803193007 22:01:55 INFO - mozversion application_changeset: 1576e7bc1bec7232e9e4ba78cce62526b1a6380b 22:01:55 INFO - mozversion application_display_name: Nightly 22:01:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:55 INFO - mozversion application_name: Firefox 22:01:55 INFO - mozversion application_remotingname: firefox 22:01:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:01:55 INFO - mozversion application_vendor: Mozilla 22:01:55 INFO - mozversion application_version: 51.0a1 22:01:55 INFO - mozversion platform_buildid: 20160803193007 22:01:55 INFO - mozversion platform_changeset: 1576e7bc1bec7232e9e4ba78cce62526b1a6380b 22:01:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:01:55 INFO - mozversion platform_version: 51.0a1 22:01:55 INFO - using testdate: 1470286915 22:01:55 INFO - actual date: 1470286915 22:01:55 INFO - starting webserver on 'localhost:52353' 22:01:55 INFO - SUITE-START | Running 2 tests 22:01:55 INFO - TEST-START | dromaeo_css 22:01:55 INFO - operating with platform_type : linux_ 22:01:55 INFO - Initialising browser for dromaeo_css test... 22:01:55 INFO - TEST-INFO | started process 3305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzWomLZ/profile http://localhost:52353/getInfo.html) 22:02:01 INFO - PROCESS | 3305 | __metrics Screen width/height:1600/1200 22:02:01 INFO - PROCESS | 3305 | colorDepth:24 22:02:01 INFO - PROCESS | 3305 | Browser inner width/height: 1024/697 22:02:01 INFO - PROCESS | 3305 | __metrics 22:02:02 INFO - PROCESS | 3305 | 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" 22:02:02 INFO - PROCESS | 3305 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:03 INFO - TEST-INFO | 3305: exit 0 22:02:03 INFO - Browser initialized. 22:02:03 INFO - Running cycle 1/1 for dromaeo_css test... 22:02:03 INFO - TEST-INFO | started process 3384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzWomLZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:15 INFO - PROCESS | 3384 | RSS: Main: 156266496 22:02:15 INFO - PROCESS | 3384 | 22:03:06 INFO - PROCESS | 3384 | 1470286986736 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] 22:05:16 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52353/tests/dromaeo/cssquery-ext.html) 22:05:17 INFO - PROCESS | 3384 | RSS: Main: 248221696 22:05:17 INFO - PROCESS | 3384 | 22:08:19 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-ext.html (next: http://localhost:52353/tests/dromaeo/cssquery-jquery.html) 22:08:20 INFO - PROCESS | 3384 | RSS: Main: 249880576 22:08:20 INFO - PROCESS | 3384 | 22:11:22 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52353/tests/dromaeo/cssquery-mootools.html) 22:11:22 INFO - PROCESS | 3384 | RSS: Main: 190341120 22:11:22 INFO - PROCESS | 3384 | 22:14:24 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52353/tests/dromaeo/cssquery-prototype.html) 22:14:25 INFO - PROCESS | 3384 | RSS: Main: 228352000 22:14:25 INFO - PROCESS | 3384 | 22:17:28 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52353/tests/dromaeo/cssquery-yui.html) 22:17:28 INFO - PROCESS | 3384 | RSS: Main: 191635456 22:17:28 INFO - PROCESS | 3384 | 22:20:31 INFO - PROCESS | 3384 | Cycle 1(1): loaded http://localhost:52353/tests/dromaeo/cssquery-yui.html (next: http://localhost:52353/tests/dromaeo/cssquery-dojo.html) 22:20:32 INFO - PROCESS | 3384 | RSS: Main: 280993792 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | __start_tp_report 22:20:32 INFO - PROCESS | 3384 | _x_x_mozilla_page_load 22:20:32 INFO - PROCESS | 3384 | _x_x_mozilla_page_load_details 22:20:32 INFO - PROCESS | 3384 | |i|pagename|runs| 22:20:32 INFO - PROCESS | 3384 | |0;dojo.html;3023.0477399619117;3026.697330266972;3053.5572342010414;3058.3118798270407;3086.243870251789;5236.031334203175;5243.449437809032;5246.5278124968845;5321.095413779657;5347.6791392516425;5227.817026404076;5319.813806516772;5326.813561525348;5356.491133342336;5377.274068000828;4794.6404019698575;4831.782569784368;4880.389951256076;4884.633652476061;5026.547610715035;4945.67853089548;4955.033768415161;4989.900201995958;5003.274525193845;5007.674501157431;4727.527247275262;4814.711117332971;4870.342503761982;4888.9755551222015;4970.552650261476;4809.8456370471185;4814.1334559779225;4818.060478206751;4828.758562071917;4919.065377578248;4834.202356611155;4862;4868.9756551217015;4879.682820616648;4897.7795999180125;5255.290535777659;5282.603804714662;5301.3373328333955;5313.203019547061;5354.544863686552;5695.060315048012;5705.657660540381;5705.686187259699;5731.484166425042;5743.4256574342235;5741.712914354307;5744.712764361765;5772.787714579943;5779.306483221998;5782.045962416196;5169.922451163236;5241.711705856176;5271.551918086929;5277.973610131925;5374.811881584125;5863.912041319383;5870.236869207061;5879.882402351929;5883.14694369314;5885.411458854081;5633.352164501053;5635;5639.689817060101;5640.294963129609;5643.1535269709875;5537.390887002426;5585.385607583163;5641.12562552805;5657.12314591239;5757.992351338499;5674.914876276859;5675.432456754292;5680.6591604503865;5681.460261275172;5683.175939488834;5636.718164091779;5671.971640141773;5684.715764211773;5687.857803554862;5706.657600543981;4236.533981262059;4320.891977700583;4322.005938634132;4353.542877997828;4367.366731823868;3843.5387753469763;3884.2037382336553;3913.9804300978312;3929.174873276644;3931.9017024574614;5468.48049435303;5476.67139971603;5498.752556134984;5504.614676972654;5517.862053448615;5764.221830052973;5769.94230057694;5771.624844385102;5772.076467765138;5779.479846813807;5259.316288882421;5376.516113549799;5400.567954563626;5441.102218133963;5461.672299662033;4844.8304309349005;4876.8780780480065;4884.7069175849565;4884.926726099111;4931.161702510582;5900.43945825146;5916.585838991229;5917.31950825652;5920.141579471038;5934.347221805684;5534.280543529316;5652.067408877653;5694.231278777312;5711.571632127524;5754.6547207167705;5401.810936617357;5935.614185077956;5941.257342832146;5991.3409524953095;6009.038553831366;5579.079451890392;5599.936012157677;5611.607187496836;5612.242347283064;5633.605647604629;5556.638818476948;5618.831435056954;5732.082866741302;5735.97132014354;5743.971280143573;5531.9723401382735;5591.636543624814;5593.804216852391;5630.071038278638;5634.267545219095;5556.722163891708;5560.221568980265;5561.249231353715;5565.443455654402;5569.55443564522;5461.453854614507;5461.590380721541;5552.3892371839875;5559.360673522597;5586.776528938797;5557.749901254372;5590.413006634245;5590.580706446952;5646.830595082154;5653.434656534314;5444.346678398618;5453;5483.479069488392;5485.917711234414;5489.560835133259 22:20:32 INFO - PROCESS | 3384 | |1;ext.html;608.9665068421237;623.9313675495695;637.5123030881374;639.9968000160004;652.9510286728497;44263.77868110659;46057.76971115144;46277.76861115698;49105.50894491054;49167.50832491683;46062.53937460625;46523.534764652344;46523.767381163045;46566.534334656644;46589;8999.325050621197;9117.407368521024;9125.954370228139;9137.725868223964;9139.223166030863;8897.911020889773;8975.820483590323;8976;8982.101789820998;8986.955065224698;9043.412178208428;9075.591598378089;9081.137291957286;9085.409448385833;9088.045755195708;9039.412438191497;9062.139096785795;9068.138526839939;9076;9077.863832042525;8907.465552066897;8975.730728078168;8981.865272020925;8985.32610054242;8985.955070224607;44141.33787993182;44286;44436.11127777426;44534.33198502025;44594.777026114985;3524.9823750881337;3548.432250839853;3588.982055089708;3671.8714844980564;4007.0583412897945;33695.4945675815;34280.65719342799;34544.82727586371;34574.6542534574;34773;4453.354263631788;4472.239719247703;4479.238529450001;4483.4843992940905;4577.862664120082;269429;269775;271590;271770;274579;59431;59773.40226597765;59843;59866.40133598696;59918.40081599215;58124;58316.708416457644;58373.416265837644;58375.832483350096;58509.70745146247;14817.77773333401;14845.96078274532;14846;14891.106533608017;14893.404263829325;13303.600891973256;13365.198088114745;13385.263810490516;13385.933070334586;13387.531436399679;15158.877757114013;16994.87564054493;17192.31230750756;17259.913700431418;17377.73933391;6557.737690492327;6603.438707709898;6677.632730199885;6709.161354830646;6771.085903402977;974.7758015656439;1019.7762684778296;1023.4831410137981;1069.534752382716;1125.6285425809463;32951.50572741411;32953;32995.505067424005;33207.3358532828;33265.66734332674;1072.6353039966293;1120.5293776613842;1126.1441304608556;1142.4230763464398;1225.7058306006495;16417.343306267616;17342.219600118024;17551.91224043897;17557.297708091788;17576.824231757775;538862;540706;541965;543748;545430;59813;59868;59882;59937.40062599405;59981;1261.1424231522653;1363.754524185656;1372.2726954714017;1379.9379027943767;1400.103933482572;31858.6814131865;34274;34328.313433730684;34530.82734586411;34844.477332839524;50738.238926416874;51063.74468127636;51256.48743512517;51314.48685513097;51353.7432312836;373842;374970;376866;377615;379742;32215.677843222205;41327;42371;42593.14813703771;44995.77502112468;48334.758326208146;48760;48934.510654893;49079.26381104358;49212;42221.788891055345;42867.57132428636;43302.78348608363;43626.78186609047;44818.103637927714;42663.57336426596;43350.34974475449;43734.42170385683;43813.34279985739;43917.560824392625;41415.58584414118;41672.79163604163;42004;42064.78967605143;42205.78897105495;35515.82242088773;37928.62071379251;37956.810215948746;38071.809640951615;38669.806650967694;28672;28733.425331492836;28809.56785648259;29029.274268143465;29216.123516294545 22:20:32 INFO - PROCESS | 3384 | |2;jquery.html;378.9981050094749;388.896942310288;393.56904189912046;396.92656858481183;419.7943007926115;14489.420423183086;17909.373171938983;18394.264229430824;19776.30782922598;19779.703304450446;15881.920590397061;18808;19209.231630734754;19466.318678846244;19691.803081969178;5711.200431939521;5876.2067120938555;5885.558583106263;5887.882242355172;5890.469857712805;5711.343195532525;5749.396313387097;5827.912581311284;5866.589338746289;5868.589198756089;5626.578006649497;5745.454181852718;5751.166080918264;5755.0504166812425;5779.797707080253;5684.090545512719;5736.053551164053;5743.3108027036815;5750.194972703804;5782.739776710058;5047.747612619354;5170.586353091744;5177.844664660066;5178.14560597501;5181;23126.42183945398;23227.883860580758;23439.88280058606;24024.639630405556;24251.757482425128;2357.4695693469002;2425.1148330859287;2496.226169887341;2523.470071285032;2544.211294498711;14427;14609.123452592772;15093.39626414953;15360.846391536053;15411.460598878986;1855.4711907106546;1880.5392678793646;1914.8563857710728;1921.3755529452844;1948;75649.62175189143;76556.23443765547;77039.61480192619;78043.6097819513;79232;10321.129576408846;10439.321444106108;10589.099926506333;10839.393257865375;10981.780364392667;14724.705505890037;14747.336369863382;14763.188024658746;14770;14772.113673179643;7926.801829954297;8214.548199848947;8343.582820858932;8424.073351931283;8477.576121194039;7911.762647120596;7956.2043795619975;7979.9601001994615;8277.089520152778;8331.66673333072;5998.220231369894;6345.238571371465;6692.564983276072;6760.966195169091;6761.560498567577;1810.3030333321503;1873.4731194076783;1909.944089456858;1918.0697361779526;1957.9902100489692;922.847730124522;1010.4745532323298;1028.4292217819032;1028.783955369381;1033.2199189611795;18214;18874.716879246822;19414.611707765765;20745.170193192404;20763.169473221205;989.2877128467492;1006.1900170362782;1006.4062203300089;1014.7017006480328;1015.8679371681782;10911.83632245517;11740;11795.469203885847;11798.41007949599;11805.645830625095;492552;495790;499198;499401;503850;92155;95223;96510;96561.5171924136;96630;997.0677416615488;1046.2937517175876;1055.9630412935664;1071.2233630617895;1075.0700643942932;17305.913470432566;17965.19705344398;18598.907005464887;18607.720884186478;18838.3406580769;41177.97055073647;43765.34351984659;43965;44860.32709509425;45607;50183;57941.4205857936;59070.70464647649;59667.104993426;61936.38063619306;8458.238758511518;8588.914110858812;8640.78398040054;8672.78318042054;9069.591868365807;10021.549030293509;13039.608811735663;13502.729945401234;13611.727765444437;13651.249181295125;10586.576536938745;11349.602763903224;11829.585964491202;12120.454579544117;12220.266783992989;10782.514786834454;10835.187360947803;11157.66527004191;11440.199186056896;11613.941930290295;11406.54373825071;11480.425978701198;11503.194776365573;11573.537058517903;12501.687457813627;8477;8480.982282126184;8505.617247223767;8535.573221339033;8611.181937715906;10459.581616735246;10810.405427701551;10898;11014.50434730423;11725.941370293094 22:20:32 INFO - PROCESS | 3384 | |3;mootools.html;1356.0168877563774;1459.0734883349073;1529.8929074964753;1628.6824069306483;1789.919453624586;8561.957190214049;9007.369484136114;9049.276057915367;9344.299177561676;9371.34400591959;9079.18287354138;9206.125418085272;9221.861672074925;9223.769405764859;9229.923267264861;2447.412620970972;2495;2495.8252922295446;2496.1887386599337;2497.126005897939;2353.329301149173;2479.417336925821;2481.6773819403543;2482.2801387597665;2484.763947425001;2390.247072172269;2413.5655581995234;2413.9034438622434;2421.055788242587;2421.1162925532144;2389.7968672662846;2391.0316321889663;2392.28231530541;2416.5046165536023;2451.877406129704;2367.893444794971;2420.794232490223;2422.212780846232;2423.3820375804235;2427.55560441537;8957.328200384933;9141.497217653026;9305.534723263878;9315.953420232923;9318.580663870142;5356.437574054746;5586.273784408001;5589.636673616202;5605;5605.691686957216;3521.0845180253064;3567.6253993330847;3609.855605775766;3633.4913112164318;3643.0710168907026;1169.2808922512593;1180.8110702287593;1193.665773583398;1204.9277043377426;2119.925802596917;12413.317267550281;12859.228446293253;12866.614001579968;12877.098603097787;12913;4612.5156858530045;4685.1800934836265;4718.174319494075;4839.612830973514;5082.847514574569;9995.650152244636;10138.03688649577;10144.239182061376;10144.44205568686;10144.847827282596;5525.419830917776;5526.392096869341;5526.778928842882;5528.474794894479;5529.198266251451;4527.909441811145;4653.2554791233915;5043.142665746832;5117.309163262987;5163.044836705221;3862.2661694278;3922.980385098056;3939.1333906540517;4005.559388467266;4038.394240863894;1153.6077733570626;1156.0462618339902;1158.4265788434611;1166.2302880099094;1174.900133488646;776.6155752902349;786.8741001439856;829.3613917283658;940.3934462271797;1012.7670635753818;5864.912026319608;5903;6003.849903752441;6052.6671033092725;6110.572259941761;942.5475771629656;963.0513943765385;988.6786794291884;996.3424140067666;1015.9542820573092;3918.45141680165;4028.879133625996;4105.384192371168;4118.19695159447;4141.855035073758;11258.493367798468;11684.70788230284;11701.414929253504;11702.76594468106;11704.414779261004;11331.43342832855;11504;11656.125790565742;11670.70823229426;11672.88327116735;8261.958690206511;8548.102449242864;8567.571621419029;8676.69631562905;8702.695405660772;5922.1412895130825;6092.268927728701;6135.233095863017;6141.692915354304;6445.709943052668;10403.843942341022;10434.37393756377;10438.060574548326;10463.215258855464;10495.10791582724;9316.385440669934;10266.230032747424;10407.427591482541;10839.945800270949;10925.508352124332;7774.689012439665;8128.83742325162;8176;8180.7954801130445;8186.222308880885;8898.199162075185;8913.643454261757;8964.596593153194;8965.50689712072;8971.955140224258;7950.284474397101;7961.761147165595;7967.960160199162;8128;8153.8369232616205;7913.881291780743;7961.363090952594;8069.031716194094;8121.066077400938;8296.211859873303;7225.313595208447;7334.193238743841;7334.339909408179;7334.889976650461;7336.523125996793;5101.566366858859;5374.543163831118;5392.892142157213;5409.918851217156;5623.634463759842;5340.2256672783005;5391.59563032782;5404.7297635118875;5449;5537.7784888604 22:20:32 INFO - PROCESS | 3384 | |4;prototype.html;323.5211886408116;330.6511630230107;337.5881424661913;338.57001607957886;340.6287147009759;5111.821086261982;5141;5159.896802063961;5178.741062946857;5207.349081364834;4837.516248375169;4845.806167753294;5034.144195486767;5066.569341605968;5092.618053645972;4038.5759495253014;4062.2078694654556;4068.41008053832;4068.776217308047;4076.51081870176;4042.6159514846045;4057.431959525669;4062.1469491406842;4106.77412742299;4121.4848143982;3847.980760096196;3872.2255548890193;3994.0813612869056;4010.739301945379;4028.6172813582807;4019.799010049515;4035.475388199545;4044.4539987101807;4057.6348128668415;4077.1030373318117;3598.8200589970397;3600.963990360089;3603.1172362771026;3605.296967091418;3611.368010598135;4799.688020278706;4999.700017998932;5005.124103281947;5007.574356179729;5032.043911656773;4529.569690879362;4547.749873756942;4596.35651008859;4607.193741095295;4615.72305661658;5269.5772141521575;5288.233206185082;5299.814506492255;5353.9196912046355;5372.489613486712;3766.305162676779;3828.602560065556;3840.6735427488697;3844.903877403059;3846.769193848378;5450.972745136289;5460.726963651801;5493.505584497414;5502.5597952163735;5517.227588137664;3917.588653191374;3923.509561304837;3924.293627147084;3928.64642182205;3934;3959.425883246913;3967.900802479903;3971.6028397160053;4004.099077707551;4004.899877503086;4400.273954797422;4524.389207457017;4526.660500462479;4526.773661316921;4535.886602834956;5293.682379057269;5327.547158491571;5333.546648534918;5345.86635334112;5364.758585863646;1349.7165595225113;1355.2884735513737;1357.172125003747;1358.8165597644388;1360.4286199796109;883.5273128876149;925.8611208318671;926.9212116969999;946.9100435458621;950.8240975419432;5124.948750512522;5141.357330333708;5164;5212.166053431509;5225.372955245395;5433.728313584305;5436.21174929633;5445.428230035868;5481.479259470344;5486.396496385394;5037.244413338029;5115.923261151086;5121.8719532012;5129.615278854101;5162.070827251056;2829.5189817731034;2851.3869518053716;2861.828290302547;2867.813592116506;2870.2393865625627;5416.837494875082;5639.6334238274385;5647.463490968352;5648.378678345379;5663.518600918968;5608.9439105609235;5612.1581762735095;5620.100783874561;5622.469888550168;5643.322801263875;5725.599208055396;5738.196652468658;5738.397468265855;5825.359210486843;5857.740585774079;5433.864153396039;5468.967713682819;5470.893705923168;5524.309461317335;5610.635308704921;5471.398146203835;5471.480209380103;5476.2333273342565;5482.945170548243;5487.451254874481;5612.326520817528;5766.740496677576;5786.971065144816;5793.275840519935;5817.2146760188525;3875.593062728373;3906.2187562487047;3913.1782325710783;3916.118873253495;3922.960770392374;5459.317585301837;5472.91790623133;5475.288212532505;5505.284313039279;5559.8888022240135;5254.211868219798;5496.560275178056;5497.972510137423;5505.174223866452;5505.697186654773;5286.233496143114;5341.305630268041;5357.151782634985;5362.892742145214;5374.7581358839725;5210.687358758335;5281.313429254173;5347.5721942243745;5357.651752636066;5363.731813409392;5270.472952704698;5346.465353464622;5351.625386222927;5370.355557333145;5390.946090539044;5212.218167274939;5244.295603928815;5277.788888444419;5297.602679799107;5315.654482458628 22:20:32 INFO - PROCESS | 3384 | |5;yui.html;759.3962799574338;771.6758961236284;772.0812233442203;773.2229109744706;806.4838503357848;15661.451849185281;16316.673666526662;16318.755218671715;16354.427595034162;16412.425565105223;15203.163825989594;15320.540383788504;15331;15351.769723454128;15356.385744570203;668.0346898731335;809.7408829174666;830.8379865926147;836.7782537627534;842.4061036968923;834.1658341658342;837.5937670229933;838.4801423117675;838.9706360277391;839.1440730454932;889.8976617688952;890.5057692980394;894.552723638181;895.2971917045121;895.7939673875013;704.3625518905374;722.3246264742456;727.4544091931052;832.7168762620678;888.076400543434;634.2769243062912;634.7492740367529;635.1235295292466;635.6599219417635;635.7870113511979;15898;15909.681806363924;15919.602009949727;15936;15946.840531594768;14877.47928822486;14895.180765057918;15038.02252853561;15161;15177.924110379488;13734.587962361145;13879.167249964934;13888.166709997433;13971.161730296113;14649.633759156;4551.613112885409;4559.247724125516;4603.171429142753;4665.580097791215;4710.552497512731;86328.56835715781;86781.13218867793;86816.56591717064;87032.56483717605;89089.55455222748;11970.58102966392;11976.401179941142;12061.698457538456;12168.087393445527;12474.81287780684;22548.774512254997;22586.7577283251;22834.657480137812;22842.771572284397;22865.31404057881;8904.73285801427;9084.500352480545;9392.577334019848;9427.528623568795;9571.952140239255;4693.812247510062;4874;4903.166461701519;4916.459189489154;5081.669691470117;3533.399322115195;3588.5334906461994;3612.710983121344;3636.9271065035878;3640.5631324241263;604.7792555717132;615.3876892491999;619.3465893482329;630.130420020369;631.9494440444753;625.1497962770628;644.24301445801;698.2040473859804;727.0802434919831;742.4913933955258;9400.24798016157;9665.613375464902;9689.41863488193;9784.608615655294;9825.017498250118;587.4771453406472;632.6046221111806;682.0621645237798;690.268315585481;725.4740313272835;4694.901156043472;4881.633877459205;4954.702717836869;4974.1797921461075;5003.724795136302;66303.33696663068;66607;66937.33062669408;67425.66287168533;101602;95294.52352738331;96155;96298.51850740841;96500.51749741206;98866;50330;51182.232266516774;52525.73737131366;55783;87789;9383.812323753351;9393.342466027585;9710.902890971;9729.412233195184;9733.561989710339;41443.58556414518;42928.57071429371;43742;49992;50553.24170137377;46959.765201173774;63725.044124339096;64774.028389575134;66777.66611166912;78374;13654.180749155083;13831.239281839595;13936;13959.371828267955;13961.720765584834;15644.38797288065;15693.529194124194;15757.606059848595;16050.839491604935;16762.078085705405;14386.928065360025;14393.424263029361;14526.709465810836;14558.835293176733;15616.92191539035;13356.532521362094;13572.321383930566;13867.167969921837;14619.780703289673;15096.622584435478;12453;12955.562420896416;13207.933960330136;13894.930525347309;14736.926315368355;11365.602203922823;11572.888756574577;12121.696957575778;12149.271043737404;12167.87832121703;11593.362365069665;11873.643790686294;12112.636620901387;12267.570635028089;12576.182548134342 22:20:32 INFO - PROCESS | 3384 | __end_tp_report 22:20:32 INFO - PROCESS | 3384 | __start_cc_report 22:20:32 INFO - PROCESS | 3384 | _x_x_mozilla_cycle_collect,1053 22:20:32 INFO - PROCESS | 3384 | __end_cc_report 22:20:32 INFO - PROCESS | 3384 | __startTimestamp1470288032181__endTimestamp 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | ------- Summary: start ------- 22:20:32 INFO - PROCESS | 3384 | Number of tests: 6 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#0] dojo.html Cycles:165 Average:5299.37 Median:5517.86 stddev:605.89 (11.0%) stddev-sans-first:580.83 22:20:32 INFO - PROCESS | 3384 | Values: 3023.0 3026.7 3053.6 3058.3 3086.2 5236.0 5243.4 5246.5 5321.1 5347.7 5227.8 5319.8 5326.8 5356.5 5377.3 4794.6 4831.8 4880.4 4884.6 5026.5 4945.7 4955.0 4989.9 5003.3 5007.7 4727.5 4814.7 4870.3 4889.0 4970.6 4809.8 4814.1 4818.1 4828.8 4919.1 4834.2 4862.0 4869.0 4879.7 4897.8 5255.3 5282.6 5301.3 5313.2 5354.5 5695.1 5705.7 5705.7 5731.5 5743.4 5741.7 5744.7 5772.8 5779.3 5782.0 5169.9 5241.7 5271.6 5278.0 5374.8 5863.9 5870.2 5879.9 5883.1 5885.4 5633.4 5635.0 5639.7 5640.3 5643.2 5537.4 5585.4 5641.1 5657.1 5758.0 5674.9 5675.4 5680.7 5681.5 5683.2 5636.7 5672.0 5684.7 5687.9 5706.7 4236.5 4320.9 4322.0 4353.5 4367.4 3843.5 3884.2 3914.0 3929.2 3931.9 5468.5 5476.7 5498.8 5504.6 5517.9 5764.2 5769.9 5771.6 5772.1 5779.5 5259.3 5376.5 5400.6 5441.1 5461.7 4844.8 4876.9 4884.7 4884.9 4931.2 5900.4 5916.6 5917.3 5920.1 5934.3 5534.3 5652.1 5694.2 5711.6 5754.7 5401.8 5935.6 5941.3 5991.3 6009.0 5579.1 5599.9 5611.6 5612.2 5633.6 5556.6 5618.8 5732.1 5736.0 5744.0 5532.0 5591.6 5593.8 5630.1 5634.3 5556.7 5560.2 5561.2 5565.4 5569.6 5461.5 5461.6 5552.4 5559.4 5586.8 5557.7 5590.4 5590.6 5646.8 5653.4 5444.3 5453.0 5483.5 5485.9 5489.6 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#1] ext.html Cycles:180 Average:57496.30 Median:43865.45 stddev:109173.07 (248.9%) stddev-sans-first:109395.78 22:20:32 INFO - PROCESS | 3384 | Values: 609.0 623.9 637.5 640.0 653.0 44263.8 46057.8 46277.8 49105.5 49167.5 46062.5 46523.5 46523.8 46566.5 46589.0 8999.3 9117.4 9126.0 9137.7 9139.2 8897.9 8975.8 8976.0 8982.1 8987.0 9043.4 9075.6 9081.1 9085.4 9088.0 9039.4 9062.1 9068.1 9076.0 9077.9 8907.5 8975.7 8981.9 8985.3 8986.0 44141.3 44286.0 44436.1 44534.3 44594.8 3525.0 3548.4 3589.0 3671.9 4007.1 33695.5 34280.7 34544.8 34574.7 34773.0 4453.4 4472.2 4479.2 4483.5 4577.9 269429.0 269775.0 271590.0 271770.0 274579.0 59431.0 59773.4 59843.0 59866.4 59918.4 58124.0 58316.7 58373.4 58375.8 58509.7 14817.8 14846.0 14846.0 14891.1 14893.4 13303.6 13365.2 13385.3 13385.9 13387.5 15158.9 16994.9 17192.3 17259.9 17377.7 6557.7 6603.4 6677.6 6709.2 6771.1 974.8 1019.8 1023.5 1069.5 1125.6 32951.5 32953.0 32995.5 33207.3 33265.7 1072.6 1120.5 1126.1 1142.4 1225.7 16417.3 17342.2 17551.9 17557.3 17576.8 538862.0 540706.0 541965.0 543748.0 545430.0 59813.0 59868.0 59882.0 59937.4 59981.0 1261.1 1363.8 1372.3 1379.9 1400.1 31858.7 34274.0 34328.3 34530.8 34844.5 50738.2 51063.7 51256.5 51314.5 51353.7 373842.0 374970.0 376866.0 377615.0 379742.0 32215.7 41327.0 42371.0 42593.1 44995.8 48334.8 48760.0 48934.5 49079.3 49212.0 42221.8 42867.6 43302.8 43626.8 44818.1 42663.6 43350.3 43734.4 43813.3 43917.6 41415.6 41672.8 42004.0 42064.8 42205.8 35515.8 37928.6 37956.8 38071.8 38669.8 28672.0 28733.4 28809.6 29029.3 29216.1 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#2] jquery.html Cycles:180 Average:29415.85 Median:13191.05 stddev:82085.22 (622.3%) stddev-sans-first:82286.54 22:20:32 INFO - PROCESS | 3384 | Values: 379.0 388.9 393.6 396.9 419.8 14489.4 17909.4 18394.3 19776.3 19779.7 15881.9 18808.0 19209.2 19466.3 19691.8 5711.2 5876.2 5885.6 5887.9 5890.5 5711.3 5749.4 5827.9 5866.6 5868.6 5626.6 5745.5 5751.2 5755.1 5779.8 5684.1 5736.1 5743.3 5750.2 5782.7 5047.7 5170.6 5177.8 5178.1 5181.0 23126.4 23227.9 23439.9 24024.6 24251.8 2357.5 2425.1 2496.2 2523.5 2544.2 14427.0 14609.1 15093.4 15360.8 15411.5 1855.5 1880.5 1914.9 1921.4 1948.0 75649.6 76556.2 77039.6 78043.6 79232.0 10321.1 10439.3 10589.1 10839.4 10981.8 14724.7 14747.3 14763.2 14770.0 14772.1 7926.8 8214.5 8343.6 8424.1 8477.6 7911.8 7956.2 7980.0 8277.1 8331.7 5998.2 6345.2 6692.6 6761.0 6761.6 1810.3 1873.5 1909.9 1918.1 1958.0 922.8 1010.5 1028.4 1028.8 1033.2 18214.0 18874.7 19414.6 20745.2 20763.2 989.3 1006.2 1006.4 1014.7 1015.9 10911.8 11740.0 11795.5 11798.4 11805.6 492552.0 495790.0 499198.0 499401.0 503850.0 92155.0 95223.0 96510.0 96561.5 96630.0 997.1 1046.3 1056.0 1071.2 1075.1 17305.9 17965.2 18598.9 18607.7 18838.3 41178.0 43765.3 43965.0 44860.3 45607.0 50183.0 57941.4 59070.7 59667.1 61936.4 8458.2 8588.9 8640.8 8672.8 9069.6 10021.5 13039.6 13502.7 13611.7 13651.2 10586.6 11349.6 11829.6 12120.5 12220.3 10782.5 10835.2 11157.7 11440.2 11613.9 11406.5 11480.4 11503.2 11573.5 12501.7 8477.0 8481.0 8505.6 8535.6 8611.2 10459.6 10810.4 10898.0 11014.5 11725.9 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#3] mootools.html Cycles:180 Average:6021.45 Median:4941.38 stddev:3432.83 (69.5%) stddev-sans-first:3424.55 22:20:32 INFO - PROCESS | 3384 | Values: 1356.0 1459.1 1529.9 1628.7 1789.9 8562.0 9007.4 9049.3 9344.3 9371.3 9079.2 9206.1 9221.9 9223.8 9229.9 2447.4 2495.0 2495.8 2496.2 2497.1 2353.3 2479.4 2481.7 2482.3 2484.8 2390.2 2413.6 2413.9 2421.1 2421.1 2389.8 2391.0 2392.3 2416.5 2451.9 2367.9 2420.8 2422.2 2423.4 2427.6 8957.3 9141.5 9305.5 9316.0 9318.6 5356.4 5586.3 5589.6 5605.0 5605.7 3521.1 3567.6 3609.9 3633.5 3643.1 1169.3 1180.8 1193.7 1204.9 2119.9 12413.3 12859.2 12866.6 12877.1 12913.0 4612.5 4685.2 4718.2 4839.6 5082.8 9995.7 10138.0 10144.2 10144.4 10144.8 5525.4 5526.4 5526.8 5528.5 5529.2 4527.9 4653.3 5043.1 5117.3 5163.0 3862.3 3923.0 3939.1 4005.6 4038.4 1153.6 1156.0 1158.4 1166.2 1174.9 776.6 786.9 829.4 940.4 1012.8 5864.9 5903.0 6003.8 6052.7 6110.6 942.5 963.1 988.7 996.3 1016.0 3918.5 4028.9 4105.4 4118.2 4141.9 11258.5 11684.7 11701.4 11702.8 11704.4 11331.4 11504.0 11656.1 11670.7 11672.9 8262.0 8548.1 8567.6 8676.7 8702.7 5922.1 6092.3 6135.2 6141.7 6445.7 10403.8 10434.4 10438.1 10463.2 10495.1 9316.4 10266.2 10407.4 10839.9 10925.5 7774.7 8128.8 8176.0 8180.8 8186.2 8898.2 8913.6 8964.6 8965.5 8972.0 7950.3 7961.8 7968.0 8128.0 8153.8 7913.9 7961.4 8069.0 8121.1 8296.2 7225.3 7334.2 7334.3 7334.9 7336.5 5101.6 5374.5 5392.9 5409.9 5623.6 5340.2 5391.6 5404.7 5449.0 5537.8 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#4] prototype.html Cycles:180 Average:4524.31 Median:5171.37 stddev:1326.89 (25.7%) stddev-sans-first:1292.61 22:20:32 INFO - PROCESS | 3384 | Values: 323.5 330.7 337.6 338.6 340.6 5111.8 5141.0 5159.9 5178.7 5207.3 4837.5 4845.8 5034.1 5066.6 5092.6 4038.6 4062.2 4068.4 4068.8 4076.5 4042.6 4057.4 4062.1 4106.8 4121.5 3848.0 3872.2 3994.1 4010.7 4028.6 4019.8 4035.5 4044.5 4057.6 4077.1 3598.8 3601.0 3603.1 3605.3 3611.4 4799.7 4999.7 5005.1 5007.6 5032.0 4529.6 4547.7 4596.4 4607.2 4615.7 5269.6 5288.2 5299.8 5353.9 5372.5 3766.3 3828.6 3840.7 3844.9 3846.8 5451.0 5460.7 5493.5 5502.6 5517.2 3917.6 3923.5 3924.3 3928.6 3934.0 3959.4 3967.9 3971.6 4004.1 4004.9 4400.3 4524.4 4526.7 4526.8 4535.9 5293.7 5327.5 5333.5 5345.9 5364.8 1349.7 1355.3 1357.2 1358.8 1360.4 883.5 925.9 926.9 946.9 950.8 5124.9 5141.4 5164.0 5212.2 5225.4 5433.7 5436.2 5445.4 5481.5 5486.4 5037.2 5115.9 5121.9 5129.6 5162.1 2829.5 2851.4 2861.8 2867.8 2870.2 5416.8 5639.6 5647.5 5648.4 5663.5 5608.9 5612.2 5620.1 5622.5 5643.3 5725.6 5738.2 5738.4 5825.4 5857.7 5433.9 5469.0 5470.9 5524.3 5610.6 5471.4 5471.5 5476.2 5482.9 5487.5 5612.3 5766.7 5787.0 5793.3 5817.2 3875.6 3906.2 3913.2 3916.1 3923.0 5459.3 5472.9 5475.3 5505.3 5559.9 5254.2 5496.6 5498.0 5505.2 5505.7 5286.2 5341.3 5357.2 5362.9 5374.8 5210.7 5281.3 5347.6 5357.7 5363.7 5270.5 5346.5 5351.6 5370.4 5390.9 5212.2 5244.3 5277.8 5297.6 5315.7 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - PROCESS | 3384 | [#5] yui.html Cycles:180 Average:19101.46 Median:4935.58 stddev:25368.11 (514.0%) stddev-sans-first:25401.88 22:20:32 INFO - PROCESS | 3384 | Values: 759.4 771.7 772.1 773.2 806.5 15661.5 16316.7 16318.8 16354.4 16412.4 15203.2 15320.5 15331.0 15351.8 15356.4 668.0 809.7 830.8 836.8 842.4 834.2 837.6 838.5 839.0 839.1 889.9 890.5 894.6 895.3 895.8 704.4 722.3 727.5 832.7 888.1 634.3 634.7 635.1 635.7 635.8 15898.0 15909.7 15919.6 15936.0 15946.8 14877.5 14895.2 15038.0 15161.0 15177.9 13734.6 13879.2 13888.2 13971.2 14649.6 4551.6 4559.2 4603.2 4665.6 4710.6 86328.6 86781.1 86816.6 87032.6 89089.6 11970.6 11976.4 12061.7 12168.1 12474.8 22548.8 22586.8 22834.7 22842.8 22865.3 8904.7 9084.5 9392.6 9427.5 9572.0 4693.8 4874.0 4903.2 4916.5 5081.7 3533.4 3588.5 3612.7 3636.9 3640.6 604.8 615.4 619.3 630.1 631.9 625.1 644.2 698.2 727.1 742.5 9400.2 9665.6 9689.4 9784.6 9825.0 587.5 632.6 682.1 690.3 725.5 4694.9 4881.6 4954.7 4974.2 5003.7 66303.3 66607.0 66937.3 67425.7 101602.0 95294.5 96155.0 96298.5 96500.5 98866.0 50330.0 51182.2 52525.7 55783.0 87789.0 9383.8 9393.3 9710.9 9729.4 9733.6 41443.6 42928.6 43742.0 49992.0 50553.2 46959.8 63725.0 64774.0 66777.7 78374.0 13654.2 13831.2 13936.0 13959.4 13961.7 15644.4 15693.5 15757.6 16050.8 16762.1 14386.9 14393.4 14526.7 14558.8 15616.9 13356.5 13572.3 13867.2 14619.8 15096.6 12453.0 12955.6 13207.9 13894.9 14736.9 11365.6 11572.9 12121.7 12149.3 12167.9 11593.4 11873.6 12112.6 12267.6 12576.2 22:20:32 INFO - PROCESS | 3384 | -------- Summary: end -------- 22:20:32 INFO - PROCESS | 3384 | 22:20:32 INFO - TEST-INFO | 3384: exit 0 22:20:32 INFO - TEST-OK | dromaeo_css | took 1117794ms 22:20:32 INFO - TEST-START | kraken 22:20:32 INFO - operating with platform_type : linux_ 22:20:32 INFO - Initialising browser for kraken test... 22:20:32 INFO - TEST-INFO | started process 3580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Lh93S/profile http://localhost:52353/getInfo.html) 22:20:37 INFO - PROCESS | 3580 | __metrics Screen width/height:1600/1200 22:20:37 INFO - PROCESS | 3580 | colorDepth:24 22:20:37 INFO - PROCESS | 3580 | Browser inner width/height: 1024/697 22:20:37 INFO - PROCESS | 3580 | __metrics 22:20:38 INFO - PROCESS | 3580 | 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" 22:20:38 INFO - PROCESS | 3580 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:20:38 INFO - TEST-INFO | 3580: exit 0 22:20:38 INFO - Browser initialized. 22:20:38 INFO - Running cycle 1/1 for kraken test... 22:20:38 INFO - TEST-INFO | started process 3661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Lh93S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:50 INFO - PROCESS | 3661 | RSS: Main: 156528640 22:20:50 INFO - PROCESS | 3661 | 22:20:53 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52353/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:54 INFO - PROCESS | 3661 | RSS: Main: 213090304 22:20:54 INFO - PROCESS | 3661 | 22:20:56 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52353/tests/kraken/driver.html?testName=audio-dft) 22:20:57 INFO - PROCESS | 3661 | RSS: Main: 191512576 22:20:57 INFO - PROCESS | 3661 | 22:21:00 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52353/tests/kraken/driver.html?testName=audio-fft) 22:21:01 INFO - PROCESS | 3661 | RSS: Main: 268906496 22:21:01 INFO - PROCESS | 3661 | 22:21:03 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52353/tests/kraken/driver.html?testName=audio-oscillator) 22:21:03 INFO - PROCESS | 3661 | RSS: Main: 180183040 22:21:03 INFO - PROCESS | 3661 | 22:21:05 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52353/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:21:06 INFO - PROCESS | 3661 | RSS: Main: 184229888 22:21:06 INFO - PROCESS | 3661 | 22:21:09 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52353/tests/kraken/driver.html?testName=imaging-darkroom) 22:21:10 INFO - PROCESS | 3661 | RSS: Main: 218996736 22:21:10 INFO - PROCESS | 3661 | 22:21:13 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52353/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:13 INFO - PROCESS | 3661 | RSS: Main: 219627520 22:21:13 INFO - PROCESS | 3661 | 22:21:16 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52353/tests/kraken/driver.html?testName=json-parse-financial) 22:21:17 INFO - PROCESS | 3661 | RSS: Main: 219779072 22:21:17 INFO - PROCESS | 3661 | 22:21:18 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52353/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:19 INFO - PROCESS | 3661 | RSS: Main: 184307712 22:21:19 INFO - PROCESS | 3661 | 22:21:21 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:21 INFO - PROCESS | 3661 | RSS: Main: 189935616 22:21:21 INFO - PROCESS | 3661 | 22:21:23 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:24 INFO - PROCESS | 3661 | RSS: Main: 194686976 22:21:24 INFO - PROCESS | 3661 | 22:21:27 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:27 INFO - PROCESS | 3661 | RSS: Main: 187596800 22:21:27 INFO - PROCESS | 3661 | 22:21:30 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:31 INFO - PROCESS | 3661 | RSS: Main: 193077248 22:21:31 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | Cycle 1(1): loaded http://localhost:52353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52353/tests/kraken/driver.html?testName=ai-astar) 22:21:32 INFO - PROCESS | 3661 | RSS: Main: 243875840 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | __start_tp_report 22:21:32 INFO - PROCESS | 3661 | _x_x_mozilla_page_load 22:21:32 INFO - PROCESS | 3661 | _x_x_mozilla_page_load_details 22:21:32 INFO - PROCESS | 3661 | |i|pagename|runs| 22:21:32 INFO - PROCESS | 3661 | |0;ai-astar;100;112;112;110;112;110;109;111;110;110 22:21:32 INFO - PROCESS | 3661 | |1;audio-beat-detection;151;117;121;176;118;118;143;116;163;117 22:21:32 INFO - PROCESS | 3661 | |2;audio-dft;161;159;160;160;161;160;159;161;161;160 22:21:32 INFO - PROCESS | 3661 | |3;audio-fft;75;75;75;111;76;75;75;75;105;74 22:21:32 INFO - PROCESS | 3661 | |4;audio-oscillator;82;83;110;84;85;83;117;83;85;135 22:21:32 INFO - PROCESS | 3661 | |5;imaging-gaussian-blur;117;117;116;117;117;118;119;118;116;117 22:21:32 INFO - PROCESS | 3661 | |6;imaging-darkroom;146;145;145;145;146;146;146;145;145;146 22:21:32 INFO - PROCESS | 3661 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;94 22:21:32 INFO - PROCESS | 3661 | |8;json-parse-financial;50;50;51;50;50;52;52;50;50;50 22:21:32 INFO - PROCESS | 3661 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;60;60;60 22:21:32 INFO - PROCESS | 3661 | |10;stanford-crypto-aes;77;86;78;76;82;82;77;76;76;77 22:21:32 INFO - PROCESS | 3661 | |11;stanford-crypto-ccm;133;124;121;120;118;129;128;127;196;131 22:21:32 INFO - PROCESS | 3661 | |12;stanford-crypto-pbkdf2;159;161;159;155;156;159;161;157;156;156 22:21:32 INFO - PROCESS | 3661 | |13;stanford-crypto-sha256-iterative;66;63;63;62;63;63;63;62;61;61 22:21:32 INFO - PROCESS | 3661 | __end_tp_report 22:21:32 INFO - PROCESS | 3661 | __start_cc_report 22:21:32 INFO - PROCESS | 3661 | _x_x_mozilla_cycle_collect,1666 22:21:32 INFO - PROCESS | 3661 | __end_cc_report 22:21:32 INFO - PROCESS | 3661 | __startTimestamp1470288092967__endTimestamp 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | ------- Summary: start ------- 22:21:32 INFO - PROCESS | 3661 | Number of tests: 14 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.50 stddev:3.53 (3.2%) stddev-sans-first:1.12 22:21:32 INFO - PROCESS | 3661 | Values: 100.0 112.0 112.0 110.0 112.0 110.0 109.0 111.0 110.0 110.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:132.00 stddev:22.50 (17.0%) stddev-sans-first:23.01 22:21:32 INFO - PROCESS | 3661 | Values: 151.0 117.0 121.0 176.0 118.0 118.0 143.0 116.0 163.0 117.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 22:21:32 INFO - PROCESS | 3661 | Values: 161.0 159.0 160.0 160.0 161.0 160.0 159.0 161.0 161.0 160.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:13.99 (18.7%) stddev-sans-first:14.64 22:21:32 INFO - PROCESS | 3661 | Values: 75.0 75.0 75.0 111.0 76.0 75.0 75.0 75.0 105.0 74.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.00 stddev:18.94 (22.8%) stddev-sans-first:19.53 22:21:32 INFO - PROCESS | 3661 | Values: 82.0 83.0 110.0 84.0 85.0 83.0 117.0 83.0 85.0 135.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 22:21:32 INFO - PROCESS | 3661 | Values: 117.0 117.0 116.0 117.0 117.0 118.0 119.0 118.0 116.0 117.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:21:32 INFO - PROCESS | 3661 | Values: 146.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 146.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:21:32 INFO - PROCESS | 3661 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:0.85 (1.7%) stddev-sans-first:0.88 22:21:32 INFO - PROCESS | 3661 | Values: 50.0 50.0 51.0 50.0 50.0 52.0 52.0 50.0 50.0 50.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.32 (0.5%) stddev-sans-first:0.00 22:21:32 INFO - PROCESS | 3661 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:77.50 stddev:3.43 (4.4%) stddev-sans-first:3.59 22:21:32 INFO - PROCESS | 3661 | Values: 77.0 86.0 78.0 76.0 82.0 82.0 77.0 76.0 76.0 77.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#11] stanford-crypto-ccm Cycles:10 Average:132.70 Median:128.50 stddev:22.77 (17.7%) stddev-sans-first:24.16 22:21:32 INFO - PROCESS | 3661 | Values: 133.0 124.0 121.0 120.0 118.0 129.0 128.0 127.0 196.0 131.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:159.00 stddev:2.18 (1.4%) stddev-sans-first:2.28 22:21:32 INFO - PROCESS | 3661 | Values: 159.0 161.0 159.0 155.0 156.0 159.0 161.0 157.0 156.0 156.0 22:21:32 INFO - PROCESS | 3661 | 22:21:32 INFO - PROCESS | 3661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.42 (2.3%) stddev-sans-first:0.87 22:21:32 INFO - PROCESS | 3661 | Values: 66.0 63.0 63.0 62.0 63.0 63.0 63.0 62.0 61.0 61.0 22:21:32 INFO - PROCESS | 3661 | -------- Summary: end -------- 22:21:32 INFO - PROCESS | 3661 | 22:21:33 INFO - TEST-INFO | 3661: exit 0 22:21:33 INFO - TEST-OK | kraken | took 60955ms 22:21:33 INFO - SUITE-END | took 1178s 22:21:34 INFO - Completed test suite (00:19:38) 22:21:34 INFO - javascript benchmark 22:21:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3023.0477399619117, 3026.697330266972, 3053.5572342010414, 3058.3118798270407, 3086.243870251789, 5236.031334203175, 5243.449437809032, 5246.5278124968845, 5321.095413779657, 5347.6791392516425, 5227.817026404076, 5319.813806516772, 5326.813561525348, 5356.491133342336, 5377.274068000828, 4794.6404019698575, 4831.782569784368, 4880.389951256076, 4884.633652476061, 5026.547610715035, 4945.67853089548, 4955.033768415161, 4989.900201995958, 5003.274525193845, 5007.674501157431, 4727.527247275262, 4814.711117332971, 4870.342503761982, 4888.9755551222015, 4970.552650261476, 4809.8456370471185, 4814.1334559779225, 4818.060478206751, 4828.758562071917, 4919.065377578248, 4834.202356611155, 4862.0, 4868.9756551217015, 4879.682820616648, 4897.7795999180125, 5255.290535777659, 5282.603804714662, 5301.3373328333955, 5313.203019547061, 5354.544863686552, 5695.060315048012, 5705.657660540381, 5705.686187259699, 5731.484166425042, 5743.4256574342235, 5741.712914354307, 5744.712764361765, 5772.787714579943, 5779.306483221998, 5782.045962416196, 5169.922451163236, 5241.711705856176, 5271.551918086929, 5277.973610131925, 5374.811881584125, 5863.912041319383, 5870.236869207061, 5879.882402351929, 5883.14694369314, 5885.411458854081, 5633.352164501053, 5635.0, 5639.689817060101, 5640.294963129609, 5643.1535269709875, 5537.390887002426, 5585.385607583163, 5641.12562552805, 5657.12314591239, 5757.992351338499, 5674.914876276859, 5675.432456754292, 5680.6591604503865, 5681.460261275172, 5683.175939488834, 5636.718164091779, 5671.971640141773, 5684.715764211773, 5687.857803554862, 5706.657600543981, 4236.533981262059, 4320.891977700583, 4322.005938634132, 4353.542877997828, 4367.366731823868, 3843.5387753469763, 3884.2037382336553, 3913.9804300978312, 3929.174873276644, 3931.9017024574614, 5468.48049435303, 5476.67139971603, 5498.752556134984, 5504.614676972654, 5517.862053448615, 5764.221830052973, 5769.94230057694, 5771.624844385102, 5772.076467765138, 5779.479846813807, 5259.316288882421, 5376.516113549799, 5400.567954563626, 5441.102218133963, 5461.672299662033, 4844.8304309349005, 4876.8780780480065, 4884.7069175849565, 4884.926726099111, 4931.161702510582, 5900.43945825146, 5916.585838991229, 5917.31950825652, 5920.141579471038, 5934.347221805684, 5534.280543529316, 5652.067408877653, 5694.231278777312, 5711.571632127524, 5754.6547207167705, 5401.810936617357, 5935.614185077956, 5941.257342832146, 5991.3409524953095, 6009.038553831366, 5579.079451890392, 5599.936012157677, 5611.607187496836, 5612.242347283064, 5633.605647604629, 5556.638818476948, 5618.831435056954, 5732.082866741302, 5735.97132014354, 5743.971280143573, 5531.9723401382735, 5591.636543624814, 5593.804216852391, 5630.071038278638, 5634.267545219095, 5556.722163891708, 5560.221568980265, 5561.249231353715, 5565.443455654402, 5569.55443564522, 5461.453854614507, 5461.590380721541, 5552.3892371839875, 5559.360673522597, 5586.776528938797, 5557.749901254372, 5590.413006634245, 5590.580706446952, 5646.830595082154, 5653.434656534314, 5444.346678398618, 5453.0, 5483.479069488392, 5485.917711234414, 5489.560835133259], "value": 5257.986153066549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.9665068421237, 623.9313675495695, 637.5123030881374, 639.9968000160004, 652.9510286728497, 44263.77868110659, 46057.76971115144, 46277.76861115698, 49105.50894491054, 49167.50832491683, 46062.53937460625, 46523.534764652344, 46523.767381163045, 46566.534334656644, 46589.0, 8999.325050621197, 9117.407368521024, 9125.954370228139, 9137.725868223964, 9139.223166030863, 8897.911020889773, 8975.820483590323, 8976.0, 8982.101789820998, 8986.955065224698, 9043.412178208428, 9075.591598378089, 9081.137291957286, 9085.409448385833, 9088.045755195708, 9039.412438191497, 9062.139096785795, 9068.138526839939, 9076.0, 9077.863832042525, 8907.465552066897, 8975.730728078168, 8981.865272020925, 8985.32610054242, 8985.955070224607, 44141.33787993182, 44286.0, 44436.11127777426, 44534.33198502025, 44594.777026114985, 3524.9823750881337, 3548.432250839853, 3588.982055089708, 3671.8714844980564, 4007.0583412897945, 33695.4945675815, 34280.65719342799, 34544.82727586371, 34574.6542534574, 34773.0, 4453.354263631788, 4472.239719247703, 4479.238529450001, 4483.4843992940905, 4577.862664120082, 269429.0, 269775.0, 271590.0, 271770.0, 274579.0, 59431.0, 59773.40226597765, 59843.0, 59866.40133598696, 59918.40081599215, 58124.0, 58316.708416457644, 58373.416265837644, 58375.832483350096, 58509.70745146247, 14817.77773333401, 14845.96078274532, 14846.0, 14891.106533608017, 14893.404263829325, 13303.600891973256, 13365.198088114745, 13385.263810490516, 13385.933070334586, 13387.531436399679, 15158.877757114013, 16994.87564054493, 17192.31230750756, 17259.913700431418, 17377.73933391, 6557.737690492327, 6603.438707709898, 6677.632730199885, 6709.161354830646, 6771.085903402977, 974.7758015656439, 1019.7762684778296, 1023.4831410137981, 1069.534752382716, 1125.6285425809463, 32951.50572741411, 32953.0, 32995.505067424005, 33207.3358532828, 33265.66734332674, 1072.6353039966293, 1120.5293776613842, 1126.1441304608556, 1142.4230763464398, 1225.7058306006495, 16417.343306267616, 17342.219600118024, 17551.91224043897, 17557.297708091788, 17576.824231757775, 538862.0, 540706.0, 541965.0, 543748.0, 545430.0, 59813.0, 59868.0, 59882.0, 59937.40062599405, 59981.0, 1261.1424231522653, 1363.754524185656, 1372.2726954714017, 1379.9379027943767, 1400.103933482572, 31858.6814131865, 34274.0, 34328.313433730684, 34530.82734586411, 34844.477332839524, 50738.238926416874, 51063.74468127636, 51256.48743512517, 51314.48685513097, 51353.7432312836, 373842.0, 374970.0, 376866.0, 377615.0, 379742.0, 32215.677843222205, 41327.0, 42371.0, 42593.14813703771, 44995.77502112468, 48334.758326208146, 48760.0, 48934.510654893, 49079.26381104358, 49212.0, 42221.788891055345, 42867.57132428636, 43302.78348608363, 43626.78186609047, 44818.103637927714, 42663.57336426596, 43350.34974475449, 43734.42170385683, 43813.34279985739, 43917.560824392625, 41415.58584414118, 41672.79163604163, 42004.0, 42064.78967605143, 42205.78897105495, 35515.82242088773, 37928.62071379251, 37956.810215948746, 38071.809640951615, 38669.806650967694, 28672.0, 28733.425331492836, 28809.56785648259, 29029.274268143465, 29216.123516294545], "value": 20428.10078333019, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.9981050094749, 388.896942310288, 393.56904189912046, 396.92656858481183, 419.7943007926115, 14489.420423183086, 17909.373171938983, 18394.264229430824, 19776.30782922598, 19779.703304450446, 15881.920590397061, 18808.0, 19209.231630734754, 19466.318678846244, 19691.803081969178, 5711.200431939521, 5876.2067120938555, 5885.558583106263, 5887.882242355172, 5890.469857712805, 5711.343195532525, 5749.396313387097, 5827.912581311284, 5866.589338746289, 5868.589198756089, 5626.578006649497, 5745.454181852718, 5751.166080918264, 5755.0504166812425, 5779.797707080253, 5684.090545512719, 5736.053551164053, 5743.3108027036815, 5750.194972703804, 5782.739776710058, 5047.747612619354, 5170.586353091744, 5177.844664660066, 5178.14560597501, 5181.0, 23126.42183945398, 23227.883860580758, 23439.88280058606, 24024.639630405556, 24251.757482425128, 2357.4695693469002, 2425.1148330859287, 2496.226169887341, 2523.470071285032, 2544.211294498711, 14427.0, 14609.123452592772, 15093.39626414953, 15360.846391536053, 15411.460598878986, 1855.4711907106546, 1880.5392678793646, 1914.8563857710728, 1921.3755529452844, 1948.0, 75649.62175189143, 76556.23443765547, 77039.61480192619, 78043.6097819513, 79232.0, 10321.129576408846, 10439.321444106108, 10589.099926506333, 10839.393257865375, 10981.780364392667, 14724.705505890037, 14747.336369863382, 14763.188024658746, 14770.0, 14772.113673179643, 7926.801829954297, 8214.548199848947, 8343.582820858932, 8424.073351931283, 8477.576121194039, 7911.762647120596, 7956.2043795619975, 7979.9601001994615, 8277.089520152778, 8331.66673333072, 5998.220231369894, 6345.238571371465, 6692.564983276072, 6760.966195169091, 6761.560498567577, 1810.3030333321503, 1873.4731194076783, 1909.944089456858, 1918.0697361779526, 1957.9902100489692, 922.847730124522, 1010.4745532323298, 1028.4292217819032, 1028.783955369381, 1033.2199189611795, 18214.0, 18874.716879246822, 19414.611707765765, 20745.170193192404, 20763.169473221205, 989.2877128467492, 1006.1900170362782, 1006.4062203300089, 1014.7017006480328, 1015.8679371681782, 10911.83632245517, 11740.0, 11795.469203885847, 11798.41007949599, 11805.645830625095, 492552.0, 495790.0, 499198.0, 499401.0, 503850.0, 92155.0, 95223.0, 96510.0, 96561.5171924136, 96630.0, 997.0677416615488, 1046.2937517175876, 1055.9630412935664, 1071.2233630617895, 1075.0700643942932, 17305.913470432566, 17965.19705344398, 18598.907005464887, 18607.720884186478, 18838.3406580769, 41177.97055073647, 43765.34351984659, 43965.0, 44860.32709509425, 45607.0, 50183.0, 57941.4205857936, 59070.70464647649, 59667.104993426, 61936.38063619306, 8458.238758511518, 8588.914110858812, 8640.78398040054, 8672.78318042054, 9069.591868365807, 10021.549030293509, 13039.608811735663, 13502.729945401234, 13611.727765444437, 13651.249181295125, 10586.576536938745, 11349.602763903224, 11829.585964491202, 12120.454579544117, 12220.266783992989, 10782.514786834454, 10835.187360947803, 11157.66527004191, 11440.199186056896, 11613.941930290295, 11406.54373825071, 11480.425978701198, 11503.194776365573, 11573.537058517903, 12501.687457813627, 8477.0, 8480.982282126184, 8505.617247223767, 8535.573221339033, 8611.181937715906, 10459.581616735246, 10810.405427701551, 10898.0, 11014.50434730423, 11725.941370293094], "value": 9398.837086192914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.0168877563774, 1459.0734883349073, 1529.8929074964753, 1628.6824069306483, 1789.919453624586, 8561.957190214049, 9007.369484136114, 9049.276057915367, 9344.299177561676, 9371.34400591959, 9079.18287354138, 9206.125418085272, 9221.861672074925, 9223.769405764859, 9229.923267264861, 2447.412620970972, 2495.0, 2495.8252922295446, 2496.1887386599337, 2497.126005897939, 2353.329301149173, 2479.417336925821, 2481.6773819403543, 2482.2801387597665, 2484.763947425001, 2390.247072172269, 2413.5655581995234, 2413.9034438622434, 2421.055788242587, 2421.1162925532144, 2389.7968672662846, 2391.0316321889663, 2392.28231530541, 2416.5046165536023, 2451.877406129704, 2367.893444794971, 2420.794232490223, 2422.212780846232, 2423.3820375804235, 2427.55560441537, 8957.328200384933, 9141.497217653026, 9305.534723263878, 9315.953420232923, 9318.580663870142, 5356.437574054746, 5586.273784408001, 5589.636673616202, 5605.0, 5605.691686957216, 3521.0845180253064, 3567.6253993330847, 3609.855605775766, 3633.4913112164318, 3643.0710168907026, 1169.2808922512593, 1180.8110702287593, 1193.665773583398, 1204.9277043377426, 2119.925802596917, 12413.317267550281, 12859.228446293253, 12866.614001579968, 12877.098603097787, 12913.0, 4612.5156858530045, 4685.1800934836265, 4718.174319494075, 4839.612830973514, 5082.847514574569, 9995.650152244636, 10138.03688649577, 10144.239182061376, 10144.44205568686, 10144.847827282596, 5525.419830917776, 5526.392096869341, 5526.778928842882, 5528.474794894479, 5529.198266251451, 4527.909441811145, 4653.2554791233915, 5043.142665746832, 5117.309163262987, 5163.044836705221, 3862.2661694278, 3922.980385098056, 3939.1333906540517, 4005.559388467266, 4038.394240863894, 1153.6077733570626, 1156.0462618339902, 1158.4265788434611, 1166.2302880099094, 1174.900133488646, 776.6155752902349, 786.8741001439856, 829.3613917283658, 940.3934462271797, 1012.7670635753818, 5864.912026319608, 5903.0, 6003.849903752441, 6052.6671033092725, 6110.572259941761, 942.5475771629656, 963.0513943765385, 988.6786794291884, 996.3424140067666, 1015.9542820573092, 3918.45141680165, 4028.879133625996, 4105.384192371168, 4118.19695159447, 4141.855035073758, 11258.493367798468, 11684.70788230284, 11701.414929253504, 11702.76594468106, 11704.414779261004, 11331.43342832855, 11504.0, 11656.125790565742, 11670.70823229426, 11672.88327116735, 8261.958690206511, 8548.102449242864, 8567.571621419029, 8676.69631562905, 8702.695405660772, 5922.1412895130825, 6092.268927728701, 6135.233095863017, 6141.692915354304, 6445.709943052668, 10403.843942341022, 10434.37393756377, 10438.060574548326, 10463.215258855464, 10495.10791582724, 9316.385440669934, 10266.230032747424, 10407.427591482541, 10839.945800270949, 10925.508352124332, 7774.689012439665, 8128.83742325162, 8176.0, 8180.7954801130445, 8186.222308880885, 8898.199162075185, 8913.643454261757, 8964.596593153194, 8965.50689712072, 8971.955140224258, 7950.284474397101, 7961.761147165595, 7967.960160199162, 8128.0, 8153.8369232616205, 7913.881291780743, 7961.363090952594, 8069.031716194094, 8121.066077400938, 8296.211859873303, 7225.313595208447, 7334.193238743841, 7334.339909408179, 7334.889976650461, 7336.523125996793, 5101.566366858859, 5374.543163831118, 5392.892142157213, 5409.918851217156, 5623.634463759842, 5340.2256672783005, 5391.59563032782, 5404.7297635118875, 5449.0, 5537.7784888604], "value": 4802.986994552954, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.5211886408116, 330.6511630230107, 337.5881424661913, 338.57001607957886, 340.6287147009759, 5111.821086261982, 5141.0, 5159.896802063961, 5178.741062946857, 5207.349081364834, 4837.516248375169, 4845.806167753294, 5034.144195486767, 5066.569341605968, 5092.618053645972, 4038.5759495253014, 4062.2078694654556, 4068.41008053832, 4068.776217308047, 4076.51081870176, 4042.6159514846045, 4057.431959525669, 4062.1469491406842, 4106.77412742299, 4121.4848143982, 3847.980760096196, 3872.2255548890193, 3994.0813612869056, 4010.739301945379, 4028.6172813582807, 4019.799010049515, 4035.475388199545, 4044.4539987101807, 4057.6348128668415, 4077.1030373318117, 3598.8200589970397, 3600.963990360089, 3603.1172362771026, 3605.296967091418, 3611.368010598135, 4799.688020278706, 4999.700017998932, 5005.124103281947, 5007.574356179729, 5032.043911656773, 4529.569690879362, 4547.749873756942, 4596.35651008859, 4607.193741095295, 4615.72305661658, 5269.5772141521575, 5288.233206185082, 5299.814506492255, 5353.9196912046355, 5372.489613486712, 3766.305162676779, 3828.602560065556, 3840.6735427488697, 3844.903877403059, 3846.769193848378, 5450.972745136289, 5460.726963651801, 5493.505584497414, 5502.5597952163735, 5517.227588137664, 3917.588653191374, 3923.509561304837, 3924.293627147084, 3928.64642182205, 3934.0, 3959.425883246913, 3967.900802479903, 3971.6028397160053, 4004.099077707551, 4004.899877503086, 4400.273954797422, 4524.389207457017, 4526.660500462479, 4526.773661316921, 4535.886602834956, 5293.682379057269, 5327.547158491571, 5333.546648534918, 5345.86635334112, 5364.758585863646, 1349.7165595225113, 1355.2884735513737, 1357.172125003747, 1358.8165597644388, 1360.4286199796109, 883.5273128876149, 925.8611208318671, 926.9212116969999, 946.9100435458621, 950.8240975419432, 5124.948750512522, 5141.357330333708, 5164.0, 5212.166053431509, 5225.372955245395, 5433.728313584305, 5436.21174929633, 5445.428230035868, 5481.479259470344, 5486.396496385394, 5037.244413338029, 5115.923261151086, 5121.8719532012, 5129.615278854101, 5162.070827251056, 2829.5189817731034, 2851.3869518053716, 2861.828290302547, 2867.813592116506, 2870.2393865625627, 5416.837494875082, 5639.6334238274385, 5647.463490968352, 5648.378678345379, 5663.518600918968, 5608.9439105609235, 5612.1581762735095, 5620.100783874561, 5622.469888550168, 5643.322801263875, 5725.599208055396, 5738.196652468658, 5738.397468265855, 5825.359210486843, 5857.740585774079, 5433.864153396039, 5468.967713682819, 5470.893705923168, 5524.309461317335, 5610.635308704921, 5471.398146203835, 5471.480209380103, 5476.2333273342565, 5482.945170548243, 5487.451254874481, 5612.326520817528, 5766.740496677576, 5786.971065144816, 5793.275840519935, 5817.2146760188525, 3875.593062728373, 3906.2187562487047, 3913.1782325710783, 3916.118873253495, 3922.960770392374, 5459.317585301837, 5472.91790623133, 5475.288212532505, 5505.284313039279, 5559.8888022240135, 5254.211868219798, 5496.560275178056, 5497.972510137423, 5505.174223866452, 5505.697186654773, 5286.233496143114, 5341.305630268041, 5357.151782634985, 5362.892742145214, 5374.7581358839725, 5210.687358758335, 5281.313429254173, 5347.5721942243745, 5357.651752636066, 5363.731813409392, 5270.472952704698, 5346.465353464622, 5351.625386222927, 5370.355557333145, 5390.946090539044, 5212.218167274939, 5244.295603928815, 5277.788888444419, 5297.602679799107, 5315.654482458628], "value": 4104.824863113439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.3962799574338, 771.6758961236284, 772.0812233442203, 773.2229109744706, 806.4838503357848, 15661.451849185281, 16316.673666526662, 16318.755218671715, 16354.427595034162, 16412.425565105223, 15203.163825989594, 15320.540383788504, 15331.0, 15351.769723454128, 15356.385744570203, 668.0346898731335, 809.7408829174666, 830.8379865926147, 836.7782537627534, 842.4061036968923, 834.1658341658342, 837.5937670229933, 838.4801423117675, 838.9706360277391, 839.1440730454932, 889.8976617688952, 890.5057692980394, 894.552723638181, 895.2971917045121, 895.7939673875013, 704.3625518905374, 722.3246264742456, 727.4544091931052, 832.7168762620678, 888.076400543434, 634.2769243062912, 634.7492740367529, 635.1235295292466, 635.6599219417635, 635.7870113511979, 15898.0, 15909.681806363924, 15919.602009949727, 15936.0, 15946.840531594768, 14877.47928822486, 14895.180765057918, 15038.02252853561, 15161.0, 15177.924110379488, 13734.587962361145, 13879.167249964934, 13888.166709997433, 13971.161730296113, 14649.633759156, 4551.613112885409, 4559.247724125516, 4603.171429142753, 4665.580097791215, 4710.552497512731, 86328.56835715781, 86781.13218867793, 86816.56591717064, 87032.56483717605, 89089.55455222748, 11970.58102966392, 11976.401179941142, 12061.698457538456, 12168.087393445527, 12474.81287780684, 22548.774512254997, 22586.7577283251, 22834.657480137812, 22842.771572284397, 22865.31404057881, 8904.73285801427, 9084.500352480545, 9392.577334019848, 9427.528623568795, 9571.952140239255, 4693.812247510062, 4874.0, 4903.166461701519, 4916.459189489154, 5081.669691470117, 3533.399322115195, 3588.5334906461994, 3612.710983121344, 3636.9271065035878, 3640.5631324241263, 604.7792555717132, 615.3876892491999, 619.3465893482329, 630.130420020369, 631.9494440444753, 625.1497962770628, 644.24301445801, 698.2040473859804, 727.0802434919831, 742.4913933955258, 9400.24798016157, 9665.613375464902, 9689.41863488193, 9784.608615655294, 9825.017498250118, 587.4771453406472, 632.6046221111806, 682.0621645237798, 690.268315585481, 725.4740313272835, 4694.901156043472, 4881.633877459205, 4954.702717836869, 4974.1797921461075, 5003.724795136302, 66303.33696663068, 66607.0, 66937.33062669408, 67425.66287168533, 101602.0, 95294.52352738331, 96155.0, 96298.51850740841, 96500.51749741206, 98866.0, 50330.0, 51182.232266516774, 52525.73737131366, 55783.0, 87789.0, 9383.812323753351, 9393.342466027585, 9710.902890971, 9729.412233195184, 9733.561989710339, 41443.58556414518, 42928.57071429371, 43742.0, 49992.0, 50553.24170137377, 46959.765201173774, 63725.044124339096, 64774.028389575134, 66777.66611166912, 78374.0, 13654.180749155083, 13831.239281839595, 13936.0, 13959.371828267955, 13961.720765584834, 15644.38797288065, 15693.529194124194, 15757.606059848595, 16050.839491604935, 16762.078085705405, 14386.928065360025, 14393.424263029361, 14526.709465810836, 14558.835293176733, 15616.92191539035, 13356.532521362094, 13572.321383930566, 13867.167969921837, 14619.780703289673, 15096.622584435478, 12453.0, 12955.562420896416, 13207.933960330136, 13894.930525347309, 14736.926315368355, 11365.602203922823, 11572.888756574577, 12121.696957575778, 12149.271043737404, 12167.87832121703, 11593.362365069665, 11873.643790686294, 12112.636620901387, 12267.570635028089, 12576.182548134342], "value": 7592.809880680042, "unit": "score"}], "name": "dromaeo_css", "value": 7298.429661985973, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 112.0, 110.0, 112.0, 110.0, 109.0, 111.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 121.0, 176.0, 118.0, 118.0, 143.0, 116.0, 163.0, 117.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 160.0, 161.0, 160.0, 159.0, 161.0, 161.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 111.0, 76.0, 75.0, 75.0, 75.0, 105.0, 74.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 110.0, 84.0, 85.0, 83.0, 117.0, 83.0, 85.0, 135.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 119.0, 118.0, 116.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 52.0, 52.0, 50.0, 50.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 86.0, 78.0, 76.0, 82.0, 82.0, 77.0, 76.0, 76.0, 77.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 124.0, 121.0, 120.0, 118.0, 129.0, 128.0, 127.0, 196.0, 131.0], "value": 132.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 159.0, 155.0, 156.0, 159.0, 161.0, 157.0, 156.0, 156.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0, 62.0, 61.0, 61.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1480.0000000000002, "alertThreshold": 2.0}]} 22:21:34 INFO - Return code: 0 22:21:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:34 INFO - # TBPL SUCCESS # 22:21:34 INFO - Running post-action listener: _resource_record_post_action 22:21:34 INFO - [mozharness: 2016-08-04 05:21:34.263080Z] Finished run-tests step (success) 22:21:34 INFO - Running post-run listener: _resource_record_post_run 22:21:34 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7815168; Write bytes: 422563840; Read time: 1360; Write time: 377792 22:21:34 INFO - TinderboxPrint: CPU usage
13.0% 22:21:34 INFO - TinderboxPrint: I/O read bytes / time
7,815,168 / 1,360 22:21:34 INFO - TinderboxPrint: I/O write bytes / time
422,563,840 / 377,792 22:21:34 INFO - TinderboxPrint: CPU idle
8,305.3 (87.0%) 22:21:34 INFO - TinderboxPrint: CPU user
1,193.1 (12.5%) 22:21:34 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 48103424; Read time: 104; Write time: 21544 22:21:34 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 5574656; Write bytes: 373972992; Read time: 1104; Write time: 356224 22:21:34 INFO - Running post-run listener: _upload_blobber_files 22:21:34 INFO - Blob upload gear active. 22:21:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:34 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 22:21:34 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:21:34 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:21:35 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:36 INFO - (blobuploader) - INFO - Done attempting. 22:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:40 INFO - (blobuploader) - INFO - Done attempting. 22:21:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:41 INFO - (blobuploader) - INFO - Done attempting. 22:21:41 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:41 INFO - Return code: 0 22:21:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d49b72c0d1624fb50a4451bf80252a74e24229deb3536155f30badca41cc4c9ef2f2bcfbeda4df7387a2a687742dba736e5578f18686ed7a2c3e0532afbe68c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e12061866ce0b5087a162c9450a42763df67c07b8a0f1b467ae7f414be3ee1b9a3b2671c162f8bd68060d925199fbd0832432ba4f45c4abdae1f98d6b8d02820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76624fe701aaa9fd0cbe6859eef211ce3a8ca9b3bda67668f357339318db1621a93879e2f8b9d71864b84d0906b07f46c44e0e172e33b669593553d6493bc5d9"} 22:21:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:41 INFO - Contents: 22:21:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d49b72c0d1624fb50a4451bf80252a74e24229deb3536155f30badca41cc4c9ef2f2bcfbeda4df7387a2a687742dba736e5578f18686ed7a2c3e0532afbe68c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e12061866ce0b5087a162c9450a42763df67c07b8a0f1b467ae7f414be3ee1b9a3b2671c162f8bd68060d925199fbd0832432ba4f45c4abdae1f98d6b8d02820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76624fe701aaa9fd0cbe6859eef211ce3a8ca9b3bda67668f357339318db1621a93879e2f8b9d71864b84d0906b07f46c44e0e172e33b669593553d6493bc5d9"} 22:21:41 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:41 INFO - Copying logs to upload dir... 22:21:41 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.340152 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-03 22:21:41.320159) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 22:21:41.323827) ========= 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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d49b72c0d1624fb50a4451bf80252a74e24229deb3536155f30badca41cc4c9ef2f2bcfbeda4df7387a2a687742dba736e5578f18686ed7a2c3e0532afbe68c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e12061866ce0b5087a162c9450a42763df67c07b8a0f1b467ae7f414be3ee1b9a3b2671c162f8bd68060d925199fbd0832432ba4f45c4abdae1f98d6b8d02820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76624fe701aaa9fd0cbe6859eef211ce3a8ca9b3bda67668f357339318db1621a93879e2f8b9d71864b84d0906b07f46c44e0e172e33b669593553d6493bc5d9"} build_url:https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/IC1nWEbjTOaZCTArm5QTwQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d49b72c0d1624fb50a4451bf80252a74e24229deb3536155f30badca41cc4c9ef2f2bcfbeda4df7387a2a687742dba736e5578f18686ed7a2c3e0532afbe68c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e12061866ce0b5087a162c9450a42763df67c07b8a0f1b467ae7f414be3ee1b9a3b2671c162f8bd68060d925199fbd0832432ba4f45c4abdae1f98d6b8d02820", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76624fe701aaa9fd0cbe6859eef211ce3a8ca9b3bda67668f357339318db1621a93879e2f8b9d71864b84d0906b07f46c44e0e172e33b669593553d6493bc5d9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 22:21:41.349169) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:21:41.349517) ========= 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=232498196343f89f466a4a9a000001c8-1470285781.498938-634745871 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004385 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 22:21:41.432730) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 22:21:41.433177) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 22:21:41.433500) ========= ========= Total master_lag: 0.16 =========